2026-01-28 04:07:29.263 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 04:07:29.358 [Test worker] INFO  io.ktor.server.Application - Application started in 0.231 seconds.
2026-01-28 04:07:29.446 [DefaultDispatcher-worker-2 @coroutine#2] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:33963
2026-01-28 04:07:29.661 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33963/resources/index.json
2026-01-28 04:07:29.662 [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:33963/resources/index.json.
2026-01-28 04:07:29.662 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33963/resources/index.json
2026-01-28 04:07:29.776 [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-28 04:07:29.815 [DefaultDispatcher-worker-2 @call-handler#12] INFO  io.ktor.server.Application - 200 OK: GET - /resources/index.json in 44ms
2026-01-28 04:07:29.819 [DefaultDispatcher-worker-2 @coroutine#8] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33963/resources/index.json
2026-01-28 04:07:29.820 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33963/resources/index.json
2026-01-28 04:07:29.821 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33963/resources/index.json
2026-01-28 04:07:29.839 [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:33963/resources/index.json.
2026-01-28 04:07:29.864 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33963/resources/index.json
2026-01-28 04:07:29.864 [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:33963/resources/index.json.
2026-01-28 04:07:29.864 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33963/resources/index.json
2026-01-28 04:07:29.866 [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-28 04:07:29.867 [eventLoopGroupProxy-4-1 @call-handler#22] INFO  io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 1ms
2026-01-28 04:07:29.868 [DefaultDispatcher-worker-2 @coroutine#20] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33963/resources/index.json
2026-01-28 04:07:29.868 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33963/resources/index.json
2026-01-28 04:07:29.868 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33963/resources/index.json
2026-01-28 04:07:31.879 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 04:07:31.879 [Test worker] INFO  io.ktor.server.Application - Application started in 0.0 seconds.
2026-01-28 04:07:31.880 [DefaultDispatcher-worker-1 @coroutine#26] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:45289
2026-01-28 04:07:31.928 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45289/resources/index.json
2026-01-28 04:07:31.928 [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:45289/resources/index.json.
2026-01-28 04:07:31.928 [Test worker @coroutine#30] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45289/resources/index.json
2026-01-28 04:07:31.933 [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-28 04:07:31.934 [DefaultDispatcher-worker-2 @call-handler#35] INFO  io.ktor.server.Application - 200 OK: GET - /resources/index.json in 1ms
2026-01-28 04:07:31.935 [Test worker @coroutine#30] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45289/resources/index.json
2026-01-28 04:07:31.935 [Test worker @coroutine#30] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45289/resources/index.json
2026-01-28 04:07:31.935 [Test worker @coroutine#30] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45289/resources/index.json
2026-01-28 04:07:31.935 [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:45289/resources/index.json.
2026-01-28 04:07:31.939 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45289/resources/index.json
2026-01-28 04:07:31.940 [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:45289/resources/index.json.
2026-01-28 04:07:31.940 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45289/resources/index.json
2026-01-28 04:07:31.941 [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-28 04:07:31.941 [eventLoopGroupProxy-7-1 @call-handler#45] INFO  io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 0ms
2026-01-28 04:07:31.942 [DefaultDispatcher-worker-2 @coroutine#43] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45289/resources/index.json
2026-01-28 04:07:31.942 [DefaultDispatcher-worker-2 @coroutine#43] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45289/resources/index.json
2026-01-28 04:07:31.942 [DefaultDispatcher-worker-2 @coroutine#43] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45289/resources/index.json
2026-01-28 04:07:33.949 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 04:07:33.950 [Test worker] INFO  io.ktor.server.Application - Application started in 0.001 seconds.
2026-01-28 04:07:33.951 [DefaultDispatcher-worker-2 @coroutine#49] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:41157
2026-01-28 04:07:33.955 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41157/resources/lesson001.json
2026-01-28 04:07:33.955 [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:41157/resources/lesson001.json.
2026-01-28 04:07:33.955 [Test worker @coroutine#52] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41157/resources/lesson001.json
2026-01-28 04:07:33.958 [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-28 04:07:33.959 [DefaultDispatcher-worker-3 @call-handler#56] INFO  io.ktor.server.Application - 200 OK: GET - /resources/lesson001.json in 1ms
2026-01-28 04:07:33.960 [DefaultDispatcher-worker-2 @coroutine#52] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41157/resources/lesson001.json
2026-01-28 04:07:33.960 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41157/resources/lesson001.json
2026-01-28 04:07:33.960 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41157/resources/lesson001.json
2026-01-28 04:07:33.981 [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:41157/resources/lesson001.json.
2026-01-28 04:07:36.508 [Test worker @coroutine#61] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 04:07:36.516 [Test worker @coroutine#61] INFO  io.ktor.server.Application - Application started in 0.449 seconds.
2026-01-28 04:07:36.520 [DefaultDispatcher-worker-3 @coroutine#70] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:44989
2026-01-28 04:07:36.530 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44989/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:36.530 [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:44989/api/school/respect/schoolpermissiongrant.
2026-01-28 04:07:36.530 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44989/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:36.537 [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-28 04:07:36.554 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44989/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:36.554 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44989/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:36.555 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44989/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:36.557 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:44989/api/school/respect/schoolpermissiongrant.
2026-01-28 04:07:36.640 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44989/api/school/respect/person?includeRelated=false
2026-01-28 04:07:36.640 [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:44989/api/school/respect/person?includeRelated=false.
2026-01-28 04:07:36.640 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44989/api/school/respect/person?includeRelated=false
2026-01-28 04:07:36.643 [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-28 04:07:36.664 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44989/api/school/respect/person?includeRelated=false
2026-01-28 04:07:36.664 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44989/api/school/respect/person?includeRelated=false
2026-01-28 04:07:36.665 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44989/api/school/respect/person?includeRelated=false
2026-01-28 04:07:36.666 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:44989/api/school/respect/person?includeRelated=false.
2026-01-28 04:07:36.672 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44989/api/school/respect/person?includeRelated=false
2026-01-28 04:07:36.673 [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:44989/api/school/respect/person?includeRelated=false.
2026-01-28 04:07:36.673 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44989/api/school/respect/person?includeRelated=false
2026-01-28 04:07:36.675 [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-28 04:07:36.677 [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-28 04:07:36.678 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44989/api/school/respect/person?includeRelated=false
2026-01-28 04:07:36.678 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44989/api/school/respect/person?includeRelated=false
2026-01-28 04:07:36.678 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44989/api/school/respect/person?includeRelated=false
2026-01-28 04:07:38.763 [Test worker @coroutine#90] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 04:07:38.764 [Test worker @coroutine#90] INFO  io.ktor.server.Application - Application started in 0.03 seconds.
2026-01-28 04:07:38.765 [DefaultDispatcher-worker-4 @coroutine#99] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:43577
2026-01-28 04:07:38.766 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43577/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:38.766 [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:43577/api/school/respect/schoolpermissiongrant.
2026-01-28 04:07:38.766 [Test worker @coroutine#90] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:43577/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:38.769 [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-28 04:07:38.771 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43577/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:38.771 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43577/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:38.771 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43577/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:38.771 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:43577/api/school/respect/schoolpermissiongrant.
2026-01-28 04:07:38.808 [DefaultDispatcher-worker-4 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43577/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 04:07:38.809 [DefaultDispatcher-worker-4 @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:43577/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100.
2026-01-28 04:07:38.809 [DefaultDispatcher-worker-4 @coroutine#109] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:43577/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 04:07:38.810 [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-28 04:07:38.813 [DefaultDispatcher-worker-1 @coroutine#109] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43577/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 04:07:38.813 [DefaultDispatcher-worker-1 @coroutine#109] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43577/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 04:07:38.813 [DefaultDispatcher-worker-1 @coroutine#109] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43577/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 04:07:38.814 [DefaultDispatcher-worker-1 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:43577/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100.
2026-01-28 04:07:40.905 [Test worker @coroutine#118] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 04:07:40.905 [Test worker @coroutine#118] INFO  io.ktor.server.Application - Application started in 0.028 seconds.
2026-01-28 04:07:40.906 [DefaultDispatcher-worker-1 @coroutine#127] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:38651
2026-01-28 04:07:40.909 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38651/api/school/respect/person?includeRelated=false
2026-01-28 04:07:40.909 [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:38651/api/school/respect/person?includeRelated=false.
2026-01-28 04:07:40.909 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38651/api/school/respect/person?includeRelated=false
2026-01-28 04:07:40.911 [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-28 04:07:40.915 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38651/api/school/respect/person?includeRelated=false
2026-01-28 04:07:40.915 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38651/api/school/respect/person?includeRelated=false
2026-01-28 04:07:40.915 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38651/api/school/respect/person?includeRelated=false
2026-01-28 04:07:40.916 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38651/api/school/respect/person?includeRelated=false.
2026-01-28 04:07:40.957 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38651/api/school/respect/person?since=2026-01-28T03%3A07%3A40.912425837Z&includeRelated=false
2026-01-28 04:07:40.957 [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:38651/api/school/respect/person?since=2026-01-28T03%3A07%3A40.912425837Z&includeRelated=false.
2026-01-28 04:07:40.957 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38651/api/school/respect/person?since=2026-01-28T03%3A07%3A40.912425837Z&includeRelated=false
2026-01-28 04:07:40.959 [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-28 04:07:40.961 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38651/api/school/respect/person?since=2026-01-28T03%3A07%3A40.912425837Z&includeRelated=false
2026-01-28 04:07:40.962 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38651/api/school/respect/person?since=2026-01-28T03%3A07%3A40.912425837Z&includeRelated=false
2026-01-28 04:07:40.962 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38651/api/school/respect/person?since=2026-01-28T03%3A07%3A40.912425837Z&includeRelated=false
2026-01-28 04:07:40.962 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38651/api/school/respect/person?since=2026-01-28T03%3A07%3A40.912425837Z&includeRelated=false.
2026-01-28 04:07:43.061 [Test worker @coroutine#144] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 04:07:43.061 [Test worker @coroutine#144] INFO  io.ktor.server.Application - Application started in 0.031 seconds.
2026-01-28 04:07:43.062 [DefaultDispatcher-worker-1 @coroutine#153] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:33125
2026-01-28 04:07:43.101 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33125/api/school/respect/person
2026-01-28 04:07:43.101 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@47c1c637 for http://localhost:33125/api/school/respect/person
2026-01-28 04:07:43.101 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33125/api/school/respect/person
2026-01-28 04:07:43.102 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:33125/api/school/respect/person from class io.ktor.http.content.TextContent
2026-01-28 04:07:43.105 [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-28 04:07:43.110 [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<world.respect.datalayer.school.model.Person>) for call /api/school/respect/person
2026-01-28 04:07:43.112 [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-28 04:07:43.113 [DefaultDispatcher-worker-4 @coroutine#151] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33125/api/school/respect/person
2026-01-28 04:07:43.113 [DefaultDispatcher-worker-4 @coroutine#151] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33125/api/school/respect/person
2026-01-28 04:07:43.113 [DefaultDispatcher-worker-4 @coroutine#151] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33125/api/school/respect/person
2026-01-28 04:07:45.249 [Test worker @coroutine#168] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 04:07:45.250 [Test worker @coroutine#168] INFO  io.ktor.server.Application - Application started in 0.051 seconds.
2026-01-28 04:07:45.251 [DefaultDispatcher-worker-8 @coroutine#177] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:38885
2026-01-28 04:07:45.252 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38885/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:45.252 [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:38885/api/school/respect/schoolpermissiongrant.
2026-01-28 04:07:45.252 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38885/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:45.254 [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-28 04:07:45.256 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38885/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:45.256 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38885/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:45.256 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38885/api/school/respect/schoolpermissiongrant
2026-01-28 04:07:45.256 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38885/api/school/respect/schoolpermissiongrant.
2026-01-28 04:07:45.305 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38885/api/school/respect/person?includeRelated=false
2026-01-28 04:07:45.306 [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:38885/api/school/respect/person?includeRelated=false.
2026-01-28 04:07:45.306 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38885/api/school/respect/person?includeRelated=false
2026-01-28 04:07:45.307 [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-28 04:07:45.312 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38885/api/school/respect/person?includeRelated=false
2026-01-28 04:07:45.312 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38885/api/school/respect/person?includeRelated=false
2026-01-28 04:07:45.312 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38885/api/school/respect/person?includeRelated=false
2026-01-28 04:07:45.313 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38885/api/school/respect/person?includeRelated=false.
2026-01-28 04:07:47.318 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38885/api/school/respect/person?includeRelated=false
2026-01-28 04:07:47.319 [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:38885/api/school/respect/person?includeRelated=false.
2026-01-28 04:07:47.319 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38885/api/school/respect/person?includeRelated=false
2026-01-28 04:07:47.320 [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-28 04:07:47.323 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38885/api/school/respect/person?includeRelated=false
2026-01-28 04:07:47.323 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38885/api/school/respect/person?includeRelated=false
2026-01-28 04:07:47.323 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38885/api/school/respect/person?includeRelated=false
2026-01-28 04:07:47.324 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38885/api/school/respect/person?includeRelated=false.
2026-01-28 04:07:49.408 [Test worker @coroutine#200] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 04:07:49.409 [Test worker @coroutine#200] INFO  io.ktor.server.Application - Application started in 0.029 seconds.
2026-01-28 04:07:49.410 [DefaultDispatcher-worker-11 @coroutine#209] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:46525
2026-01-28 04:07:49.412 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46525/api/school/respect/person?includeRelated=false
2026-01-28 04:07:49.412 [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:46525/api/school/respect/person?includeRelated=false.
2026-01-28 04:07:49.412 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46525/api/school/respect/person?includeRelated=false
2026-01-28 04:07:49.414 [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-28 04:07:49.417 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46525/api/school/respect/person?includeRelated=false
2026-01-28 04:07:49.417 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46525/api/school/respect/person?includeRelated=false
2026-01-28 04:07:49.417 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46525/api/school/respect/person?includeRelated=false
2026-01-28 04:07:49.418 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:46525/api/school/respect/person?includeRelated=false.
2026-01-28 04:07:49.449 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46525/api/school/respect/person?since=2026-01-28T03%3A07%3A49.415006879Z&includeRelated=false
2026-01-28 04:07:49.449 [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:46525/api/school/respect/person?since=2026-01-28T03%3A07%3A49.415006879Z&includeRelated=false.
2026-01-28 04:07:49.449 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46525/api/school/respect/person?since=2026-01-28T03%3A07%3A49.415006879Z&includeRelated=false
2026-01-28 04:07:49.451 [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-28 04:07:49.453 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46525/api/school/respect/person?since=2026-01-28T03%3A07%3A49.415006879Z&includeRelated=false
2026-01-28 04:07:49.453 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46525/api/school/respect/person?since=2026-01-28T03%3A07%3A49.415006879Z&includeRelated=false
2026-01-28 04:07:49.453 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46525/api/school/respect/person?since=2026-01-28T03%3A07%3A49.415006879Z&includeRelated=false
2026-01-28 04:07:49.454 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:46525/api/school/respect/person?since=2026-01-28T03%3A07%3A49.415006879Z&includeRelated=false.
2026-01-28 09:39:01.816 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:39:01.971 [Test worker] INFO  io.ktor.server.Application - Application started in 0.356 seconds.
2026-01-28 09:39:02.075 [DefaultDispatcher-worker-2 @coroutine#2] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:34343
2026-01-28 09:39:02.403 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34343/resources/index.json
2026-01-28 09:39:02.404 [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:34343/resources/index.json.
2026-01-28 09:39:02.405 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34343/resources/index.json
2026-01-28 09:39:02.648 [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-28 09:39:02.703 [DefaultDispatcher-worker-2 @call-handler#12] INFO  io.ktor.server.Application - 200 OK: GET - /resources/index.json in 63ms
2026-01-28 09:39:02.706 [DefaultDispatcher-worker-1 @coroutine#8] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34343/resources/index.json
2026-01-28 09:39:02.708 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34343/resources/index.json
2026-01-28 09:39:02.708 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34343/resources/index.json
2026-01-28 09:39:02.733 [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:34343/resources/index.json.
2026-01-28 09:39:02.778 [DefaultDispatcher-worker-3 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34343/resources/index.json
2026-01-28 09:39:02.779 [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:34343/resources/index.json.
2026-01-28 09:39:02.779 [DefaultDispatcher-worker-3 @coroutine#20] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34343/resources/index.json
2026-01-28 09:39:02.782 [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-28 09:39:02.785 [eventLoopGroupProxy-4-1 @call-handler#22] INFO  io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 3ms
2026-01-28 09:39:02.786 [DefaultDispatcher-worker-1 @coroutine#20] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34343/resources/index.json
2026-01-28 09:39:02.786 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34343/resources/index.json
2026-01-28 09:39:02.786 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34343/resources/index.json
2026-01-28 09:39:04.826 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:39:04.826 [Test worker] INFO  io.ktor.server.Application - Application started in 0.0 seconds.
2026-01-28 09:39:04.829 [DefaultDispatcher-worker-1 @coroutine#26] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:34765
2026-01-28 09:39:04.889 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34765/resources/index.json
2026-01-28 09:39:04.889 [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:34765/resources/index.json.
2026-01-28 09:39:04.890 [Test worker @coroutine#30] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34765/resources/index.json
2026-01-28 09:39:04.895 [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-28 09:39:04.897 [DefaultDispatcher-worker-4 @call-handler#35] INFO  io.ktor.server.Application - 200 OK: GET - /resources/index.json in 3ms
2026-01-28 09:39:04.898 [Test worker @coroutine#30] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34765/resources/index.json
2026-01-28 09:39:04.898 [Test worker @coroutine#30] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34765/resources/index.json
2026-01-28 09:39:04.898 [Test worker @coroutine#30] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34765/resources/index.json
2026-01-28 09:39:04.899 [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:34765/resources/index.json.
2026-01-28 09:39:04.911 [DefaultDispatcher-worker-5 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34765/resources/index.json
2026-01-28 09:39:04.912 [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:34765/resources/index.json.
2026-01-28 09:39:04.912 [DefaultDispatcher-worker-5 @coroutine#43] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34765/resources/index.json
2026-01-28 09:39:04.917 [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-28 09:39:04.918 [eventLoopGroupProxy-7-1 @call-handler#45] INFO  io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 2ms
2026-01-28 09:39:04.919 [DefaultDispatcher-worker-5 @coroutine#43] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34765/resources/index.json
2026-01-28 09:39:04.919 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34765/resources/index.json
2026-01-28 09:39:04.919 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34765/resources/index.json
2026-01-28 09:39:06.935 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:39:06.936 [Test worker] INFO  io.ktor.server.Application - Application started in 0.001 seconds.
2026-01-28 09:39:06.938 [DefaultDispatcher-worker-3 @coroutine#49] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:46127
2026-01-28 09:39:06.942 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46127/resources/lesson001.json
2026-01-28 09:39:06.942 [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:46127/resources/lesson001.json.
2026-01-28 09:39:06.942 [Test worker @coroutine#52] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46127/resources/lesson001.json
2026-01-28 09:39:06.945 [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-28 09:39:06.946 [eventLoopGroupProxy-10-1 @call-handler#56] INFO  io.ktor.server.Application - 200 OK: GET - /resources/lesson001.json in 1ms
2026-01-28 09:39:06.947 [DefaultDispatcher-worker-2 @coroutine#52] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46127/resources/lesson001.json
2026-01-28 09:39:06.947 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46127/resources/lesson001.json
2026-01-28 09:39:06.947 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46127/resources/lesson001.json
2026-01-28 09:39:06.973 [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:46127/resources/lesson001.json.
2026-01-28 09:39:10.143 [Test worker @coroutine#61] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:39:10.186 [Test worker @coroutine#61] INFO  io.ktor.server.Application - Application started in 1.073 seconds.
2026-01-28 09:39:10.226 [DefaultDispatcher-worker-3 @coroutine#70] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:40225
2026-01-28 09:39:10.268 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40225/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:10.269 [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:40225/api/school/respect/schoolpermissiongrant.
2026-01-28 09:39:10.269 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40225/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:10.348 [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-28 09:39:10.450 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40225/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:10.455 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40225/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:10.456 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40225/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:10.460 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:40225/api/school/respect/schoolpermissiongrant.
2026-01-28 09:39:10.775 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40225/api/school/respect/person?includeRelated=false
2026-01-28 09:39:10.776 [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:40225/api/school/respect/person?includeRelated=false.
2026-01-28 09:39:10.783 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40225/api/school/respect/person?includeRelated=false
2026-01-28 09:39:10.811 [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-28 09:39:10.900 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40225/api/school/respect/person?includeRelated=false
2026-01-28 09:39:10.904 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40225/api/school/respect/person?includeRelated=false
2026-01-28 09:39:10.905 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40225/api/school/respect/person?includeRelated=false
2026-01-28 09:39:10.907 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:40225/api/school/respect/person?includeRelated=false.
2026-01-28 09:39:10.938 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40225/api/school/respect/person?includeRelated=false
2026-01-28 09:39:10.938 [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:40225/api/school/respect/person?includeRelated=false.
2026-01-28 09:39:10.938 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40225/api/school/respect/person?includeRelated=false
2026-01-28 09:39:10.943 [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-28 09:39:10.948 [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-28 09:39:10.953 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40225/api/school/respect/person?includeRelated=false
2026-01-28 09:39:10.953 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40225/api/school/respect/person?includeRelated=false
2026-01-28 09:39:10.953 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40225/api/school/respect/person?includeRelated=false
2026-01-28 09:39:13.122 [Test worker @coroutine#90] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:39:13.125 [Test worker @coroutine#90] INFO  io.ktor.server.Application - Application started in 0.05 seconds.
2026-01-28 09:39:13.130 [DefaultDispatcher-worker-5 @coroutine#99] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:41417
2026-01-28 09:39:13.134 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41417/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:13.140 [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:41417/api/school/respect/schoolpermissiongrant.
2026-01-28 09:39:13.140 [Test worker @coroutine#90] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41417/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:13.152 [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-28 09:39:13.164 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41417/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:13.164 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41417/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:13.165 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41417/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:13.166 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41417/api/school/respect/schoolpermissiongrant.
2026-01-28 09:39:13.212 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41417/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 09:39:13.212 [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:41417/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100.
2026-01-28 09:39:13.212 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41417/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 09:39:13.214 [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-28 09:39:13.217 [DefaultDispatcher-worker-5 @coroutine#109] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41417/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 09:39:13.217 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41417/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 09:39:13.217 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41417/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 09:39:13.218 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41417/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100.
2026-01-28 09:39:15.356 [Test worker @coroutine#118] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:39:15.364 [Test worker @coroutine#118] INFO  io.ktor.server.Application - Application started in 0.055 seconds.
2026-01-28 09:39:15.366 [DefaultDispatcher-worker-5 @coroutine#127] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:39823
2026-01-28 09:39:15.371 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39823/api/school/respect/person?includeRelated=false
2026-01-28 09:39:15.371 [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:39823/api/school/respect/person?includeRelated=false.
2026-01-28 09:39:15.372 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39823/api/school/respect/person?includeRelated=false
2026-01-28 09:39:15.380 [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-28 09:39:15.388 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39823/api/school/respect/person?includeRelated=false
2026-01-28 09:39:15.389 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39823/api/school/respect/person?includeRelated=false
2026-01-28 09:39:15.389 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39823/api/school/respect/person?includeRelated=false
2026-01-28 09:39:15.391 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39823/api/school/respect/person?includeRelated=false.
2026-01-28 09:39:15.488 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39823/api/school/respect/person?since=2026-01-28T08%3A39%3A15.381049630Z&includeRelated=false
2026-01-28 09:39:15.490 [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:39823/api/school/respect/person?since=2026-01-28T08%3A39%3A15.381049630Z&includeRelated=false.
2026-01-28 09:39:15.490 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39823/api/school/respect/person?since=2026-01-28T08%3A39%3A15.381049630Z&includeRelated=false
2026-01-28 09:39:15.495 [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-28 09:39:15.509 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39823/api/school/respect/person?since=2026-01-28T08%3A39%3A15.381049630Z&includeRelated=false
2026-01-28 09:39:15.514 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39823/api/school/respect/person?since=2026-01-28T08%3A39%3A15.381049630Z&includeRelated=false
2026-01-28 09:39:15.517 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39823/api/school/respect/person?since=2026-01-28T08%3A39%3A15.381049630Z&includeRelated=false
2026-01-28 09:39:15.519 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39823/api/school/respect/person?since=2026-01-28T08%3A39%3A15.381049630Z&includeRelated=false.
2026-01-28 09:39:17.727 [Test worker @coroutine#144] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:39:17.731 [Test worker @coroutine#144] INFO  io.ktor.server.Application - Application started in 0.048 seconds.
2026-01-28 09:39:17.740 [DefaultDispatcher-worker-7 @coroutine#153] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:45015
2026-01-28 09:39:17.792 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45015/api/school/respect/person
2026-01-28 09:39:17.792 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@40ba8632 for http://localhost:45015/api/school/respect/person
2026-01-28 09:39:17.792 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45015/api/school/respect/person
2026-01-28 09:39:17.792 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:45015/api/school/respect/person from class io.ktor.http.content.TextContent
2026-01-28 09:39:17.799 [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-28 09:39:17.808 [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<world.respect.datalayer.school.model.Person>) for call /api/school/respect/person
2026-01-28 09:39:17.810 [DefaultDispatcher-worker-5 @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-28 09:39:17.813 [DefaultDispatcher-worker-2 @coroutine#151] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45015/api/school/respect/person
2026-01-28 09:39:17.814 [DefaultDispatcher-worker-2 @coroutine#151] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45015/api/school/respect/person
2026-01-28 09:39:17.814 [DefaultDispatcher-worker-2 @coroutine#151] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45015/api/school/respect/person
2026-01-28 09:39:19.934 [Test worker @coroutine#168] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:39:19.936 [Test worker @coroutine#168] INFO  io.ktor.server.Application - Application started in 0.04 seconds.
2026-01-28 09:39:19.949 [DefaultDispatcher-worker-6 @coroutine#177] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:41249
2026-01-28 09:39:19.950 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41249/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:19.950 [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:41249/api/school/respect/schoolpermissiongrant.
2026-01-28 09:39:19.950 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41249/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:19.966 [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-28 09:39:19.971 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41249/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:19.971 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41249/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:19.971 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41249/api/school/respect/schoolpermissiongrant
2026-01-28 09:39:19.972 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41249/api/school/respect/schoolpermissiongrant.
2026-01-28 09:39:20.009 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41249/api/school/respect/person?includeRelated=false
2026-01-28 09:39:20.009 [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:41249/api/school/respect/person?includeRelated=false.
2026-01-28 09:39:20.009 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41249/api/school/respect/person?includeRelated=false
2026-01-28 09:39:20.011 [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-28 09:39:20.021 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41249/api/school/respect/person?includeRelated=false
2026-01-28 09:39:20.021 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41249/api/school/respect/person?includeRelated=false
2026-01-28 09:39:20.021 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41249/api/school/respect/person?includeRelated=false
2026-01-28 09:39:20.023 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41249/api/school/respect/person?includeRelated=false.
2026-01-28 09:39:22.053 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41249/api/school/respect/person?includeRelated=false
2026-01-28 09:39:22.053 [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:41249/api/school/respect/person?includeRelated=false.
2026-01-28 09:39:22.053 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41249/api/school/respect/person?includeRelated=false
2026-01-28 09:39:22.056 [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-28 09:39:22.060 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41249/api/school/respect/person?includeRelated=false
2026-01-28 09:39:22.061 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41249/api/school/respect/person?includeRelated=false
2026-01-28 09:39:22.061 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41249/api/school/respect/person?includeRelated=false
2026-01-28 09:39:22.062 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41249/api/school/respect/person?includeRelated=false.
2026-01-28 09:39:24.195 [Test worker @coroutine#200] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:39:24.196 [Test worker @coroutine#200] INFO  io.ktor.server.Application - Application started in 0.061 seconds.
2026-01-28 09:39:24.197 [DefaultDispatcher-worker-4 @coroutine#209] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:33317
2026-01-28 09:39:24.199 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33317/api/school/respect/person?includeRelated=false
2026-01-28 09:39:24.199 [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:33317/api/school/respect/person?includeRelated=false.
2026-01-28 09:39:24.199 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33317/api/school/respect/person?includeRelated=false
2026-01-28 09:39:24.202 [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-28 09:39:24.205 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33317/api/school/respect/person?includeRelated=false
2026-01-28 09:39:24.205 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33317/api/school/respect/person?includeRelated=false
2026-01-28 09:39:24.205 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33317/api/school/respect/person?includeRelated=false
2026-01-28 09:39:24.206 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33317/api/school/respect/person?includeRelated=false.
2026-01-28 09:39:24.259 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33317/api/school/respect/person?since=2026-01-28T08%3A39%3A24.202575055Z&includeRelated=false
2026-01-28 09:39:24.260 [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:33317/api/school/respect/person?since=2026-01-28T08%3A39%3A24.202575055Z&includeRelated=false.
2026-01-28 09:39:24.260 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33317/api/school/respect/person?since=2026-01-28T08%3A39%3A24.202575055Z&includeRelated=false
2026-01-28 09:39:24.270 [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-28 09:39:24.280 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33317/api/school/respect/person?since=2026-01-28T08%3A39%3A24.202575055Z&includeRelated=false
2026-01-28 09:39:24.281 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33317/api/school/respect/person?since=2026-01-28T08%3A39%3A24.202575055Z&includeRelated=false
2026-01-28 09:39:24.281 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33317/api/school/respect/person?since=2026-01-28T08%3A39%3A24.202575055Z&includeRelated=false
2026-01-28 09:39:24.282 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33317/api/school/respect/person?since=2026-01-28T08%3A39%3A24.202575055Z&includeRelated=false.
2026-01-28 09:45:05.557 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:45:05.702 [Test worker] INFO  io.ktor.server.Application - Application started in 0.337 seconds.
2026-01-28 09:45:05.815 [DefaultDispatcher-worker-1 @coroutine#2] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:40591
2026-01-28 09:45:06.167 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40591/resources/index.json
2026-01-28 09:45:06.169 [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:40591/resources/index.json.
2026-01-28 09:45:06.169 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40591/resources/index.json
2026-01-28 09:45:06.358 [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-28 09:45:06.417 [eventLoopGroupProxy-4-1 @call-handler#12] INFO  io.ktor.server.Application - 200 OK: GET - /resources/index.json in 66ms
2026-01-28 09:45:06.425 [DefaultDispatcher-worker-1 @coroutine#8] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40591/resources/index.json
2026-01-28 09:45:06.427 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40591/resources/index.json
2026-01-28 09:45:06.428 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40591/resources/index.json
2026-01-28 09:45:06.454 [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:40591/resources/index.json.
2026-01-28 09:45:06.491 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40591/resources/index.json
2026-01-28 09:45:06.491 [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:40591/resources/index.json.
2026-01-28 09:45:06.491 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40591/resources/index.json
2026-01-28 09:45:06.494 [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-28 09:45:06.496 [eventLoopGroupProxy-4-1 @call-handler#22] INFO  io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 2ms
2026-01-28 09:45:06.497 [DefaultDispatcher-worker-2 @coroutine#20] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40591/resources/index.json
2026-01-28 09:45:06.497 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40591/resources/index.json
2026-01-28 09:45:06.497 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40591/resources/index.json
2026-01-28 09:45:08.521 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:45:08.522 [Test worker] INFO  io.ktor.server.Application - Application started in 0.001 seconds.
2026-01-28 09:45:08.529 [DefaultDispatcher-worker-2 @coroutine#26] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:37559
2026-01-28 09:45:08.605 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:37559/resources/index.json
2026-01-28 09:45:08.606 [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:37559/resources/index.json.
2026-01-28 09:45:08.606 [Test worker @coroutine#30] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:37559/resources/index.json
2026-01-28 09:45:08.611 [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-28 09:45:08.615 [DefaultDispatcher-worker-1 @call-handler#35] INFO  io.ktor.server.Application - 200 OK: GET - /resources/index.json in 4ms
2026-01-28 09:45:08.615 [Test worker @coroutine#30] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:37559/resources/index.json
2026-01-28 09:45:08.616 [Test worker @coroutine#30] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:37559/resources/index.json
2026-01-28 09:45:08.616 [Test worker @coroutine#30] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:37559/resources/index.json
2026-01-28 09:45:08.617 [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:37559/resources/index.json.
2026-01-28 09:45:08.623 [DefaultDispatcher-worker-9 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:37559/resources/index.json
2026-01-28 09:45:08.623 [DefaultDispatcher-worker-9 @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:37559/resources/index.json.
2026-01-28 09:45:08.623 [DefaultDispatcher-worker-9 @coroutine#43] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:37559/resources/index.json
2026-01-28 09:45:08.625 [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-28 09:45:08.626 [eventLoopGroupProxy-7-1 @call-handler#45] INFO  io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 1ms
2026-01-28 09:45:08.627 [DefaultDispatcher-worker-9 @coroutine#43] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:37559/resources/index.json
2026-01-28 09:45:08.627 [DefaultDispatcher-worker-9 @coroutine#43] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:37559/resources/index.json
2026-01-28 09:45:08.627 [DefaultDispatcher-worker-9 @coroutine#43] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:37559/resources/index.json
2026-01-28 09:45:10.636 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:45:10.636 [Test worker] INFO  io.ktor.server.Application - Application started in 0.0 seconds.
2026-01-28 09:45:10.637 [DefaultDispatcher-worker-9 @coroutine#49] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:39473
2026-01-28 09:45:10.641 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39473/resources/lesson001.json
2026-01-28 09:45:10.641 [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:39473/resources/lesson001.json.
2026-01-28 09:45:10.641 [Test worker @coroutine#52] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39473/resources/lesson001.json
2026-01-28 09:45:10.644 [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-28 09:45:10.646 [DefaultDispatcher-worker-2 @call-handler#56] INFO  io.ktor.server.Application - 200 OK: GET - /resources/lesson001.json in 2ms
2026-01-28 09:45:10.646 [DefaultDispatcher-worker-2 @coroutine#52] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39473/resources/lesson001.json
2026-01-28 09:45:10.646 [DefaultDispatcher-worker-2 @coroutine#52] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39473/resources/lesson001.json
2026-01-28 09:45:10.646 [DefaultDispatcher-worker-2 @coroutine#52] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39473/resources/lesson001.json
2026-01-28 09:45:10.667 [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:39473/resources/lesson001.json.
2026-01-28 09:45:14.067 [Test worker @coroutine#61] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:45:14.112 [Test worker @coroutine#61] INFO  io.ktor.server.Application - Application started in 1.312 seconds.
2026-01-28 09:45:14.149 [DefaultDispatcher-worker-8 @coroutine#70] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:38095
2026-01-28 09:45:14.255 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38095/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:14.261 [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:38095/api/school/respect/schoolpermissiongrant.
2026-01-28 09:45:14.267 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38095/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:14.342 [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-28 09:45:14.425 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38095/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:14.430 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38095/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:14.430 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38095/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:14.448 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38095/api/school/respect/schoolpermissiongrant.
2026-01-28 09:45:14.661 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38095/api/school/respect/person?includeRelated=false
2026-01-28 09:45:14.662 [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:38095/api/school/respect/person?includeRelated=false.
2026-01-28 09:45:14.662 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38095/api/school/respect/person?includeRelated=false
2026-01-28 09:45:14.668 [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-28 09:45:14.746 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38095/api/school/respect/person?includeRelated=false
2026-01-28 09:45:14.747 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38095/api/school/respect/person?includeRelated=false
2026-01-28 09:45:14.747 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38095/api/school/respect/person?includeRelated=false
2026-01-28 09:45:14.749 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38095/api/school/respect/person?includeRelated=false.
2026-01-28 09:45:14.780 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38095/api/school/respect/person?includeRelated=false
2026-01-28 09:45:14.780 [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:38095/api/school/respect/person?includeRelated=false.
2026-01-28 09:45:14.780 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38095/api/school/respect/person?includeRelated=false
2026-01-28 09:45:14.783 [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-28 09:45:14.789 [DefaultDispatcher-worker-2 @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-28 09:45:14.799 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38095/api/school/respect/person?includeRelated=false
2026-01-28 09:45:14.800 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38095/api/school/respect/person?includeRelated=false
2026-01-28 09:45:14.800 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38095/api/school/respect/person?includeRelated=false
2026-01-28 09:45:16.940 [Test worker @coroutine#90] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:45:16.942 [Test worker @coroutine#90] INFO  io.ktor.server.Application - Application started in 0.045 seconds.
2026-01-28 09:45:16.946 [DefaultDispatcher-worker-2 @coroutine#99] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:40545
2026-01-28 09:45:16.950 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40545/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:16.950 [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:40545/api/school/respect/schoolpermissiongrant.
2026-01-28 09:45:16.951 [Test worker @coroutine#90] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40545/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:16.963 [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-28 09:45:16.966 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40545/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:16.967 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40545/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:16.967 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40545/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:16.967 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:40545/api/school/respect/schoolpermissiongrant.
2026-01-28 09:45:17.016 [DefaultDispatcher-worker-2 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40545/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 09:45:17.017 [DefaultDispatcher-worker-2 @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:40545/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100.
2026-01-28 09:45:17.017 [DefaultDispatcher-worker-2 @coroutine#109] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40545/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 09:45:17.018 [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-28 09:45:17.022 [DefaultDispatcher-worker-5 @coroutine#109] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40545/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 09:45:17.022 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40545/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 09:45:17.022 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40545/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 09:45:17.023 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:40545/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100.
2026-01-28 09:45:19.168 [Test worker @coroutine#118] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:45:19.169 [Test worker @coroutine#118] INFO  io.ktor.server.Application - Application started in 0.057 seconds.
2026-01-28 09:45:19.170 [DefaultDispatcher-worker-8 @coroutine#127] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:40443
2026-01-28 09:45:19.174 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40443/api/school/respect/person?includeRelated=false
2026-01-28 09:45:19.174 [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:40443/api/school/respect/person?includeRelated=false.
2026-01-28 09:45:19.174 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40443/api/school/respect/person?includeRelated=false
2026-01-28 09:45:19.177 [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-28 09:45:19.182 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40443/api/school/respect/person?includeRelated=false
2026-01-28 09:45:19.182 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40443/api/school/respect/person?includeRelated=false
2026-01-28 09:45:19.182 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40443/api/school/respect/person?includeRelated=false
2026-01-28 09:45:19.183 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:40443/api/school/respect/person?includeRelated=false.
2026-01-28 09:45:19.230 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40443/api/school/respect/person?since=2026-01-28T08%3A45%3A19.178433730Z&includeRelated=false
2026-01-28 09:45:19.230 [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:40443/api/school/respect/person?since=2026-01-28T08%3A45%3A19.178433730Z&includeRelated=false.
2026-01-28 09:45:19.230 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40443/api/school/respect/person?since=2026-01-28T08%3A45%3A19.178433730Z&includeRelated=false
2026-01-28 09:45:19.232 [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-28 09:45:19.235 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40443/api/school/respect/person?since=2026-01-28T08%3A45%3A19.178433730Z&includeRelated=false
2026-01-28 09:45:19.236 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40443/api/school/respect/person?since=2026-01-28T08%3A45%3A19.178433730Z&includeRelated=false
2026-01-28 09:45:19.236 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40443/api/school/respect/person?since=2026-01-28T08%3A45%3A19.178433730Z&includeRelated=false
2026-01-28 09:45:19.237 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:40443/api/school/respect/person?since=2026-01-28T08%3A45%3A19.178433730Z&includeRelated=false.
2026-01-28 09:45:21.402 [Test worker @coroutine#144] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:45:21.403 [Test worker @coroutine#144] INFO  io.ktor.server.Application - Application started in 0.05 seconds.
2026-01-28 09:45:21.411 [DefaultDispatcher-worker-1 @coroutine#153] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:38819
2026-01-28 09:45:21.463 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38819/api/school/respect/person
2026-01-28 09:45:21.464 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@11a70c2e for http://localhost:38819/api/school/respect/person
2026-01-28 09:45:21.464 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38819/api/school/respect/person
2026-01-28 09:45:21.464 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:38819/api/school/respect/person from class io.ktor.http.content.TextContent
2026-01-28 09:45:21.468 [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-28 09:45:21.479 [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<world.respect.datalayer.school.model.Person>) for call /api/school/respect/person
2026-01-28 09:45:21.482 [DefaultDispatcher-worker-5 @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-28 09:45:21.483 [DefaultDispatcher-worker-8 @coroutine#151] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38819/api/school/respect/person
2026-01-28 09:45:21.483 [DefaultDispatcher-worker-8 @coroutine#151] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38819/api/school/respect/person
2026-01-28 09:45:21.483 [DefaultDispatcher-worker-8 @coroutine#151] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38819/api/school/respect/person
2026-01-28 09:45:23.641 [Test worker @coroutine#168] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:45:23.642 [Test worker @coroutine#168] INFO  io.ktor.server.Application - Application started in 0.036 seconds.
2026-01-28 09:45:23.648 [DefaultDispatcher-worker-8 @coroutine#177] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:46567
2026-01-28 09:45:23.651 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46567/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:23.655 [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:46567/api/school/respect/schoolpermissiongrant.
2026-01-28 09:45:23.655 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46567/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:23.679 [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-28 09:45:23.682 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46567/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:23.683 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46567/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:23.683 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46567/api/school/respect/schoolpermissiongrant
2026-01-28 09:45:23.683 [Test worker @coroutine#168] 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-28 09:45:23.717 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46567/api/school/respect/person?includeRelated=false
2026-01-28 09:45:23.717 [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:46567/api/school/respect/person?includeRelated=false.
2026-01-28 09:45:23.717 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46567/api/school/respect/person?includeRelated=false
2026-01-28 09:45:23.718 [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-28 09:45:23.738 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46567/api/school/respect/person?includeRelated=false
2026-01-28 09:45:23.739 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46567/api/school/respect/person?includeRelated=false
2026-01-28 09:45:23.739 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46567/api/school/respect/person?includeRelated=false
2026-01-28 09:45:23.744 [Test worker @coroutine#168] 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-28 09:45:25.762 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46567/api/school/respect/person?includeRelated=false
2026-01-28 09:45:25.762 [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:46567/api/school/respect/person?includeRelated=false.
2026-01-28 09:45:25.762 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46567/api/school/respect/person?includeRelated=false
2026-01-28 09:45:25.763 [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-28 09:45:25.767 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46567/api/school/respect/person?includeRelated=false
2026-01-28 09:45:25.767 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46567/api/school/respect/person?includeRelated=false
2026-01-28 09:45:25.767 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46567/api/school/respect/person?includeRelated=false
2026-01-28 09:45:25.768 [Test worker @coroutine#168] 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-28 09:45:27.888 [Test worker @coroutine#200] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 09:45:27.889 [Test worker @coroutine#200] INFO  io.ktor.server.Application - Application started in 0.04 seconds.
2026-01-28 09:45:27.892 [DefaultDispatcher-worker-6 @coroutine#209] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:33039
2026-01-28 09:45:27.904 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33039/api/school/respect/person?includeRelated=false
2026-01-28 09:45:27.904 [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:33039/api/school/respect/person?includeRelated=false.
2026-01-28 09:45:27.904 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33039/api/school/respect/person?includeRelated=false
2026-01-28 09:45:27.909 [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-28 09:45:27.913 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33039/api/school/respect/person?includeRelated=false
2026-01-28 09:45:27.913 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33039/api/school/respect/person?includeRelated=false
2026-01-28 09:45:27.913 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33039/api/school/respect/person?includeRelated=false
2026-01-28 09:45:27.914 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33039/api/school/respect/person?includeRelated=false.
2026-01-28 09:45:27.972 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33039/api/school/respect/person?since=2026-01-28T08%3A45%3A27.909948306Z&includeRelated=false
2026-01-28 09:45:27.973 [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:33039/api/school/respect/person?since=2026-01-28T08%3A45%3A27.909948306Z&includeRelated=false.
2026-01-28 09:45:27.974 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33039/api/school/respect/person?since=2026-01-28T08%3A45%3A27.909948306Z&includeRelated=false
2026-01-28 09:45:27.982 [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-28 09:45:27.989 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33039/api/school/respect/person?since=2026-01-28T08%3A45%3A27.909948306Z&includeRelated=false
2026-01-28 09:45:27.989 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33039/api/school/respect/person?since=2026-01-28T08%3A45%3A27.909948306Z&includeRelated=false
2026-01-28 09:45:27.989 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33039/api/school/respect/person?since=2026-01-28T08%3A45%3A27.909948306Z&includeRelated=false
2026-01-28 09:45:27.991 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33039/api/school/respect/person?since=2026-01-28T08%3A45%3A27.909948306Z&includeRelated=false.
2026-01-28 11:28:35.583 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:28:35.735 [Test worker] INFO  io.ktor.server.Application - Application started in 0.385 seconds.
2026-01-28 11:28:35.853 [DefaultDispatcher-worker-1 @coroutine#2] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:33029
2026-01-28 11:28:36.164 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33029/resources/index.json
2026-01-28 11:28:36.165 [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:33029/resources/index.json.
2026-01-28 11:28:36.165 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33029/resources/index.json
2026-01-28 11:28:36.350 [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-28 11:28:36.400 [DefaultDispatcher-worker-3 @call-handler#12] INFO  io.ktor.server.Application - 200 OK: GET - /resources/index.json in 58ms
2026-01-28 11:28:36.405 [DefaultDispatcher-worker-3 @coroutine#8] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33029/resources/index.json
2026-01-28 11:28:36.407 [DefaultDispatcher-worker-3 @coroutine#8] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33029/resources/index.json
2026-01-28 11:28:36.408 [DefaultDispatcher-worker-3 @coroutine#8] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33029/resources/index.json
2026-01-28 11:28:36.433 [DefaultDispatcher-worker-3 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:33029/resources/index.json.
2026-01-28 11:28:36.494 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33029/resources/index.json
2026-01-28 11:28:36.494 [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:33029/resources/index.json.
2026-01-28 11:28:36.494 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33029/resources/index.json
2026-01-28 11:28:36.496 [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-28 11:28:36.500 [eventLoopGroupProxy-4-1 @call-handler#22] INFO  io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 4ms
2026-01-28 11:28:36.502 [DefaultDispatcher-worker-4 @coroutine#20] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33029/resources/index.json
2026-01-28 11:28:36.502 [DefaultDispatcher-worker-4 @coroutine#20] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33029/resources/index.json
2026-01-28 11:28:36.502 [DefaultDispatcher-worker-4 @coroutine#20] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33029/resources/index.json
2026-01-28 11:28:38.548 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:28:38.548 [Test worker] INFO  io.ktor.server.Application - Application started in 0.001 seconds.
2026-01-28 11:28:38.554 [DefaultDispatcher-worker-4 @coroutine#26] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:38905
2026-01-28 11:28:38.593 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38905/resources/index.json
2026-01-28 11:28:38.594 [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:38905/resources/index.json.
2026-01-28 11:28:38.594 [Test worker @coroutine#30] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38905/resources/index.json
2026-01-28 11:28:38.599 [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-28 11:28:38.600 [DefaultDispatcher-worker-2 @call-handler#35] INFO  io.ktor.server.Application - 200 OK: GET - /resources/index.json in 2ms
2026-01-28 11:28:38.602 [Test worker @coroutine#30] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38905/resources/index.json
2026-01-28 11:28:38.602 [Test worker @coroutine#30] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38905/resources/index.json
2026-01-28 11:28:38.602 [Test worker @coroutine#30] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38905/resources/index.json
2026-01-28 11:28:38.603 [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:38905/resources/index.json.
2026-01-28 11:28:38.613 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38905/resources/index.json
2026-01-28 11:28:38.614 [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:38905/resources/index.json.
2026-01-28 11:28:38.614 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38905/resources/index.json
2026-01-28 11:28:38.616 [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-28 11:28:38.619 [eventLoopGroupProxy-7-1 @call-handler#45] INFO  io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 3ms
2026-01-28 11:28:38.621 [DefaultDispatcher-worker-3 @coroutine#43] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38905/resources/index.json
2026-01-28 11:28:38.621 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38905/resources/index.json
2026-01-28 11:28:38.621 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38905/resources/index.json
2026-01-28 11:28:40.630 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:28:40.631 [Test worker] INFO  io.ktor.server.Application - Application started in 0.001 seconds.
2026-01-28 11:28:40.633 [DefaultDispatcher-worker-3 @coroutine#49] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:41713
2026-01-28 11:28:40.636 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41713/resources/lesson001.json
2026-01-28 11:28:40.637 [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:41713/resources/lesson001.json.
2026-01-28 11:28:40.637 [Test worker @coroutine#52] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41713/resources/lesson001.json
2026-01-28 11:28:40.644 [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-28 11:28:40.646 [DefaultDispatcher-worker-3 @call-handler#56] INFO  io.ktor.server.Application - 200 OK: GET - /resources/lesson001.json in 2ms
2026-01-28 11:28:40.648 [DefaultDispatcher-worker-7 @coroutine#52] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41713/resources/lesson001.json
2026-01-28 11:28:40.649 [DefaultDispatcher-worker-7 @coroutine#52] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41713/resources/lesson001.json
2026-01-28 11:28:40.649 [DefaultDispatcher-worker-7 @coroutine#52] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41713/resources/lesson001.json
2026-01-28 11:28:40.682 [DefaultDispatcher-worker-7 @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsPublication for http://localhost:41713/resources/lesson001.json.
2026-01-28 11:28:43.872 [Test worker @coroutine#61] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:28:43.959 [Test worker @coroutine#61] INFO  io.ktor.server.Application - Application started in 1.114 seconds.
2026-01-28 11:28:43.977 [DefaultDispatcher-worker-7 @coroutine#70] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:42453
2026-01-28 11:28:44.013 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42453/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:44.013 [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:42453/api/school/respect/schoolpermissiongrant.
2026-01-28 11:28:44.013 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42453/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:44.040 [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-28 11:28:44.104 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42453/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:44.104 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42453/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:44.106 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42453/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:44.120 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42453/api/school/respect/schoolpermissiongrant.
2026-01-28 11:28:44.363 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42453/api/school/respect/person?includeRelated=false
2026-01-28 11:28:44.365 [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:42453/api/school/respect/person?includeRelated=false.
2026-01-28 11:28:44.365 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42453/api/school/respect/person?includeRelated=false
2026-01-28 11:28:44.372 [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-28 11:28:44.446 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42453/api/school/respect/person?includeRelated=false
2026-01-28 11:28:44.446 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42453/api/school/respect/person?includeRelated=false
2026-01-28 11:28:44.446 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42453/api/school/respect/person?includeRelated=false
2026-01-28 11:28:44.457 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42453/api/school/respect/person?includeRelated=false.
2026-01-28 11:28:44.500 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42453/api/school/respect/person?includeRelated=false
2026-01-28 11:28:44.501 [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:42453/api/school/respect/person?includeRelated=false.
2026-01-28 11:28:44.501 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42453/api/school/respect/person?includeRelated=false
2026-01-28 11:28:44.506 [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-28 11:28:44.529 [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-28 11:28:44.534 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42453/api/school/respect/person?includeRelated=false
2026-01-28 11:28:44.535 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42453/api/school/respect/person?includeRelated=false
2026-01-28 11:28:44.535 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42453/api/school/respect/person?includeRelated=false
2026-01-28 11:28:46.700 [Test worker @coroutine#90] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:28:46.702 [Test worker @coroutine#90] INFO  io.ktor.server.Application - Application started in 0.045 seconds.
2026-01-28 11:28:46.704 [DefaultDispatcher-worker-3 @coroutine#99] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:42959
2026-01-28 11:28:46.706 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42959/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:46.706 [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:42959/api/school/respect/schoolpermissiongrant.
2026-01-28 11:28:46.706 [Test worker @coroutine#90] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42959/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:46.713 [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-28 11:28:46.720 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42959/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:46.721 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42959/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:46.721 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42959/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:46.722 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42959/api/school/respect/schoolpermissiongrant.
2026-01-28 11:28:46.760 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42959/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 11:28:46.760 [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:42959/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100.
2026-01-28 11:28:46.760 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42959/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 11:28:46.761 [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-28 11:28:46.765 [DefaultDispatcher-worker-7 @coroutine#109] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42959/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 11:28:46.765 [DefaultDispatcher-worker-7 @coroutine#109] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42959/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 11:28:46.765 [DefaultDispatcher-worker-7 @coroutine#109] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42959/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 11:28:46.766 [DefaultDispatcher-worker-7 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42959/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100.
2026-01-28 11:28:48.871 [Test worker @coroutine#118] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:28:48.872 [Test worker @coroutine#118] INFO  io.ktor.server.Application - Application started in 0.036 seconds.
2026-01-28 11:28:48.876 [DefaultDispatcher-worker-7 @coroutine#127] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:38025
2026-01-28 11:28:48.882 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38025/api/school/respect/person?includeRelated=false
2026-01-28 11:28:48.882 [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:38025/api/school/respect/person?includeRelated=false.
2026-01-28 11:28:48.882 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38025/api/school/respect/person?includeRelated=false
2026-01-28 11:28:48.891 [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-28 11:28:48.900 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38025/api/school/respect/person?includeRelated=false
2026-01-28 11:28:48.901 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38025/api/school/respect/person?includeRelated=false
2026-01-28 11:28:48.901 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38025/api/school/respect/person?includeRelated=false
2026-01-28 11:28:48.905 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38025/api/school/respect/person?includeRelated=false.
2026-01-28 11:28:49.007 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38025/api/school/respect/person?since=2026-01-28T10%3A28%3A48.894570032Z&includeRelated=false
2026-01-28 11:28:49.013 [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:38025/api/school/respect/person?since=2026-01-28T10%3A28%3A48.894570032Z&includeRelated=false.
2026-01-28 11:28:49.013 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38025/api/school/respect/person?since=2026-01-28T10%3A28%3A48.894570032Z&includeRelated=false
2026-01-28 11:28:49.019 [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-28 11:28:49.024 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38025/api/school/respect/person?since=2026-01-28T10%3A28%3A48.894570032Z&includeRelated=false
2026-01-28 11:28:49.025 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38025/api/school/respect/person?since=2026-01-28T10%3A28%3A48.894570032Z&includeRelated=false
2026-01-28 11:28:49.025 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38025/api/school/respect/person?since=2026-01-28T10%3A28%3A48.894570032Z&includeRelated=false
2026-01-28 11:28:49.026 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38025/api/school/respect/person?since=2026-01-28T10%3A28%3A48.894570032Z&includeRelated=false.
2026-01-28 11:28:51.184 [Test worker @coroutine#144] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:28:51.187 [Test worker @coroutine#144] INFO  io.ktor.server.Application - Application started in 0.05 seconds.
2026-01-28 11:28:51.191 [DefaultDispatcher-worker-6 @coroutine#153] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:37493
2026-01-28 11:28:51.271 [DefaultDispatcher-worker-7 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:37493/api/school/respect/person
2026-01-28 11:28:51.272 [DefaultDispatcher-worker-7 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@1f8cd893 for http://localhost:37493/api/school/respect/person
2026-01-28 11:28:51.272 [DefaultDispatcher-worker-7 @coroutine#151] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:37493/api/school/respect/person
2026-01-28 11:28:51.272 [DefaultDispatcher-worker-7 @coroutine#151] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:37493/api/school/respect/person from class io.ktor.http.content.TextContent
2026-01-28 11:28:51.289 [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-28 11:28:51.309 [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<world.respect.datalayer.school.model.Person>) for call /api/school/respect/person
2026-01-28 11:28:51.319 [eventLoopGroupProxy-22-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-28 11:28:51.330 [DefaultDispatcher-worker-9 @coroutine#151] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:37493/api/school/respect/person
2026-01-28 11:28:51.331 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:37493/api/school/respect/person
2026-01-28 11:28:51.331 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:37493/api/school/respect/person
2026-01-28 11:28:53.483 [Test worker @coroutine#168] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:28:53.488 [Test worker @coroutine#168] INFO  io.ktor.server.Application - Application started in 0.043 seconds.
2026-01-28 11:28:53.495 [DefaultDispatcher-worker-3 @coroutine#177] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:34819
2026-01-28 11:28:53.496 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34819/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:53.497 [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:34819/api/school/respect/schoolpermissiongrant.
2026-01-28 11:28:53.497 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34819/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:53.504 [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-28 11:28:53.506 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34819/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:53.507 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34819/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:53.507 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34819/api/school/respect/schoolpermissiongrant
2026-01-28 11:28:53.507 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:34819/api/school/respect/schoolpermissiongrant.
2026-01-28 11:28:53.545 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34819/api/school/respect/person?includeRelated=false
2026-01-28 11:28:53.545 [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:34819/api/school/respect/person?includeRelated=false.
2026-01-28 11:28:53.545 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34819/api/school/respect/person?includeRelated=false
2026-01-28 11:28:53.552 [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-28 11:28:53.571 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34819/api/school/respect/person?includeRelated=false
2026-01-28 11:28:53.571 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34819/api/school/respect/person?includeRelated=false
2026-01-28 11:28:53.571 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34819/api/school/respect/person?includeRelated=false
2026-01-28 11:28:53.576 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:34819/api/school/respect/person?includeRelated=false.
2026-01-28 11:28:55.589 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34819/api/school/respect/person?includeRelated=false
2026-01-28 11:28:55.589 [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:34819/api/school/respect/person?includeRelated=false.
2026-01-28 11:28:55.589 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34819/api/school/respect/person?includeRelated=false
2026-01-28 11:28:55.591 [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-28 11:28:55.595 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34819/api/school/respect/person?includeRelated=false
2026-01-28 11:28:55.596 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34819/api/school/respect/person?includeRelated=false
2026-01-28 11:28:55.596 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34819/api/school/respect/person?includeRelated=false
2026-01-28 11:28:55.598 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:34819/api/school/respect/person?includeRelated=false.
2026-01-28 11:28:57.748 [Test worker @coroutine#200] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:28:57.756 [Test worker @coroutine#200] INFO  io.ktor.server.Application - Application started in 0.052 seconds.
2026-01-28 11:28:57.761 [DefaultDispatcher-worker-1 @coroutine#209] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:38645
2026-01-28 11:28:57.769 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38645/api/school/respect/person?includeRelated=false
2026-01-28 11:28:57.769 [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:38645/api/school/respect/person?includeRelated=false.
2026-01-28 11:28:57.769 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38645/api/school/respect/person?includeRelated=false
2026-01-28 11:28:57.781 [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-28 11:28:57.790 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38645/api/school/respect/person?includeRelated=false
2026-01-28 11:28:57.791 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38645/api/school/respect/person?includeRelated=false
2026-01-28 11:28:57.791 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38645/api/school/respect/person?includeRelated=false
2026-01-28 11:28:57.793 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38645/api/school/respect/person?includeRelated=false.
2026-01-28 11:28:57.842 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38645/api/school/respect/person?since=2026-01-28T10%3A28%3A57.781761583Z&includeRelated=false
2026-01-28 11:28:57.842 [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:38645/api/school/respect/person?since=2026-01-28T10%3A28%3A57.781761583Z&includeRelated=false.
2026-01-28 11:28:57.842 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38645/api/school/respect/person?since=2026-01-28T10%3A28%3A57.781761583Z&includeRelated=false
2026-01-28 11:28:57.846 [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-28 11:28:57.854 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38645/api/school/respect/person?since=2026-01-28T10%3A28%3A57.781761583Z&includeRelated=false
2026-01-28 11:28:57.856 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38645/api/school/respect/person?since=2026-01-28T10%3A28%3A57.781761583Z&includeRelated=false
2026-01-28 11:28:57.857 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38645/api/school/respect/person?since=2026-01-28T10%3A28%3A57.781761583Z&includeRelated=false
2026-01-28 11:28:57.860 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38645/api/school/respect/person?since=2026-01-28T10%3A28%3A57.781761583Z&includeRelated=false.
2026-01-28 11:44:40.045 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:44:40.191 [Test worker] INFO  io.ktor.server.Application - Application started in 0.348 seconds.
2026-01-28 11:44:40.317 [DefaultDispatcher-worker-1 @coroutine#2] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:44161
2026-01-28 11:44:40.745 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44161/resources/index.json
2026-01-28 11:44:40.746 [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:44161/resources/index.json.
2026-01-28 11:44:40.746 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44161/resources/index.json
2026-01-28 11:44:40.896 [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-28 11:44:40.943 [DefaultDispatcher-worker-3 @call-handler#12] INFO  io.ktor.server.Application - 200 OK: GET - /resources/index.json in 52ms
2026-01-28 11:44:40.949 [DefaultDispatcher-worker-5 @coroutine#8] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44161/resources/index.json
2026-01-28 11:44:40.951 [DefaultDispatcher-worker-5 @coroutine#8] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44161/resources/index.json
2026-01-28 11:44:40.951 [DefaultDispatcher-worker-5 @coroutine#8] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44161/resources/index.json
2026-01-28 11:44:40.977 [DefaultDispatcher-worker-5 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:44161/resources/index.json.
2026-01-28 11:44:41.056 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44161/resources/index.json
2026-01-28 11:44:41.056 [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:44161/resources/index.json.
2026-01-28 11:44:41.056 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44161/resources/index.json
2026-01-28 11:44:41.059 [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-28 11:44:41.065 [eventLoopGroupProxy-4-1 @call-handler#22] INFO  io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 6ms
2026-01-28 11:44:41.067 [DefaultDispatcher-worker-2 @coroutine#20] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44161/resources/index.json
2026-01-28 11:44:41.067 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44161/resources/index.json
2026-01-28 11:44:41.067 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44161/resources/index.json
2026-01-28 11:44:43.113 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:44:43.114 [Test worker] INFO  io.ktor.server.Application - Application started in 0.001 seconds.
2026-01-28 11:44:43.119 [DefaultDispatcher-worker-7 @coroutine#26] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:44389
2026-01-28 11:44:43.182 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44389/resources/index.json
2026-01-28 11:44:43.182 [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:44389/resources/index.json.
2026-01-28 11:44:43.182 [Test worker @coroutine#30] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44389/resources/index.json
2026-01-28 11:44:43.186 [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-28 11:44:43.189 [eventLoopGroupProxy-7-1 @call-handler#35] INFO  io.ktor.server.Application - 200 OK: GET - /resources/index.json in 3ms
2026-01-28 11:44:43.190 [Test worker @coroutine#30] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44389/resources/index.json
2026-01-28 11:44:43.190 [Test worker @coroutine#30] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44389/resources/index.json
2026-01-28 11:44:43.190 [Test worker @coroutine#30] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44389/resources/index.json
2026-01-28 11:44:43.191 [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:44389/resources/index.json.
2026-01-28 11:44:43.198 [DefaultDispatcher-worker-2 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44389/resources/index.json
2026-01-28 11:44:43.198 [DefaultDispatcher-worker-2 @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:44389/resources/index.json.
2026-01-28 11:44:43.198 [DefaultDispatcher-worker-2 @coroutine#43] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44389/resources/index.json
2026-01-28 11:44:43.201 [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-28 11:44:43.202 [eventLoopGroupProxy-7-1 @call-handler#45] INFO  io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 1ms
2026-01-28 11:44:43.203 [DefaultDispatcher-worker-2 @coroutine#43] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44389/resources/index.json
2026-01-28 11:44:43.203 [DefaultDispatcher-worker-2 @coroutine#43] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44389/resources/index.json
2026-01-28 11:44:43.203 [DefaultDispatcher-worker-2 @coroutine#43] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44389/resources/index.json
2026-01-28 11:44:45.214 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:44:45.215 [Test worker] INFO  io.ktor.server.Application - Application started in 0.001 seconds.
2026-01-28 11:44:45.216 [DefaultDispatcher-worker-2 @coroutine#49] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:40623
2026-01-28 11:44:45.220 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40623/resources/lesson001.json
2026-01-28 11:44:45.221 [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:40623/resources/lesson001.json.
2026-01-28 11:44:45.221 [Test worker @coroutine#52] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40623/resources/lesson001.json
2026-01-28 11:44:45.223 [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-28 11:44:45.224 [DefaultDispatcher-worker-7 @call-handler#56] INFO  io.ktor.server.Application - 200 OK: GET - /resources/lesson001.json in 1ms
2026-01-28 11:44:45.226 [DefaultDispatcher-worker-7 @coroutine#52] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40623/resources/lesson001.json
2026-01-28 11:44:45.226 [DefaultDispatcher-worker-2 @coroutine#52] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40623/resources/lesson001.json
2026-01-28 11:44:45.226 [DefaultDispatcher-worker-2 @coroutine#52] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40623/resources/lesson001.json
2026-01-28 11:44:45.260 [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:40623/resources/lesson001.json.
2026-01-28 11:44:48.787 [Test worker @coroutine#61] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:44:48.843 [Test worker @coroutine#61] INFO  io.ktor.server.Application - Application started in 1.427 seconds.
2026-01-28 11:44:48.858 [DefaultDispatcher-worker-2 @coroutine#70] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:45213
2026-01-28 11:44:48.908 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45213/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:48.908 [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:45213/api/school/respect/schoolpermissiongrant.
2026-01-28 11:44:48.913 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45213/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:48.964 [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-28 11:44:49.030 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45213/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:49.030 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45213/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:49.030 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45213/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:49.035 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:45213/api/school/respect/schoolpermissiongrant.
2026-01-28 11:44:49.189 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45213/api/school/respect/person?includeRelated=false
2026-01-28 11:44:49.189 [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:45213/api/school/respect/person?includeRelated=false.
2026-01-28 11:44:49.190 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45213/api/school/respect/person?includeRelated=false
2026-01-28 11:44:49.193 [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-28 11:44:49.219 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45213/api/school/respect/person?includeRelated=false
2026-01-28 11:44:49.220 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45213/api/school/respect/person?includeRelated=false
2026-01-28 11:44:49.220 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45213/api/school/respect/person?includeRelated=false
2026-01-28 11:44:49.224 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:45213/api/school/respect/person?includeRelated=false.
2026-01-28 11:44:49.234 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45213/api/school/respect/person?includeRelated=false
2026-01-28 11:44:49.234 [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:45213/api/school/respect/person?includeRelated=false.
2026-01-28 11:44:49.234 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45213/api/school/respect/person?includeRelated=false
2026-01-28 11:44:49.242 [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-28 11:44:49.246 [DefaultDispatcher-worker-7 @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-28 11:44:49.260 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45213/api/school/respect/person?includeRelated=false
2026-01-28 11:44:49.261 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45213/api/school/respect/person?includeRelated=false
2026-01-28 11:44:49.261 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45213/api/school/respect/person?includeRelated=false
2026-01-28 11:44:51.422 [Test worker @coroutine#90] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:44:51.423 [Test worker @coroutine#90] INFO  io.ktor.server.Application - Application started in 0.039 seconds.
2026-01-28 11:44:51.424 [DefaultDispatcher-worker-4 @coroutine#99] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:35449
2026-01-28 11:44:51.426 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:35449/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:51.426 [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:35449/api/school/respect/schoolpermissiongrant.
2026-01-28 11:44:51.426 [Test worker @coroutine#90] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:35449/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:51.429 [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-28 11:44:51.431 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:35449/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:51.431 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:35449/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:51.431 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:35449/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:51.431 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:35449/api/school/respect/schoolpermissiongrant.
2026-01-28 11:44:51.486 [DefaultDispatcher-worker-8 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:35449/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 11:44:51.486 [DefaultDispatcher-worker-8 @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:35449/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100.
2026-01-28 11:44:51.486 [DefaultDispatcher-worker-8 @coroutine#109] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:35449/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 11:44:51.488 [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-28 11:44:51.491 [DefaultDispatcher-worker-4 @coroutine#109] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:35449/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 11:44:51.491 [DefaultDispatcher-worker-4 @coroutine#109] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:35449/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 11:44:51.491 [DefaultDispatcher-worker-4 @coroutine#109] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:35449/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 11:44:51.492 [DefaultDispatcher-worker-4 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:35449/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100.
2026-01-28 11:44:53.646 [Test worker @coroutine#118] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:44:53.649 [Test worker @coroutine#118] INFO  io.ktor.server.Application - Application started in 0.049 seconds.
2026-01-28 11:44:53.651 [DefaultDispatcher-worker-8 @coroutine#127] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:33819
2026-01-28 11:44:53.665 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33819/api/school/respect/person?includeRelated=false
2026-01-28 11:44:53.666 [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:33819/api/school/respect/person?includeRelated=false.
2026-01-28 11:44:53.666 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33819/api/school/respect/person?includeRelated=false
2026-01-28 11:44:53.673 [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-28 11:44:53.680 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33819/api/school/respect/person?includeRelated=false
2026-01-28 11:44:53.681 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33819/api/school/respect/person?includeRelated=false
2026-01-28 11:44:53.682 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33819/api/school/respect/person?includeRelated=false
2026-01-28 11:44:53.683 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33819/api/school/respect/person?includeRelated=false.
2026-01-28 11:44:53.772 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33819/api/school/respect/person?since=2026-01-28T10%3A44%3A53.673796458Z&includeRelated=false
2026-01-28 11:44:53.773 [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:33819/api/school/respect/person?since=2026-01-28T10%3A44%3A53.673796458Z&includeRelated=false.
2026-01-28 11:44:53.773 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33819/api/school/respect/person?since=2026-01-28T10%3A44%3A53.673796458Z&includeRelated=false
2026-01-28 11:44:53.779 [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-28 11:44:53.795 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33819/api/school/respect/person?since=2026-01-28T10%3A44%3A53.673796458Z&includeRelated=false
2026-01-28 11:44:53.796 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33819/api/school/respect/person?since=2026-01-28T10%3A44%3A53.673796458Z&includeRelated=false
2026-01-28 11:44:53.796 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33819/api/school/respect/person?since=2026-01-28T10%3A44%3A53.673796458Z&includeRelated=false
2026-01-28 11:44:53.802 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33819/api/school/respect/person?since=2026-01-28T10%3A44%3A53.673796458Z&includeRelated=false.
2026-01-28 11:44:55.974 [Test worker @coroutine#144] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:44:55.976 [Test worker @coroutine#144] INFO  io.ktor.server.Application - Application started in 0.047 seconds.
2026-01-28 11:44:55.979 [DefaultDispatcher-worker-8 @coroutine#153] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:40683
2026-01-28 11:44:56.052 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40683/api/school/respect/person
2026-01-28 11:44:56.055 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@5b78327f for http://localhost:40683/api/school/respect/person
2026-01-28 11:44:56.055 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40683/api/school/respect/person
2026-01-28 11:44:56.056 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:40683/api/school/respect/person from class io.ktor.http.content.TextContent
2026-01-28 11:44:56.073 [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-28 11:44:56.088 [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<world.respect.datalayer.school.model.Person>) for call /api/school/respect/person
2026-01-28 11:44:56.102 [DefaultDispatcher-worker-7 @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-28 11:44:56.113 [DefaultDispatcher-worker-7 @coroutine#151] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40683/api/school/respect/person
2026-01-28 11:44:56.113 [DefaultDispatcher-worker-7 @coroutine#151] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40683/api/school/respect/person
2026-01-28 11:44:56.113 [DefaultDispatcher-worker-7 @coroutine#151] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40683/api/school/respect/person
2026-01-28 11:44:58.392 [Test worker @coroutine#168] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:44:58.398 [Test worker @coroutine#168] INFO  io.ktor.server.Application - Application started in 0.117 seconds.
2026-01-28 11:44:58.403 [DefaultDispatcher-worker-10 @coroutine#177] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:42639
2026-01-28 11:44:58.405 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42639/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:58.406 [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:42639/api/school/respect/schoolpermissiongrant.
2026-01-28 11:44:58.407 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42639/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:58.429 [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-28 11:44:58.435 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42639/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:58.435 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42639/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:58.435 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42639/api/school/respect/schoolpermissiongrant
2026-01-28 11:44:58.436 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42639/api/school/respect/schoolpermissiongrant.
2026-01-28 11:44:58.497 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42639/api/school/respect/person?includeRelated=false
2026-01-28 11:44:58.498 [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:42639/api/school/respect/person?includeRelated=false.
2026-01-28 11:44:58.498 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42639/api/school/respect/person?includeRelated=false
2026-01-28 11:44:58.500 [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-28 11:44:58.524 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42639/api/school/respect/person?includeRelated=false
2026-01-28 11:44:58.524 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42639/api/school/respect/person?includeRelated=false
2026-01-28 11:44:58.524 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42639/api/school/respect/person?includeRelated=false
2026-01-28 11:44:58.525 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42639/api/school/respect/person?includeRelated=false.
2026-01-28 11:45:00.533 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42639/api/school/respect/person?includeRelated=false
2026-01-28 11:45:00.533 [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:42639/api/school/respect/person?includeRelated=false.
2026-01-28 11:45:00.533 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42639/api/school/respect/person?includeRelated=false
2026-01-28 11:45:00.534 [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-28 11:45:00.538 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42639/api/school/respect/person?includeRelated=false
2026-01-28 11:45:00.538 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42639/api/school/respect/person?includeRelated=false
2026-01-28 11:45:00.538 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42639/api/school/respect/person?includeRelated=false
2026-01-28 11:45:00.539 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42639/api/school/respect/person?includeRelated=false.
2026-01-28 11:45:02.701 [Test worker @coroutine#200] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 11:45:02.702 [Test worker @coroutine#200] INFO  io.ktor.server.Application - Application started in 0.072 seconds.
2026-01-28 11:45:02.705 [DefaultDispatcher-worker-10 @coroutine#209] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:41659
2026-01-28 11:45:02.716 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41659/api/school/respect/person?includeRelated=false
2026-01-28 11:45:02.717 [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:41659/api/school/respect/person?includeRelated=false.
2026-01-28 11:45:02.717 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41659/api/school/respect/person?includeRelated=false
2026-01-28 11:45:02.719 [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-28 11:45:02.733 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41659/api/school/respect/person?includeRelated=false
2026-01-28 11:45:02.733 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41659/api/school/respect/person?includeRelated=false
2026-01-28 11:45:02.733 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41659/api/school/respect/person?includeRelated=false
2026-01-28 11:45:02.735 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41659/api/school/respect/person?includeRelated=false.
2026-01-28 11:45:02.806 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41659/api/school/respect/person?since=2026-01-28T10%3A45%3A02.720331422Z&includeRelated=false
2026-01-28 11:45:02.807 [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:41659/api/school/respect/person?since=2026-01-28T10%3A45%3A02.720331422Z&includeRelated=false.
2026-01-28 11:45:02.807 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41659/api/school/respect/person?since=2026-01-28T10%3A45%3A02.720331422Z&includeRelated=false
2026-01-28 11:45:02.816 [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-28 11:45:02.824 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41659/api/school/respect/person?since=2026-01-28T10%3A45%3A02.720331422Z&includeRelated=false
2026-01-28 11:45:02.824 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41659/api/school/respect/person?since=2026-01-28T10%3A45%3A02.720331422Z&includeRelated=false
2026-01-28 11:45:02.824 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41659/api/school/respect/person?since=2026-01-28T10%3A45%3A02.720331422Z&includeRelated=false
2026-01-28 11:45:02.825 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41659/api/school/respect/person?since=2026-01-28T10%3A45%3A02.720331422Z&includeRelated=false.
2026-01-28 13:57:28.179 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 13:57:28.296 [Test worker] INFO  io.ktor.server.Application - Application started in 0.26 seconds.
2026-01-28 13:57:28.402 [DefaultDispatcher-worker-1 @coroutine#2] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:35747
2026-01-28 13:57:28.706 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:35747/resources/index.json
2026-01-28 13:57:28.706 [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:35747/resources/index.json.
2026-01-28 13:57:28.707 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:35747/resources/index.json
2026-01-28 13:57:28.962 [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-28 13:57:29.032 [eventLoopGroupProxy-4-1 @call-handler#12] INFO  io.ktor.server.Application - 200 OK: GET - /resources/index.json in 83ms
2026-01-28 13:57:29.040 [DefaultDispatcher-worker-2 @coroutine#8] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:35747/resources/index.json
2026-01-28 13:57:29.042 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:35747/resources/index.json
2026-01-28 13:57:29.042 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:35747/resources/index.json
2026-01-28 13:57:29.069 [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:35747/resources/index.json.
2026-01-28 13:57:29.123 [DefaultDispatcher-worker-4 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:35747/resources/index.json
2026-01-28 13:57:29.123 [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:35747/resources/index.json.
2026-01-28 13:57:29.123 [DefaultDispatcher-worker-4 @coroutine#20] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:35747/resources/index.json
2026-01-28 13:57:29.132 [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-28 13:57:29.135 [eventLoopGroupProxy-4-1 @call-handler#22] INFO  io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 3ms
2026-01-28 13:57:29.136 [DefaultDispatcher-worker-4 @coroutine#20] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:35747/resources/index.json
2026-01-28 13:57:29.137 [DefaultDispatcher-worker-4 @coroutine#20] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:35747/resources/index.json
2026-01-28 13:57:29.139 [DefaultDispatcher-worker-4 @coroutine#20] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:35747/resources/index.json
2026-01-28 13:57:31.161 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 13:57:31.162 [Test worker] INFO  io.ktor.server.Application - Application started in 0.001 seconds.
2026-01-28 13:57:31.166 [DefaultDispatcher-worker-4 @coroutine#26] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:37957
2026-01-28 13:57:31.229 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:37957/resources/index.json
2026-01-28 13:57:31.229 [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:37957/resources/index.json.
2026-01-28 13:57:31.229 [Test worker @coroutine#30] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:37957/resources/index.json
2026-01-28 13:57:31.233 [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-28 13:57:31.234 [DefaultDispatcher-worker-4 @call-handler#35] INFO  io.ktor.server.Application - 200 OK: GET - /resources/index.json in 1ms
2026-01-28 13:57:31.235 [Test worker @coroutine#30] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:37957/resources/index.json
2026-01-28 13:57:31.235 [Test worker @coroutine#30] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:37957/resources/index.json
2026-01-28 13:57:31.235 [Test worker @coroutine#30] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:37957/resources/index.json
2026-01-28 13:57:31.236 [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:37957/resources/index.json.
2026-01-28 13:57:31.240 [DefaultDispatcher-worker-2 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:37957/resources/index.json
2026-01-28 13:57:31.240 [DefaultDispatcher-worker-2 @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:37957/resources/index.json.
2026-01-28 13:57:31.240 [DefaultDispatcher-worker-2 @coroutine#43] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:37957/resources/index.json
2026-01-28 13:57:31.242 [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-28 13:57:31.242 [eventLoopGroupProxy-7-1 @call-handler#45] INFO  io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 0ms
2026-01-28 13:57:31.243 [DefaultDispatcher-worker-2 @coroutine#43] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:37957/resources/index.json
2026-01-28 13:57:31.243 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:37957/resources/index.json
2026-01-28 13:57:31.243 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:37957/resources/index.json
2026-01-28 13:57:33.265 [Test worker] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 13:57:33.266 [Test worker] INFO  io.ktor.server.Application - Application started in 0.004 seconds.
2026-01-28 13:57:33.276 [DefaultDispatcher-worker-3 @coroutine#49] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:44519
2026-01-28 13:57:33.293 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44519/resources/lesson001.json
2026-01-28 13:57:33.293 [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:44519/resources/lesson001.json.
2026-01-28 13:57:33.293 [Test worker @coroutine#52] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44519/resources/lesson001.json
2026-01-28 13:57:33.311 [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-28 13:57:33.316 [DefaultDispatcher-worker-2 @call-handler#56] INFO  io.ktor.server.Application - 200 OK: GET - /resources/lesson001.json in 5ms
2026-01-28 13:57:33.326 [DefaultDispatcher-worker-3 @coroutine#52] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44519/resources/lesson001.json
2026-01-28 13:57:33.327 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44519/resources/lesson001.json
2026-01-28 13:57:33.327 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44519/resources/lesson001.json
2026-01-28 13:57:33.395 [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:44519/resources/lesson001.json.
2026-01-28 13:57:36.081 [Test worker @coroutine#61] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 13:57:36.092 [Test worker @coroutine#61] INFO  io.ktor.server.Application - Application started in 0.558 seconds.
2026-01-28 13:57:36.095 [DefaultDispatcher-worker-3 @coroutine#70] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:38029
2026-01-28 13:57:36.103 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38029/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:36.103 [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:38029/api/school/respect/schoolpermissiongrant.
2026-01-28 13:57:36.103 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38029/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:36.109 [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-28 13:57:36.124 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38029/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:36.124 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38029/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:36.124 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38029/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:36.127 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38029/api/school/respect/schoolpermissiongrant.
2026-01-28 13:57:36.207 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38029/api/school/respect/person?includeRelated=false
2026-01-28 13:57:36.207 [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:38029/api/school/respect/person?includeRelated=false.
2026-01-28 13:57:36.207 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38029/api/school/respect/person?includeRelated=false
2026-01-28 13:57:36.209 [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-28 13:57:36.230 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38029/api/school/respect/person?includeRelated=false
2026-01-28 13:57:36.231 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38029/api/school/respect/person?includeRelated=false
2026-01-28 13:57:36.231 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38029/api/school/respect/person?includeRelated=false
2026-01-28 13:57:36.232 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38029/api/school/respect/person?includeRelated=false.
2026-01-28 13:57:36.238 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38029/api/school/respect/person?includeRelated=false
2026-01-28 13:57:36.238 [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:38029/api/school/respect/person?includeRelated=false.
2026-01-28 13:57:36.238 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38029/api/school/respect/person?includeRelated=false
2026-01-28 13:57:36.240 [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-28 13:57:36.242 [DefaultDispatcher-worker-10 @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-28 13:57:36.244 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38029/api/school/respect/person?includeRelated=false
2026-01-28 13:57:36.244 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38029/api/school/respect/person?includeRelated=false
2026-01-28 13:57:36.244 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38029/api/school/respect/person?includeRelated=false
2026-01-28 13:57:38.389 [Test worker @coroutine#90] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 13:57:38.394 [Test worker @coroutine#90] INFO  io.ktor.server.Application - Application started in 0.044 seconds.
2026-01-28 13:57:38.399 [DefaultDispatcher-worker-3 @coroutine#99] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:39813
2026-01-28 13:57:38.402 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39813/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:38.404 [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:39813/api/school/respect/schoolpermissiongrant.
2026-01-28 13:57:38.404 [Test worker @coroutine#90] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39813/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:38.423 [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-28 13:57:38.439 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39813/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:38.440 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39813/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:38.440 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39813/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:38.443 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39813/api/school/respect/schoolpermissiongrant.
2026-01-28 13:57:38.532 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39813/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 13:57:38.532 [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:39813/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100.
2026-01-28 13:57:38.532 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39813/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 13:57:38.539 [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-28 13:57:38.547 [DefaultDispatcher-worker-3 @coroutine#109] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39813/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 13:57:38.547 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39813/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 13:57:38.547 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39813/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100
2026-01-28 13:57:38.549 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39813/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100.
2026-01-28 13:57:40.727 [Test worker @coroutine#118] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 13:57:40.732 [Test worker @coroutine#118] INFO  io.ktor.server.Application - Application started in 0.041 seconds.
2026-01-28 13:57:40.741 [DefaultDispatcher-worker-10 @coroutine#127] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:44839
2026-01-28 13:57:40.750 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44839/api/school/respect/person?includeRelated=false
2026-01-28 13:57:40.751 [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:44839/api/school/respect/person?includeRelated=false.
2026-01-28 13:57:40.751 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44839/api/school/respect/person?includeRelated=false
2026-01-28 13:57:40.776 [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-28 13:57:40.790 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44839/api/school/respect/person?includeRelated=false
2026-01-28 13:57:40.791 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44839/api/school/respect/person?includeRelated=false
2026-01-28 13:57:40.791 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44839/api/school/respect/person?includeRelated=false
2026-01-28 13:57:40.795 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:44839/api/school/respect/person?includeRelated=false.
2026-01-28 13:57:40.852 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44839/api/school/respect/person?since=2026-01-28T12%3A57%3A40.780605793Z&includeRelated=false
2026-01-28 13:57:40.852 [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:44839/api/school/respect/person?since=2026-01-28T12%3A57%3A40.780605793Z&includeRelated=false.
2026-01-28 13:57:40.852 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44839/api/school/respect/person?since=2026-01-28T12%3A57%3A40.780605793Z&includeRelated=false
2026-01-28 13:57:40.855 [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-28 13:57:40.865 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44839/api/school/respect/person?since=2026-01-28T12%3A57%3A40.780605793Z&includeRelated=false
2026-01-28 13:57:40.866 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44839/api/school/respect/person?since=2026-01-28T12%3A57%3A40.780605793Z&includeRelated=false
2026-01-28 13:57:40.866 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44839/api/school/respect/person?since=2026-01-28T12%3A57%3A40.780605793Z&includeRelated=false
2026-01-28 13:57:40.868 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:44839/api/school/respect/person?since=2026-01-28T12%3A57%3A40.780605793Z&includeRelated=false.
2026-01-28 13:57:43.079 [Test worker @coroutine#144] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 13:57:43.080 [Test worker @coroutine#144] INFO  io.ktor.server.Application - Application started in 0.043 seconds.
2026-01-28 13:57:43.082 [DefaultDispatcher-worker-9 @coroutine#153] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:46645
2026-01-28 13:57:43.127 [DefaultDispatcher-worker-7 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46645/api/school/respect/person
2026-01-28 13:57:43.128 [DefaultDispatcher-worker-7 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@42bb7317 for http://localhost:46645/api/school/respect/person
2026-01-28 13:57:43.128 [DefaultDispatcher-worker-7 @coroutine#151] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46645/api/school/respect/person
2026-01-28 13:57:43.128 [DefaultDispatcher-worker-7 @coroutine#151] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:46645/api/school/respect/person from class io.ktor.http.content.TextContent
2026-01-28 13:57:43.143 [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-28 13:57:43.161 [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<world.respect.datalayer.school.model.Person>) for call /api/school/respect/person
2026-01-28 13:57:43.170 [DefaultDispatcher-worker-7 @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-28 13:57:43.186 [DefaultDispatcher-worker-1 @coroutine#151] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46645/api/school/respect/person
2026-01-28 13:57:43.187 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46645/api/school/respect/person
2026-01-28 13:57:43.190 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46645/api/school/respect/person
2026-01-28 13:57:45.330 [Test worker @coroutine#168] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 13:57:45.331 [Test worker @coroutine#168] INFO  io.ktor.server.Application - Application started in 0.042 seconds.
2026-01-28 13:57:45.338 [DefaultDispatcher-worker-1 @coroutine#177] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:42917
2026-01-28 13:57:45.340 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42917/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:45.340 [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:42917/api/school/respect/schoolpermissiongrant.
2026-01-28 13:57:45.340 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42917/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:45.342 [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-28 13:57:45.344 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42917/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:45.344 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42917/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:45.344 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42917/api/school/respect/schoolpermissiongrant
2026-01-28 13:57:45.345 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42917/api/school/respect/schoolpermissiongrant.
2026-01-28 13:57:45.380 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42917/api/school/respect/person?includeRelated=false
2026-01-28 13:57:45.380 [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:42917/api/school/respect/person?includeRelated=false.
2026-01-28 13:57:45.380 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42917/api/school/respect/person?includeRelated=false
2026-01-28 13:57:45.381 [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-28 13:57:45.388 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42917/api/school/respect/person?includeRelated=false
2026-01-28 13:57:45.388 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42917/api/school/respect/person?includeRelated=false
2026-01-28 13:57:45.388 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42917/api/school/respect/person?includeRelated=false
2026-01-28 13:57:45.391 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42917/api/school/respect/person?includeRelated=false.
2026-01-28 13:57:47.398 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42917/api/school/respect/person?includeRelated=false
2026-01-28 13:57:47.399 [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:42917/api/school/respect/person?includeRelated=false.
2026-01-28 13:57:47.399 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42917/api/school/respect/person?includeRelated=false
2026-01-28 13:57:47.400 [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-28 13:57:47.404 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42917/api/school/respect/person?includeRelated=false
2026-01-28 13:57:47.404 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42917/api/school/respect/person?includeRelated=false
2026-01-28 13:57:47.404 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42917/api/school/respect/person?includeRelated=false
2026-01-28 13:57:47.405 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42917/api/school/respect/person?includeRelated=false.
2026-01-28 13:57:49.507 [Test worker @coroutine#200] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-01-28 13:57:49.508 [Test worker @coroutine#200] INFO  io.ktor.server.Application - Application started in 0.047 seconds.
2026-01-28 13:57:49.509 [DefaultDispatcher-worker-1 @coroutine#209] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:45973
2026-01-28 13:57:49.511 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45973/api/school/respect/person?includeRelated=false
2026-01-28 13:57:49.511 [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:45973/api/school/respect/person?includeRelated=false.
2026-01-28 13:57:49.511 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45973/api/school/respect/person?includeRelated=false
2026-01-28 13:57:49.514 [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-28 13:57:49.517 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45973/api/school/respect/person?includeRelated=false
2026-01-28 13:57:49.517 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45973/api/school/respect/person?includeRelated=false
2026-01-28 13:57:49.517 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45973/api/school/respect/person?includeRelated=false
2026-01-28 13:57:49.518 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:45973/api/school/respect/person?includeRelated=false.
2026-01-28 13:57:49.549 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45973/api/school/respect/person?since=2026-01-28T12%3A57%3A49.514502606Z&includeRelated=false
2026-01-28 13:57:49.550 [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:45973/api/school/respect/person?since=2026-01-28T12%3A57%3A49.514502606Z&includeRelated=false.
2026-01-28 13:57:49.550 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45973/api/school/respect/person?since=2026-01-28T12%3A57%3A49.514502606Z&includeRelated=false
2026-01-28 13:57:49.551 [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-28 13:57:49.554 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45973/api/school/respect/person?since=2026-01-28T12%3A57%3A49.514502606Z&includeRelated=false
2026-01-28 13:57:49.554 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45973/api/school/respect/person?since=2026-01-28T12%3A57%3A49.514502606Z&includeRelated=false
2026-01-28 13:57:49.554 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45973/api/school/respect/person?since=2026-01-28T12%3A57%3A49.514502606Z&includeRelated=false
2026-01-28 13:57:49.555 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:45973/api/school/respect/person?since=2026-01-28T12%3A57%3A49.514502606Z&includeRelated=false.