¡ ”ð°Žport = 39125 ¡ ”ñ·é19:00:50,008 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found logback-core version 1.6.1 ¡ ”ñ·È19:00:50,009 |-INFO in ch.qos.logback.classic.util.ContextInitializer@2b0f373b - No custom configurators were discovered as a service. ¡ ”ñ·à19:00:50,010 |-INFO in ch.qos.logback.classic.util.ContextInitializer@2b0f373b - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator ¡ ”ñ¸î19:00:50,011 |-INFO in ch.qos.logback.classic.util.ContextInitializer@2b0f373b - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator ¡ ”ñ¸ó19:00:50,014 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] ¡ ”ñ¸î19:00:50,017 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/home/jenkins/.jenkins/workspace/dev_navigation_bug/respect-datalayer-repository/build/processedResources/jvm/test/logback.xml] ¡ ”ñ¸×19:00:50,018 |-WARN in ch.qos.logback.classic.util.DefaultJoranConfigurator@2ceb80a1 - Resource [logback.xml] occurs multiple times on the classpath. ¡ ”ñ¸ÿ19:00:50,018 |-WARN in ch.qos.logback.classic.util.DefaultJoranConfigurator@2ceb80a1 - Resource [logback.xml] occurs at [file:/home/jenkins/.jenkins/workspace/dev_navigation_bug/respect-datalayer-repository/build/processedResources/jvm/test/logback.xml] ¡ ”ñ¸ù19:00:50,018 |-WARN in ch.qos.logback.classic.util.DefaultJoranConfigurator@2ceb80a1 - Resource [logback.xml] occurs at [jar:file:/home/jenkins/.jenkins/workspace/dev_navigation_bug/respect-server/build/libs/respect-server-1.0.41.jar!/logback.xml] ¡ ”ñ¸Ô19:00:50,129 |-INFO in ch.qos.logback.classic.model.processor.ConfigurationModelHandlerFull - Scan attribute not set or set to unrecognized value. ¡ ”ñ¸ö19:00:50,137 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [STDOUT] ¡ ”ñ¸ß19:00:50,138 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] ¡ ”ñ¸ö19:00:50,146 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property ¡ ”ñ¹Í19:00:50,218 |-INFO in ch.qos.logback.core.ConsoleAppender[STDOUT] - NOTE: Writing to the console can be slow. Try to avoid logging to the ¡ ”ñ¹Í19:00:50,218 |-INFO in ch.qos.logback.core.ConsoleAppender[STDOUT] - console in production environments, especially in high volume systems. ¡ ”ñ¹ý19:00:50,218 |-INFO in ch.qos.logback.core.ConsoleAppender[STDOUT] - See also https://logback.qos.ch/codes.html#slowConsole ¡ ”ñ¹þ19:00:50,219 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE ¡ ”ñ¹È19:00:50,221 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [STDOUT] to Logger[ROOT] ¡ ”ñ¹ÿ19:00:50,221 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO ¡ ”ñ¹î19:00:50,221 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@4b45dcb8 - End of configuration. ¡ ”ñ¹Ë19:00:50,222 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@7216fb24 - Registering current configuration as safe fallback point ¡ ”ñ¹Û19:00:50,224 |-INFO in ch.qos.logback.classic.util.ContextInitializer@2b0f373b - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 212 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY ¡ ”ñ¹‚ ¡ ”óÆ2026-10-05 19:00:50.583 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ ”þËŽport = 44603 ¡ ”þÌÆ2026-10-05 19:00:53.580 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ •ñŽport = 35411 ¡ •ñÆ2026-10-05 19:00:54.641 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ •»Ô2026-10-05 19:00:55.867 [Test worker @coroutine#62] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ •Ãþ2026-10-05 19:00:55.875 [Test worker @coroutine#62] INFO io.ktor.server.Application - Application started in 0.108 seconds. ¡ •ÅÊ2026-10-05 19:00:55.877 [DefaultDispatcher-worker-4 @coroutine#71] INFO io.ktor.server.Application - Responding at http://0.0.0.0:46477 ¡ •ÏÄ2026-10-05 19:00:55.887 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46477/api/school/respect/schoolpermissiongrant ¡ •ÏÂ2026-10-05 19:00:55.887 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:46477/api/school/respect/schoolpermissiongrant. ¡ •Ôä2026-10-05 19:00:55.892 [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-10-05 19:00:55.910 [Test worker @coroutine#62] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46477/api/school/respect/schoolpermissiongrant ¡ •æÂ2026-10-05 19:00:55.910 [Test worker @coroutine#62] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46477/api/school/respect/schoolpermissiongrant ¡ •æÈ2026-10-05 19:00:55.911 [Test worker @coroutine#62] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46477/api/school/respect/schoolpermissiongrant ¡ •éØ2026-10-05 19:00:55.913 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:46477/api/school/respect/schoolpermissiongrant. ¡ •(Ê2026-10-05 19:00:55.976 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46477/api/school/respect/person?includeRelated=false ¡ •(È2026-10-05 19:00:55.976 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:46477/api/school/respect/person?includeRelated=false. ¡ •*Õ2026-10-05 19:00:55.978 [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)] ¡ •Bó2026-10-05 19:00:56.002 [Test worker @coroutine#62] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46477/api/school/respect/person?includeRelated=false ¡ •BÈ2026-10-05 19:00:56.003 [Test worker @coroutine#62] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46477/api/school/respect/person?includeRelated=false ¡ •BÎ2026-10-05 19:00:56.003 [Test worker @coroutine#62] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46477/api/school/respect/person?includeRelated=false ¡ •DÞ2026-10-05 19:00:56.004 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:46477/api/school/respect/person?includeRelated=false. ¡ •KÊ2026-10-05 19:00:56.011 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46477/api/school/respect/person?includeRelated=false ¡ •KÈ2026-10-05 19:00:56.011 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:46477/api/school/respect/person?includeRelated=false. ¡ •MÕ2026-10-05 19:00:56.013 [eventLoopGroupProxy-13-1 @call-handler#86] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, person] ¡ •M�/, segment:0 -> SUCCESS @ / ¡ •M¥ /api, segment:1 -> SUCCESS @ /api ¡ •Mµ /api/school, segment:2 -> SUCCESS @ /api/school ¡ •MÇ /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect ¡ •M× /api/school/respect/person, segment:4 -> SUCCESS @ /api/school/respect/person ¡ •M÷ /api/school/respect/person [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •MÑ /api/school/respect/person [(method:POST)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/respect/person [(method:POST)] ¡ •MÍ /api/school/respect/schoolpermissiongrant, segment:3 -> FAILURE "Selector didn't match" @ /api/school/respect/schoolpermissiongrant ¡ •M‘Matched routes: ¡ •MÆ "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" ¡ •M™Routing resolve result: ¡ •M· SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •NÓ2026-10-05 19:00:56.015 [eventLoopGroupProxy-13-1 @call-handler#86] TRACE i.k.s.p.c.ContentNegotiation - Skipping response body transformation from HttpStatusCode to OutgoingContent for the GET /api/school/respect/person?includeRelated=false request because the HttpStatusCode type is ignored. See [ContentNegotiationConfig::ignoreType]. ¡ •Pó2026-10-05 19:00:56.016 [Test worker @coroutine#62] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46477/api/school/respect/person?includeRelated=false ¡ •PÈ2026-10-05 19:00:56.016 [Test worker @coroutine#62] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46477/api/school/respect/person?includeRelated=false ¡ •PÎ2026-10-05 19:00:56.016 [Test worker @coroutine#62] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46477/api/school/respect/person?includeRelated=false ¡ • �Ô2026-10-05 19:00:57.104 [Test worker @coroutine#90] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ • ‘ý2026-10-05 19:00:57.105 [Test worker @coroutine#90] INFO io.ktor.server.Application - Application started in 0.03 seconds. ¡ • ’Ê2026-10-05 19:00:57.106 [DefaultDispatcher-worker-1 @coroutine#99] INFO io.ktor.server.Application - Responding at http://0.0.0.0:43121 ¡ • “Ä2026-10-05 19:00:57.107 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43121/api/school/respect/schoolpermissiongrant ¡ • “Â2026-10-05 19:00:57.107 [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:43121/api/school/respect/schoolpermissiongrant. ¡ • •å2026-10-05 19:00:57.110 [eventLoopGroupProxy-16-1 @call-handler#102] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, schoolpermissiongrant] ¡ • •�/, segment:0 -> SUCCESS @ / ¡ • •¥ /api, segment:1 -> SUCCESS @ /api ¡ • •µ /api/school, segment:2 -> SUCCESS @ /api/school ¡ • •Ç /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect ¡ • •ï /api/school/respect/person, segment:3 -> FAILURE "Selector didn't match" @ /api/school/respect/person ¡ • •õ /api/school/respect/schoolpermissiongrant, segment:4 -> SUCCESS @ /api/school/respect/schoolpermissiongrant ¡ • •Õ /api/school/respect/schoolpermissiongrant [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/schoolpermissiongrant [(method:GET)] ¡ • •‘Matched routes: ¡ • •Õ "" -> "api" -> "school" -> "respect" -> "schoolpermissiongrant" -> "(method:GET)" ¡ • •™Routing resolve result: ¡ • •Æ SUCCESS @ /api/school/respect/schoolpermissiongrant [(method:GET)] ¡ • —í2026-10-05 19:00:57.112 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43121/api/school/respect/schoolpermissiongrant ¡ • ˜Â2026-10-05 19:00:57.112 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43121/api/school/respect/schoolpermissiongrant ¡ • ˜È2026-10-05 19:00:57.112 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43121/api/school/respect/schoolpermissiongrant ¡ • ˜Ø2026-10-05 19:00:57.113 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:43121/api/school/respect/schoolpermissiongrant. ¡ • ÅÁ2026-10-05 19:00:57.158 [DefaultDispatcher-worker-5 @coroutine#108] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43121/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 ¡ • Åÿ2026-10-05 19:00:57.158 [DefaultDispatcher-worker-5 @coroutine#108] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:43121/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. ¡ • ÇÖ2026-10-05 19:00:57.160 [eventLoopGroupProxy-16-1 @call-handler#112] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, person] ¡ • Ç�/, segment:0 -> SUCCESS @ / ¡ • Ç¥ /api, segment:1 -> SUCCESS @ /api ¡ • ǵ /api/school, segment:2 -> SUCCESS @ /api/school ¡ • ÇÇ /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect ¡ • Ç× /api/school/respect/person, segment:4 -> SUCCESS @ /api/school/respect/person ¡ • Ç÷ /api/school/respect/person [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/person [(method:GET)] ¡ • ÇÑ /api/school/respect/person [(method:POST)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/respect/person [(method:POST)] ¡ • ÇÍ /api/school/respect/schoolpermissiongrant, segment:3 -> FAILURE "Selector didn't match" @ /api/school/respect/schoolpermissiongrant ¡ • Ç‘Matched routes: ¡ • ÇÆ "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" ¡ • Ç™Routing resolve result: ¡ • Ç· SUCCESS @ /api/school/respect/person [(method:GET)] ¡ • Ëê2026-10-05 19:00:57.163 [DefaultDispatcher-worker-3 @coroutine#108] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43121/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 ¡ • Ëÿ2026-10-05 19:00:57.163 [DefaultDispatcher-worker-3 @coroutine#108] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43121/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 ¡ • ËÅ2026-10-05 19:00:57.163 [DefaultDispatcher-worker-3 @coroutine#108] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43121/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 ¡ • ÌÕ2026-10-05 19:00:57.164 [DefaultDispatcher-worker-3 @coroutine#108] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:43121/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. ¡ •Õ2026-10-05 19:00:58.247 [Test worker @coroutine#117] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ •ÿ2026-10-05 19:00:58.247 [Test worker @coroutine#117] INFO io.ktor.server.Application - Application started in 0.028 seconds. ¡ •Ë2026-10-05 19:00:58.249 [DefaultDispatcher-worker-3 @coroutine#126] INFO io.ktor.server.Application - Responding at http://0.0.0.0:42005 ¡ • Ë2026-10-05 19:00:58.251 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42005/api/school/respect/person?includeRelated=false ¡ • É2026-10-05 19:00:58.251 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:42005/api/school/respect/person?includeRelated=false. ¡ • Ö2026-10-05 19:00:58.253 [eventLoopGroupProxy-19-1 @call-handler#130] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, person] ¡ • �/, segment:0 -> SUCCESS @ / ¡ • ¥ /api, segment:1 -> SUCCESS @ /api ¡ • µ /api/school, segment:2 -> SUCCESS @ /api/school ¡ • Ç /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect ¡ • × /api/school/respect/person, segment:4 -> SUCCESS @ /api/school/respect/person ¡ • ÷ /api/school/respect/person [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/person [(method:GET)] ¡ • Ñ /api/school/respect/person [(method:POST)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/respect/person [(method:POST)] ¡ • ‘Matched routes: ¡ • Æ "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" ¡ • ™Routing resolve result: ¡ • · SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •ô2026-10-05 19:00:58.258 [Test worker @coroutine#117] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42005/api/school/respect/person?includeRelated=false ¡ •É2026-10-05 19:00:58.258 [Test worker @coroutine#117] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42005/api/school/respect/person?includeRelated=false ¡ •Ï2026-10-05 19:00:58.258 [Test worker @coroutine#117] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42005/api/school/respect/person?includeRelated=false ¡ •ß2026-10-05 19:00:58.258 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42005/api/school/respect/person?includeRelated=false. ¡ •8à0DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-10-05T15:00:58.175Z, stored=2026-10-05T15:00:58.286Z, metadata=null, userMasterIdentifier=null, username=null, givenName=Admin, familyName=User, middleName=null, gender=UNSPECIFIED, preferredFirstName=null, preferredMiddleName=null, preferredLastName=null, pronouns=null, roles=[PersonRole(isPrimaryRole=true, roleEnum=SYSTEM_ADMINISTRATOR, beginDate=null, endDate=null)], relatedPersonUids=[], dateOfBirth=null, email=null, phoneNumber=null)], metaInfo=DataLoadMetaInfo(lastModified=1791212458175, lastStored=1791212458286, etag=null, url=http://localhost:42005/api/school/respect/person?includeRelated=false, consistentThrough=2026-10-05T15:00:58.287495379Z, validationInfoKey=0, varyHeader=null, headers=null, permissionsLastModified=null), localState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-10-05T15:00:58.175Z, stored=2026-10-05T15:00:58.286Z, metadata=null, userMasterIdentifier=null, username=null, givenName=Admin, familyName=User, middleName=null, gender=UNSPECIFIED, preferredFirstName=null, preferredMiddleName=null, preferredLastName=null, pronouns=null, roles=[PersonRole(isPrimaryRole=true, roleEnum=SYSTEM_ADMINISTRATOR, beginDate=null, endDate=null)], relatedPersonUids=[], dateOfBirth=null, email=null, phoneNumber=null)], metaInfo=DataLoadMetaInfo(lastModified=1791212458175, lastStored=1791212458286, etag=null, url=null, consistentThrough=2026-10-05T15:00:58.287495379Z, validationInfoKey=0, varyHeader=null, headers=null, permissionsLastModified=null), localState=null, remoteState=null), remoteState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-10-05T15:00:58.175Z, stored=2026-10-05T15:00:58.218Z, metadata=null, userMasterIdentifier=null, username=null, givenName=Admin, familyName=User, middleName=null, gender=UNSPECIFIED, preferredFirstName=null, preferredMiddleName=null, preferredLastName=null, pronouns=null, roles=[PersonRole(isPrimaryRole=true, roleEnum=SYSTEM_ADMINISTRATOR, beginDate=null, endDate=null)], relatedPersonUids=[], dateOfBirth=null, email=null, phoneNumber=null), Person(guid=42, userActive=true, status=ACTIVE, lastModified=2026-10-05T15:00:58.173Z, stored=2026-10-05T15:00:58.249Z, metadata=null, userMasterIdentifier=null, username=test, givenName=test, familyName=test, middleName=null, gender=FEMALE, preferredFirstName=null, preferredMiddleName=null, preferredLastName=null, pronouns=null, roles=[PersonRole(isPrimaryRole=true, roleEnum=SITE_ADMINISTRATOR, beginDate=null, endDate=null)], relatedPersonUids=[], dateOfBirth=null, email=null, phoneNumber=null)], metaInfo=DataLoadMetaInfo(lastModified=1791212458000, lastStored=-1, etag=null, url=http://localhost:42005/api/school/respect/person?includeRelated=false, consistentThrough=2026-10-05T15:00:58.254405127Z, validationInfoKey=7092960727423664838, varyHeader=Authorization, headers=io.ktor.client.engine.okhttp.OkUtilsKt$fromOkHttp$1@5501d1c8, permissionsLastModified=null), localState=null, remoteState=null)) ¡ •:ô2026-10-05 19:00:58.298 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42005/api/school/respect/person?since=2026-10-05T15%3A00%3A58.254405127Z&includeRelated=false ¡ •:ò2026-10-05 19:00:58.298 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:42005/api/school/respect/person?since=2026-10-05T15%3A00%3A58.254405127Z&includeRelated=false. ¡ •;Ö2026-10-05 19:00:58.300 [eventLoopGroupProxy-19-1 @call-handler#136] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, person] ¡ •;�/, segment:0 -> SUCCESS @ / ¡ •;¥ /api, segment:1 -> SUCCESS @ /api ¡ •;µ /api/school, segment:2 -> SUCCESS @ /api/school ¡ •;Ç /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect ¡ •;× /api/school/respect/person, segment:4 -> SUCCESS @ /api/school/respect/person ¡ •;÷ /api/school/respect/person [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •;Ñ /api/school/respect/person [(method:POST)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/respect/person [(method:POST)] ¡ •;‘Matched routes: ¡ •;Æ "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" ¡ •;™Routing resolve result: ¡ •;· SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •=Ý2026-10-05 19:00:58.302 [Test worker @coroutine#117] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42005/api/school/respect/person?since=2026-10-05T15%3A00%3A58.254405127Z&includeRelated=false ¡ •=ò2026-10-05 19:00:58.302 [Test worker @coroutine#117] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42005/api/school/respect/person?since=2026-10-05T15%3A00%3A58.254405127Z&includeRelated=false ¡ •=ø2026-10-05 19:00:58.302 [Test worker @coroutine#117] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42005/api/school/respect/person?since=2026-10-05T15%3A00%3A58.254405127Z&includeRelated=false ¡ •>È2026-10-05 19:00:58.302 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42005/api/school/respect/person?since=2026-10-05T15%3A00%3A58.254405127Z&includeRelated=false. ¡ •?—Run time: 54.099265ms ¡ •lÕ2026-10-05 19:00:59.373 [Test worker @coroutine#142] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ •mÿ2026-10-05 19:00:59.373 [Test worker @coroutine#142] INFO io.ktor.server.Application - Application started in 0.027 seconds. ¡ •nË2026-10-05 19:00:59.374 [DefaultDispatcher-worker-5 @coroutine#151] INFO io.ktor.server.Application - Responding at http://0.0.0.0:37881 ¡ •›Å2026-10-05 19:00:59.419 [DefaultDispatcher-worker-3 @coroutine#149] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:37881/api/school/respect/person ¡ •›Á2026-10-05 19:00:59.419 [DefaultDispatcher-worker-3 @coroutine#149] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@6b7d3c86 for http://localhost:37881/api/school/respect/person ¡ •›Ã2026-10-05 19:00:59.420 [DefaultDispatcher-worker-3 @coroutine#149] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:37881/api/school/respect/person from class io.ktor.http.content.TextContent ¡ •žÖ2026-10-05 19:00:59.423 [eventLoopGroupProxy-22-1 @call-handler#160] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, person] ¡ •ž�/, segment:0 -> SUCCESS @ / ¡ •ž¥ /api, segment:1 -> SUCCESS @ /api ¡ •žµ /api/school, segment:2 -> SUCCESS @ /api/school ¡ •žÇ /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect ¡ •ž× /api/school/respect/person, segment:4 -> SUCCESS @ /api/school/respect/person ¡ •žÏ /api/school/respect/person [(method:GET)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/respect/person [(method:GET)] ¡ •žù /api/school/respect/person [(method:POST)], segment:4 -> SUCCESS @ /api/school/respect/person [(method:POST)] ¡ •ž‘Matched routes: ¡ •žÇ "" -> "api" -> "school" -> "respect" -> "person" -> "(method:POST)" ¡ •ž™Routing resolve result: ¡ •ž¸ SUCCESS @ /api/school/respect/person [(method:POST)] ¡ •¢þ2026-10-05 19:00:59.426 [eventLoopGroupProxy-22-1 @call-handler#160] TRACE i.k.server.engine.DefaultTransform - No Default Transformations found for class io.ktor.utils.io.ByteChannel and expected type TypeInfo(kotlin.collections.List) for call /api/school/respect/person ¡ •£À2026-10-05 19:00:59.427 [eventLoopGroupProxy-22-1 @call-handler#160] TRACE i.k.s.p.c.ContentNegotiation - Skipping response body transformation from HttpStatusCode to OutgoingContent for the POST /api/school/respect/person request because the HttpStatusCode type is ignored. See [ContentNegotiationConfig::ignoreType]. ¡ •¤î2026-10-05 19:00:59.428 [DefaultDispatcher-worker-2 @coroutine#149] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:37881/api/school/respect/person ¡ •¤Ã2026-10-05 19:00:59.428 [DefaultDispatcher-worker-2 @coroutine#149] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:37881/api/school/respect/person ¡ •¤É2026-10-05 19:00:59.428 [DefaultDispatcher-worker-2 @coroutine#149] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:37881/api/school/respect/person ¡ •ÓÕ2026-10-05 19:01:00.499 [Test worker @coroutine#165] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ •Ôÿ2026-10-05 19:01:00.500 [Test worker @coroutine#165] INFO io.ktor.server.Application - Application started in 0.028 seconds. ¡ •ÖË2026-10-05 19:01:00.502 [DefaultDispatcher-worker-3 @coroutine#174] INFO io.ktor.server.Application - Responding at http://0.0.0.0:33293 ¡ •×Å2026-10-05 19:01:00.503 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33293/api/school/respect/schoolpermissiongrant ¡ •×Ã2026-10-05 19:01:00.503 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:33293/api/school/respect/schoolpermissiongrant. ¡ •Ùå2026-10-05 19:01:00.505 [eventLoopGroupProxy-25-1 @call-handler#177] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, schoolpermissiongrant] ¡ •Ù�/, segment:0 -> SUCCESS @ / ¡ •Ù¥ /api, segment:1 -> SUCCESS @ /api ¡ •Ùµ /api/school, segment:2 -> SUCCESS @ /api/school ¡ •ÙÇ /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect ¡ •Ùï /api/school/respect/person, segment:3 -> FAILURE "Selector didn't match" @ /api/school/respect/person ¡ •Ùõ /api/school/respect/schoolpermissiongrant, segment:4 -> SUCCESS @ /api/school/respect/schoolpermissiongrant ¡ •ÙÕ /api/school/respect/schoolpermissiongrant [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/schoolpermissiongrant [(method:GET)] ¡ •Ù‘Matched routes: ¡ •ÙÕ "" -> "api" -> "school" -> "respect" -> "schoolpermissiongrant" -> "(method:GET)" ¡ •Ù™Routing resolve result: ¡ •ÙÆ SUCCESS @ /api/school/respect/schoolpermissiongrant [(method:GET)] ¡ •Ûî2026-10-05 19:01:00.507 [Test worker @coroutine#165] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33293/api/school/respect/schoolpermissiongrant ¡ •ÛÃ2026-10-05 19:01:00.507 [Test worker @coroutine#165] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33293/api/school/respect/schoolpermissiongrant ¡ •ÛÉ2026-10-05 19:01:00.507 [Test worker @coroutine#165] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33293/api/school/respect/schoolpermissiongrant ¡ •ÛÙ2026-10-05 19:01:00.507 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33293/api/school/respect/schoolpermissiongrant. ¡ •Ë2026-10-05 19:01:00.544 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33293/api/school/respect/person?includeRelated=false ¡ •É2026-10-05 19:01:00.544 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:33293/api/school/respect/person?includeRelated=false. ¡ •Ö2026-10-05 19:01:00.546 [eventLoopGroupProxy-25-1 @call-handler#183] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, person] ¡ •�/, segment:0 -> SUCCESS @ / ¡ •¥ /api, segment:1 -> SUCCESS @ /api ¡ •µ /api/school, segment:2 -> SUCCESS @ /api/school ¡ •Ç /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect ¡ •× /api/school/respect/person, segment:4 -> SUCCESS @ /api/school/respect/person ¡ •÷ /api/school/respect/person [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •Ñ /api/school/respect/person [(method:POST)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/respect/person [(method:POST)] ¡ •Í /api/school/respect/schoolpermissiongrant, segment:3 -> FAILURE "Selector didn't match" @ /api/school/respect/schoolpermissiongrant ¡ •‘Matched routes: ¡ •Æ "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" ¡ •™Routing resolve result: ¡ •· SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •ÉOct 05, 2026 7:01:00 PM io.github.aakira.napier.DebugAntilog performLog ¡ •ÀFINE: [DEBUG] MapPagingSource$load - MapPagingSource(tag=PersonDataSourceDb/listAsPagingSource(params=GetListParams(common=GetListCommonParams(guid=null, searchQuery=null, since=null, sinceIfPermissionsNotChangedSince=null, includeDeleted=null), filterByClazzUid=null, filterByEnrolmentRole=null, filterByName=null, filterByPersonStatus=null, filterByPersonRole=null, includeRelated=false, inClassOnDay=null))): load Refresh(key=0, loadSize=1000) ¡ •ô2026-10-05 19:01:00.551 [Test worker @coroutine#165] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33293/api/school/respect/person?includeRelated=false ¡ •É2026-10-05 19:01:00.551 [Test worker @coroutine#165] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33293/api/school/respect/person?includeRelated=false ¡ •Ï2026-10-05 19:01:00.551 [Test worker @coroutine#165] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33293/api/school/respect/person?includeRelated=false ¡ •ß2026-10-05 19:01:00.552 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33293/api/school/respect/person?includeRelated=false. ¡ •!ÜÉOct 05, 2026 7:01:02 PM io.github.aakira.napier.DebugAntilog performLog ¡ •!ÜæFINE: [DEBUG] DelegatedInvalidationPagingSource$onSrcInvalidated - RPaging/DelegatedInvalidationPagingSource(extra=PersonDataSourceDb/listAsPagingSource(params=GetListParams(common=GetListCommonParams(guid=null, searchQuery=null, since=null, sinceIfPermissionsNotChangedSince=null, includeDeleted=null), filterByClazzUid=null, filterByEnrolmentRole=null, filterByName=null, filterByPersonStatus=null, filterByPersonRole=null, includeRelated=false, inClassOnDay=null))) src invalidated ¡ •!ÝÉOct 05, 2026 7:01:02 PM io.github.aakira.napier.DebugAntilog performLog ¡ •!ÝÛFINE: [DEBUG] HttpRequestBuilderExtKt$addCacheValidationHeaders - addCacheValidationHeaders = If-Modified-Since = Mon, 05 Oct 2026 15:01:00 GMT for http://localhost:33293/api/school/respect/person?includeRelated=false ¡ •!ÝË2026-10-05 19:01:02.557 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33293/api/school/respect/person?includeRelated=false ¡ •!ÝÉ2026-10-05 19:01:02.558 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:33293/api/school/respect/person?includeRelated=false. ¡ •!ßÖ2026-10-05 19:01:02.559 [eventLoopGroupProxy-25-1 @call-handler#190] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, person] ¡ •!ß�/, segment:0 -> SUCCESS @ / ¡ •!ߥ /api, segment:1 -> SUCCESS @ /api ¡ •!ßµ /api/school, segment:2 -> SUCCESS @ /api/school ¡ •!ßÇ /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect ¡ •!ß× /api/school/respect/person, segment:4 -> SUCCESS @ /api/school/respect/person ¡ •!ß÷ /api/school/respect/person [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •!ßÑ /api/school/respect/person [(method:POST)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/respect/person [(method:POST)] ¡ •!ßÍ /api/school/respect/schoolpermissiongrant, segment:3 -> FAILURE "Selector didn't match" @ /api/school/respect/schoolpermissiongrant ¡ •!ß‘Matched routes: ¡ •!߯ "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" ¡ •!ß™Routing resolve result: ¡ •!ß· SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •!ßÉOct 05, 2026 7:01:02 PM io.github.aakira.napier.DebugAntilog performLog ¡ •!ßÀFINE: [DEBUG] MapPagingSource$load - MapPagingSource(tag=PersonDataSourceDb/listAsPagingSource(params=GetListParams(common=GetListCommonParams(guid=null, searchQuery=null, since=null, sinceIfPermissionsNotChangedSince=null, includeDeleted=null), filterByClazzUid=null, filterByEnrolmentRole=null, filterByName=null, filterByPersonStatus=null, filterByPersonRole=null, includeRelated=false, inClassOnDay=null))): load Refresh(key=0, loadSize=1000) ¡ •!áô2026-10-05 19:01:02.561 [Test worker @coroutine#165] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33293/api/school/respect/person?includeRelated=false ¡ •!áÉ2026-10-05 19:01:02.562 [Test worker @coroutine#165] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33293/api/school/respect/person?includeRelated=false ¡ •!áÏ2026-10-05 19:01:02.562 [Test worker @coroutine#165] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33293/api/school/respect/person?includeRelated=false ¡ •!âß2026-10-05 19:01:02.562 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33293/api/school/respect/person?includeRelated=false. ¡ •&Õ2026-10-05 19:01:03.643 [Test worker @coroutine#196] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ •&ÿ2026-10-05 19:01:03.644 [Test worker @coroutine#196] INFO io.ktor.server.Application - Application started in 0.028 seconds. ¡ •&Ë2026-10-05 19:01:03.645 [DefaultDispatcher-worker-2 @coroutine#205] INFO io.ktor.server.Application - Responding at http://0.0.0.0:44461 ¡ •&Ë2026-10-05 19:01:03.647 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44461/api/school/respect/person?includeRelated=false ¡ •&É2026-10-05 19:01:03.647 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:44461/api/school/respect/person?includeRelated=false. ¡ •&!Ö2026-10-05 19:01:03.649 [eventLoopGroupProxy-28-1 @call-handler#209] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, person] ¡ •&!�/, segment:0 -> SUCCESS @ / ¡ •&!¥ /api, segment:1 -> SUCCESS @ /api ¡ •&!µ /api/school, segment:2 -> SUCCESS @ /api/school ¡ •&!Ç /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect ¡ •&!× /api/school/respect/person, segment:4 -> SUCCESS @ /api/school/respect/person ¡ •&!÷ /api/school/respect/person [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •&!Ñ /api/school/respect/person [(method:POST)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/respect/person [(method:POST)] ¡ •&!‘Matched routes: ¡ •&!Æ "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" ¡ •&!™Routing resolve result: ¡ •&!· SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •&!ÉOct 05, 2026 7:01:03 PM io.github.aakira.napier.DebugAntilog performLog ¡ •&"ÀFINE: [DEBUG] MapPagingSource$load - MapPagingSource(tag=PersonDataSourceDb/listAsPagingSource(params=GetListParams(common=GetListCommonParams(guid=null, searchQuery=null, since=null, sinceIfPermissionsNotChangedSince=null, includeDeleted=null), filterByClazzUid=null, filterByEnrolmentRole=null, filterByName=null, filterByPersonStatus=null, filterByPersonRole=null, includeRelated=false, inClassOnDay=null))): load Refresh(key=0, loadSize=1000) ¡ •&$ô2026-10-05 19:01:03.653 [Test worker @coroutine#196] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44461/api/school/respect/person?includeRelated=false ¡ •&$É2026-10-05 19:01:03.653 [Test worker @coroutine#196] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44461/api/school/respect/person?includeRelated=false ¡ •&$Ï2026-10-05 19:01:03.653 [Test worker @coroutine#196] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44461/api/school/respect/person?includeRelated=false ¡ •&%ß2026-10-05 19:01:03.654 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:44461/api/school/respect/person?includeRelated=false. ¡ •&Bà0DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-10-05T15:01:03.569Z, stored=2026-10-05T15:01:03.681Z, metadata=null, userMasterIdentifier=null, username=null, givenName=Admin, familyName=User, middleName=null, gender=UNSPECIFIED, preferredFirstName=null, preferredMiddleName=null, preferredLastName=null, pronouns=null, roles=[PersonRole(isPrimaryRole=true, roleEnum=SYSTEM_ADMINISTRATOR, beginDate=null, endDate=null)], relatedPersonUids=[], dateOfBirth=null, email=null, phoneNumber=null)], metaInfo=DataLoadMetaInfo(lastModified=1791212463569, lastStored=1791212463681, etag=null, url=http://localhost:44461/api/school/respect/person?includeRelated=false, consistentThrough=2026-10-05T15:01:03.681816513Z, validationInfoKey=0, varyHeader=null, headers=null, permissionsLastModified=null), localState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-10-05T15:01:03.569Z, stored=2026-10-05T15:01:03.681Z, metadata=null, userMasterIdentifier=null, username=null, givenName=Admin, familyName=User, middleName=null, gender=UNSPECIFIED, preferredFirstName=null, preferredMiddleName=null, preferredLastName=null, pronouns=null, roles=[PersonRole(isPrimaryRole=true, roleEnum=SYSTEM_ADMINISTRATOR, beginDate=null, endDate=null)], relatedPersonUids=[], dateOfBirth=null, email=null, phoneNumber=null)], metaInfo=DataLoadMetaInfo(lastModified=1791212463569, lastStored=1791212463681, etag=null, url=null, consistentThrough=2026-10-05T15:01:03.681816513Z, validationInfoKey=0, varyHeader=null, headers=null, permissionsLastModified=null), localState=null, remoteState=null), remoteState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-10-05T15:01:03.569Z, stored=2026-10-05T15:01:03.615Z, metadata=null, userMasterIdentifier=null, username=null, givenName=Admin, familyName=User, middleName=null, gender=UNSPECIFIED, preferredFirstName=null, preferredMiddleName=null, preferredLastName=null, pronouns=null, roles=[PersonRole(isPrimaryRole=true, roleEnum=SYSTEM_ADMINISTRATOR, beginDate=null, endDate=null)], relatedPersonUids=[], dateOfBirth=null, email=null, phoneNumber=null), Person(guid=42, userActive=true, status=ACTIVE, lastModified=2026-10-05T15:01:03.567Z, stored=2026-10-05T15:01:03.645Z, metadata=null, userMasterIdentifier=null, username=test, givenName=test, familyName=test, middleName=null, gender=FEMALE, preferredFirstName=null, preferredMiddleName=null, preferredLastName=null, pronouns=null, roles=[PersonRole(isPrimaryRole=true, roleEnum=SITE_ADMINISTRATOR, beginDate=null, endDate=null)], relatedPersonUids=[], dateOfBirth=null, email=null, phoneNumber=null)], metaInfo=DataLoadMetaInfo(lastModified=1791212463000, lastStored=-1, etag=null, url=http://localhost:44461/api/school/respect/person?includeRelated=false, consistentThrough=2026-10-05T15:01:03.650144040Z, validationInfoKey=7092960727423664838, varyHeader=Authorization, headers=io.ktor.client.engine.okhttp.OkUtilsKt$fromOkHttp$1@6c519e47, permissionsLastModified=null), localState=null, remoteState=null)) ¡ •&CÉOct 05, 2026 7:01:03 PM io.github.aakira.napier.DebugAntilog performLog ¡ •&CæFINE: [DEBUG] DelegatedInvalidationPagingSource$onSrcInvalidated - RPaging/DelegatedInvalidationPagingSource(extra=PersonDataSourceDb/listAsPagingSource(params=GetListParams(common=GetListCommonParams(guid=null, searchQuery=null, since=null, sinceIfPermissionsNotChangedSince=null, includeDeleted=null), filterByClazzUid=null, filterByEnrolmentRole=null, filterByName=null, filterByPersonStatus=null, filterByPersonRole=null, includeRelated=false, inClassOnDay=null))) src invalidated ¡ •&Dô2026-10-05 19:01:03.684 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44461/api/school/respect/person?since=2026-10-05T15%3A01%3A03.650144040Z&includeRelated=false ¡ •&Dò2026-10-05 19:01:03.685 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:44461/api/school/respect/person?since=2026-10-05T15%3A01%3A03.650144040Z&includeRelated=false. ¡ •&EÖ2026-10-05 19:01:03.686 [eventLoopGroupProxy-28-1 @call-handler#216] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, person] ¡ •&E�/, segment:0 -> SUCCESS @ / ¡ •&E¥ /api, segment:1 -> SUCCESS @ /api ¡ •&Eµ /api/school, segment:2 -> SUCCESS @ /api/school ¡ •&EÇ /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect ¡ •&E× /api/school/respect/person, segment:4 -> SUCCESS @ /api/school/respect/person ¡ •&E÷ /api/school/respect/person [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •&EÑ /api/school/respect/person [(method:POST)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/respect/person [(method:POST)] ¡ •&E‘Matched routes: ¡ •&EÆ "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" ¡ •&E™Routing resolve result: ¡ •&E· SUCCESS @ /api/school/respect/person [(method:GET)] ¡ •&FÉOct 05, 2026 7:01:03 PM io.github.aakira.napier.DebugAntilog performLog ¡ •&FÚFINE: [DEBUG] MapPagingSource$load - MapPagingSource(tag=PersonDataSourceDb/listAsPagingSource(params=GetListParams(common=GetListCommonParams(guid=null, searchQuery=null, since=2026-10-05T15:01:03.650144040Z, sinceIfPermissionsNotChangedSince=null, includeDeleted=null), filterByClazzUid=null, filterByEnrolmentRole=null, filterByName=null, filterByPersonStatus=null, filterByPersonRole=null, includeRelated=false, inClassOnDay=null))): load Refresh(key=0, loadSize=1000) ¡ •&GÝ2026-10-05 19:01:03.687 [Test worker @coroutine#196] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44461/api/school/respect/person?since=2026-10-05T15%3A01%3A03.650144040Z&includeRelated=false ¡ •&Gò2026-10-05 19:01:03.688 [Test worker @coroutine#196] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44461/api/school/respect/person?since=2026-10-05T15%3A01%3A03.650144040Z&includeRelated=false ¡ •&Gø2026-10-05 19:01:03.688 [Test worker @coroutine#196] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44461/api/school/respect/person?since=2026-10-05T15%3A01%3A03.650144040Z&includeRelated=false ¡ •&HÈ2026-10-05 19:01:03.688 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:44461/api/school/respect/person?since=2026-10-05T15%3A01%3A03.650144040Z&includeRelated=false. ¡ •&I—Run time: 43.563686ms ¡ •*tÕ2026-10-05 19:01:04.756 [Test worker @coroutine#222] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ •*uÿ2026-10-05 19:01:04.758 [Test worker @coroutine#222] INFO io.ktor.server.Application - Application started in 0.032 seconds. ¡ •*vË2026-10-05 19:01:04.759 [DefaultDispatcher-worker-4 @coroutine#232] INFO io.ktor.server.Application - Responding at http://0.0.0.0:42779 ¡ •*xÊ2026-10-05 19:01:04.760 [Test worker @coroutine#222] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42779/playlist/f6766d87-8202-467c-9765-8e5ea67fcc2d ¡ •*xÈ2026-10-05 19:01:04.760 [Test worker @coroutine#222] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:42779/playlist/f6766d87-8202-467c-9765-8e5ea67fcc2d. ¡ •*zè2026-10-05 19:01:04.762 [eventLoopGroupProxy-31-1 @call-handler#235] TRACE io.ktor.server.routing.Routing - Trace for [playlist, f6766d87-8202-467c-9765-8e5ea67fcc2d] ¡ •*z�/, segment:0 -> SUCCESS @ / ¡ •*z¯ /playlist, segment:1 -> SUCCESS @ /playlist ¡ •*zù /playlist/{uuid}, segment:2 -> SUCCESS; Parameters [uuid=[f6766d87-8202-467c-9765-8e5ea67fcc2d]] @ /playlist/{uuid} ¡ •*zß /playlist/{uuid} [(method:GET)], segment:2 -> SUCCESS @ /playlist/{uuid} [(method:GET)] ¡ •*zù /playlist/{uuid} [(method:POST)], segment:2 -> FAILURE "Selector didn't match" @ /playlist/{uuid} [(method:POST)] ¡ •*z‘Matched routes: ¡ •*z² "" -> "playlist" -> "{uuid}" -> "(method:GET)" ¡ •*z™Routing resolve result: ¡ •*zç SUCCESS; Parameters [uuid=[f6766d87-8202-467c-9765-8e5ea67fcc2d]] @ /playlist/{uuid} [(method:GET)] ¡ •*ó2026-10-05 19:01:04.768 [Test worker @coroutine#222] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42779/playlist/f6766d87-8202-467c-9765-8e5ea67fcc2d ¡ •*€È2026-10-05 19:01:04.768 [Test worker @coroutine#222] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42779/playlist/f6766d87-8202-467c-9765-8e5ea67fcc2d ¡ •*€Î2026-10-05 19:01:04.768 [Test worker @coroutine#222] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42779/playlist/f6766d87-8202-467c-9765-8e5ea67fcc2d ¡ •*€ð2026-10-05 19:01:04.769 [Test worker @coroutine#222] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:42779/playlist/f6766d87-8202-467c-9765-8e5ea67fcc2d. ¡ •.ÁÕ2026-10-05 19:01:05.858 [Test worker @coroutine#239] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ •.Âÿ2026-10-05 19:01:05.858 [Test worker @coroutine#239] INFO io.ktor.server.Application - Application started in 0.028 seconds. ¡ •.ÃË2026-10-05 19:01:05.859 [DefaultDispatcher-worker-3 @coroutine#248] INFO io.ktor.server.Application - Responding at http://0.0.0.0:40095 ¡ •.áÙ2026-10-05 19:01:05.889 [DefaultDispatcher-worker-4 @coroutine#246] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40095/playlist/0bc0d1b9-5599-4c43-949f-df22fc753206 ¡ •.áÕ2026-10-05 19:01:05.889 [DefaultDispatcher-worker-4 @coroutine#246] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@6bbccf00 for http://localhost:40095/playlist/0bc0d1b9-5599-4c43-949f-df22fc753206 ¡ •.á×2026-10-05 19:01:05.889 [DefaultDispatcher-worker-4 @coroutine#246] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:40095/playlist/0bc0d1b9-5599-4c43-949f-df22fc753206 from class io.ktor.http.content.TextContent ¡ •.ãè2026-10-05 19:01:05.891 [eventLoopGroupProxy-34-1 @call-handler#255] TRACE io.ktor.server.routing.Routing - Trace for [playlist, 0bc0d1b9-5599-4c43-949f-df22fc753206] ¡ •.ã�/, segment:0 -> SUCCESS @ / ¡ •.㯠/playlist, segment:1 -> SUCCESS @ /playlist ¡ •.ãù /playlist/{uuid}, segment:2 -> SUCCESS; Parameters [uuid=[0bc0d1b9-5599-4c43-949f-df22fc753206]] @ /playlist/{uuid} ¡ •.ã÷ /playlist/{uuid} [(method:GET)], segment:2 -> FAILURE "Selector didn't match" @ /playlist/{uuid} [(method:GET)] ¡ •.ãá /playlist/{uuid} [(method:POST)], segment:2 -> SUCCESS @ /playlist/{uuid} [(method:POST)] ¡ •.ã‘Matched routes: ¡ •.ã³ "" -> "playlist" -> "{uuid}" -> "(method:POST)" ¡ •.ã™Routing resolve result: ¡ •.ãè SUCCESS; Parameters [uuid=[0bc0d1b9-5599-4c43-949f-df22fc753206]] @ /playlist/{uuid} [(method:POST)] ¡ •.ãó2026-10-05 19:01:05.892 [eventLoopGroupProxy-34-1 @call-handler#255] TRACE i.k.server.engine.DefaultTransform - No Default Transformations found for class io.ktor.utils.io.ByteChannel and expected type TypeInfo(world.respect.lib.opds.model.OpdsFeed) for call /playlist/0bc0d1b9-5599-4c43-949f-df22fc753206 ¡ •.äÔ2026-10-05 19:01:05.893 [eventLoopGroupProxy-34-1 @call-handler#255] TRACE i.k.s.p.c.ContentNegotiation - Skipping response body transformation from HttpStatusCode to OutgoingContent for the POST /playlist/0bc0d1b9-5599-4c43-949f-df22fc753206 request because the HttpStatusCode type is ignored. See [ContentNegotiationConfig::ignoreType]. ¡ •.åÂ2026-10-05 19:01:05.893 [DefaultDispatcher-worker-3 @coroutine#246] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40095/playlist/0bc0d1b9-5599-4c43-949f-df22fc753206 ¡ •.å×2026-10-05 19:01:05.894 [DefaultDispatcher-worker-3 @coroutine#246] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40095/playlist/0bc0d1b9-5599-4c43-949f-df22fc753206 ¡ •.åÝ2026-10-05 19:01:05.894 [DefaultDispatcher-worker-3 @coroutine#246] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40095/playlist/0bc0d1b9-5599-4c43-949f-df22fc753206 ¡ •3 Õ2026-10-05 19:01:06.976 [Test worker @coroutine#260] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ •3!þ2026-10-05 19:01:06.978 [Test worker @coroutine#260] INFO io.ktor.server.Application - Application started in 0.03 seconds. ¡ •3#Ë2026-10-05 19:01:06.979 [DefaultDispatcher-worker-5 @coroutine#269] INFO io.ktor.server.Application - Responding at http://0.0.0.0:45621 ¡ •3iÆ2026-10-05 19:01:07.050 [DefaultDispatcher-worker-3 @coroutine#267] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45621/api/school/xapi/statements ¡ •3jÂ2026-10-05 19:01:07.051 [DefaultDispatcher-worker-3 @coroutine#267] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@70f76e62 for http://localhost:45621/api/school/xapi/statements ¡ •3jÄ2026-10-05 19:01:07.051 [DefaultDispatcher-worker-3 @coroutine#267] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:45621/api/school/xapi/statements from class io.ktor.http.content.TextContent ¡ •3m×2026-10-05 19:01:07.054 [eventLoopGroupProxy-37-1 @call-handler#278] TRACE io.ktor.server.routing.Routing - Trace for [api, school, xapi, statements] ¡ •3m�/, segment:0 -> SUCCESS @ / ¡ •3m¥ /api, segment:1 -> SUCCESS @ /api ¡ •3mµ /api/school, segment:2 -> SUCCESS @ /api/school ¡ •3mÁ /api/school/xapi, segment:3 -> SUCCESS @ /api/school/xapi ¡ •3mÙ /api/school/xapi/statements, segment:4 -> SUCCESS @ /api/school/xapi/statements ¡ •3mÑ /api/school/xapi/statements [(method:GET)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/xapi/statements [(method:GET)] ¡ •3mû /api/school/xapi/statements [(method:POST)], segment:4 -> SUCCESS @ /api/school/xapi/statements [(method:POST)] ¡ •3m‘Matched routes: ¡ •3mÈ "" -> "api" -> "school" -> "xapi" -> "statements" -> "(method:POST)" ¡ •3m™Routing resolve result: ¡ •3m¹ SUCCESS @ /api/school/xapi/statements [(method:POST)] ¡ •3má2026-10-05 19:01:07.054 [eventLoopGroupProxy-37-1 @call-handler#278] TRACE i.k.server.engine.DefaultTransform - No Default Transformations found for class io.ktor.utils.io.ByteChannel and expected type TypeInfo(kotlinx.serialization.json.JsonElement) for call /api/school/xapi/statements ¡ •3zÑ2026-10-05 19:01:07.067 [DefaultDispatcher-worker-2 @coroutine#267] TRACE i.k.client.plugins.HttpCallValidator - Processing exception java.io.IOException: unexpected end of stream on http://localhost:45621/... for request http://localhost:45621/api/school/xapi/statements ¡ •3|ÍException in thread "DefaultDispatcher-worker-2 @coroutine#267" java.io.IOException: unexpected end of stream on http://localhost:45621/... ¡ •3|¶ at _COROUTINE._BOUNDARY._(CoroutineDebugging.kt:42) ¡ •3|× at io.ktor.client.engine.okhttp.OkHttpEngine.executeHttpRequest(OkHttpEngine.kt:107) ¡ •3|Ë at io.ktor.client.engine.okhttp.OkHttpEngine.execute(OkHttpEngine.kt:71) ¡ •3|î at io.ktor.client.engine.HttpClientEngine$executeWithinCallContext$2.invokeSuspend(HttpClientEngine.kt:183) ¡ •3|Þ at io.ktor.client.engine.HttpClientEngine.executeWithinCallContext(HttpClientEngine.kt:184) ¡ •3}Ý at io.ktor.client.engine.HttpClientEngine$install$1.invokeSuspend(HttpClientEngine.kt:154) ¡ •3}Ø at io.ktor.util.pipeline.DebugPipelineContext.proceedLoop(DebugPipelineContext.kt:79) ¡ •3}Ì at io.ktor.client.plugins.HttpSend$DefaultSender.execute(HttpSend.kt:141) ¡ •3}ß at io.ktor.client.plugins.HttpRedirectKt$HttpRedirect$2$1.invokeSuspend(HttpRedirect.kt:110) ¡ •3}Ð at io.ktor.client.plugins.api.Send$install$1.invokeSuspend(CommonHooks.kt:52) ¡ •3}î at io.ktor.client.plugins.HttpCallValidatorKt$HttpCallValidator$2$2.invokeSuspend(HttpCallValidator.kt:143) ¡ •3}Ð at io.ktor.client.plugins.api.Send$install$1.invokeSuspend(CommonHooks.kt:52) ¡ •3}Õ at io.ktor.client.plugins.HttpSend$Plugin$install$1.invokeSuspend(HttpSend.kt:107) ¡ •3}Ø at io.ktor.util.pipeline.DebugPipelineContext.proceedLoop(DebugPipelineContext.kt:79) ¡ •3}ë at io.ktor.client.plugins.DefaultTransformKt$defaultTransformers$1.invokeSuspend(DefaultTransform.kt:64) ¡ •3}Ø at io.ktor.util.pipeline.DebugPipelineContext.proceedLoop(DebugPipelineContext.kt:79) ¡ •3}é at io.ktor.client.plugins.api.TransformRequestBodyHook$install$1.invokeSuspend(KtorCallContexts.kt:88) ¡ •3}Ø at io.ktor.util.pipeline.DebugPipelineContext.proceedLoop(DebugPipelineContext.kt:79) ¡ •3}Û at io.ktor.client.plugins.RequestError$install$1.invokeSuspend(HttpCallValidator.kt:165) ¡ •3}Ø at io.ktor.util.pipeline.DebugPipelineContext.proceedLoop(DebugPipelineContext.kt:79) ¡ •3}ö at io.ktor.client.plugins.HttpRequestLifecycleKt$HttpRequestLifecycle$1$1.invokeSuspend(HttpRequestLifecycle.kt:29) ¡ •3}ä at io.ktor.client.plugins.SetupRequestContext$install$1.invokeSuspend(HttpRequestLifecycle.kt:42) ¡ •3}Ø at io.ktor.util.pipeline.DebugPipelineContext.proceedLoop(DebugPipelineContext.kt:79) ¡ •3}Ì at io.ktor.client.HttpClient.execute$ktor_client_core(HttpClient.kt:1420) ¡ •3}Ð at io.ktor.client.statement.HttpStatement.fetchResponse(HttpStatement.kt:207) ¡ •3}ñ at world.respect.datalayer.http.school.xapi.XapiStatementsResourceHttp.post(XapiStatementsResourceHttp.kt:129) ¡ •3} at world.respect.datalayer.repository.school.writequeue.DrainRemoteWriteQueueUseCase.invoke(DrainRemoteWriteQueueUseCase.kt:90) ¡ •3}Ì at world.respect.lib.test.clientservertest.ClientServerDataSourceTestBuilder$clients$1$2.invokeSuspend(ClientServerDatasourceTest.kt:280) ¡ •3}ñ Suppressed: kotlinx.coroutines.internal.DiagnosticCoroutineContextException: [CoroutineId(267), "coroutine#267":StandaloneCoroutine{Cancelling}@5c39c3f4, Dispatchers.Default] ¡ •3}ØCaused by: java.io.IOException: unexpected end of stream on http://localhost:45621/... ¡ •3}Þ at okhttp3.internal.http1.Http1ExchangeCodec.readResponseHeaders(Http1ExchangeCodec.kt:252) ¡ •3}Ï at okhttp3.internal.connection.Exchange.readResponseHeaders(Exchange.kt:116) ¡ •3}Ø at okhttp3.internal.http.CallServerInterceptor.intercept(CallServerInterceptor.kt:97) ¡ •3}Õ at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:332) ¡ •3}Ø at okhttp3.internal.connection.ConnectInterceptor.intercept(ConnectInterceptor.kt:34) ¡ •3}Õ at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:332) ¡ •3}Ï at okhttp3.internal.cache.CacheInterceptor.intercept(CacheInterceptor.kt:98) ¡ •3}Õ at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:332) ¡ •3}Ð at okhttp3.internal.http.BridgeInterceptor.intercept(BridgeInterceptor.kt:83) ¡ •3}Õ at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:332) ¡ •3}ä at okhttp3.internal.http.RetryAndFollowUpInterceptor.intercept(RetryAndFollowUpInterceptor.kt:72) ¡ •3}Õ at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:332) ¡ •3}â at okhttp3.internal.connection.RealCall.getResponseWithInterceptorChain$okhttp(RealCall.kt:232) ¡ •3}É at okhttp3.internal.connection.RealCall$AsyncCall.run(RealCall.kt:580) ¡ •3}ß at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) ¡ •3}ß at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) ¡ •3}µ at java.base/java.lang.Thread.run(Thread.java:840) ¡ •3~ÂCaused by: java.io.EOFException: \n not found: limit=0 content=… ¡ •3~Ë at okio.RealBufferedSource.readUtf8LineStrict(RealBufferedSource.kt:362) ¡ •3~È at okhttp3.internal.http1.HeadersReader.readLine(HeadersReader.kt:32) ¡ •3~Þ at okhttp3.internal.http1.Http1ExchangeCodec.readResponseHeaders(Http1ExchangeCodec.kt:215) ¡ •3~Ž ... 16 more ¡ •8Õ2026-10-05 19:01:08.250 [Test worker @coroutine#282] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. ¡ •8ÿ2026-10-05 19:01:08.251 [Test worker @coroutine#282] INFO io.ktor.server.Application - Application started in 0.029 seconds. ¡ •8Ë2026-10-05 19:01:08.252 [DefaultDispatcher-worker-1 @coroutine#291] INFO io.ktor.server.Application - Responding at http://0.0.0.0:36737 ¡ •8?Å2026-10-05 19:01:08.288 [Test worker @coroutine#282] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:36737/api/school/xapi/statements?statementId=6690e6c9-3ef0-4ed3-8b37-7f3964730bee&related_activities=false&related_agents=false&format=exact&attachments=false&ascending=false ¡ •8?Ã2026-10-05 19:01:08.288 [Test worker @coroutine#282] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:36737/api/school/xapi/statements?statementId=6690e6c9-3ef0-4ed3-8b37-7f3964730bee&related_activities=false&related_agents=false&format=exact&attachments=false&ascending=false. ¡ •8A×2026-10-05 19:01:08.290 [eventLoopGroupProxy-40-1 @call-handler#298] TRACE io.ktor.server.routing.Routing - Trace for [api, school, xapi, statements] ¡ •8A�/, segment:0 -> SUCCESS @ / ¡ •8A¥ /api, segment:1 -> SUCCESS @ /api ¡ •8Aµ /api/school, segment:2 -> SUCCESS @ /api/school ¡ •8AÁ /api/school/xapi, segment:3 -> SUCCESS @ /api/school/xapi ¡ •8AÙ /api/school/xapi/statements, segment:4 -> SUCCESS @ /api/school/xapi/statements ¡ •8Aù /api/school/xapi/statements [(method:GET)], segment:4 -> SUCCESS @ /api/school/xapi/statements [(method:GET)] ¡ •8AÓ /api/school/xapi/statements [(method:POST)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/xapi/statements [(method:POST)] ¡ •8A‘Matched routes: ¡ •8AÇ "" -> "api" -> "school" -> "xapi" -> "statements" -> "(method:GET)" ¡ •8A™Routing resolve result: ¡ •8A¸ SUCCESS @ /api/school/xapi/statements [(method:GET)] ¡ •8Eî2026-10-05 19:01:08.294 [Test worker @coroutine#282] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:36737/api/school/xapi/statements?statementId=6690e6c9-3ef0-4ed3-8b37-7f3964730bee&related_activities=false&related_agents=false&format=exact&attachments=false&ascending=false ¡ •8EÃ2026-10-05 19:01:08.294 [Test worker @coroutine#282] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:36737/api/school/xapi/statements?statementId=6690e6c9-3ef0-4ed3-8b37-7f3964730bee&related_activities=false&related_agents=false&format=exact&attachments=false&ascending=false ¡ •8EÉ2026-10-05 19:01:08.294 [Test worker @coroutine#282] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:36737/api/school/xapi/statements?statementId=6690e6c9-3ef0-4ed3-8b37-7f3964730bee&related_activities=false&related_agents=false&format=exact&attachments=false&ascending=false ¡ •8Fö2026-10-05 19:01:08.295 [Test worker @coroutine#282] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.xapi.model.XapiStatementResult for http://localhost:36737/api/school/xapi/statements?statementId=6690e6c9-3ef0-4ed3-8b37-7f3964730bee&related_activities=false&related_agents=false&format=exact&attachments=false&ascending=false.