14:38:25,444 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:38:25,444 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:38:25,445 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
14:38:25,447 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
14:38:25,484 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:38:25,484 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:38:25,496 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
14:38:25,496 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:38:25,505 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:38:25,505 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
14:38:25,507 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T12:38:25.269Z
14:38:25,507 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:38:25,507 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:38:25,509 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:38:25,513 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.log
14:38:25,513 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.log]
14:38:25,514 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:38:25,514 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:38:25,514 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:38:25,514 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:38:25,514 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
14:38:25,514 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
14:38:25,514 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 70 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066
CreateLearningSpaceRequest(url=http://37.27.227.212:8066/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8064
[Preparing Device] 004_003_multiple_submission_possible_for_student (0s)
TestServerController: stopping server on port: 8066
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8065 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8065/api/centralappconfig/learningspace/getAll ready
14:42:23,939 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
14:42:23,940 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
14:42:23,940 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
14:42:23,940 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
14:42:23,943 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
14:42:23,943 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
14:42:23,947 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
14:42:23,947 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:42:23,947 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:42:23,947 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:42:23,949 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
14:42:23,950 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
14:42:23,987 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:42:23,987 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:42:23,998 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
14:42:23,999 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:42:24,008 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:42:24,008 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
14:42:24,010 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T12:42:23.771Z
14:42:24,010 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:42:24,010 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:42:24,012 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:42:24,016 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.log
14:42:24,016 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.log]
14:42:24,017 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:42:24,017 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:42:24,017 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:42:24,017 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:42:24,017 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
14:42:24,017 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
14:42:24,017 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 70 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065
CreateLearningSpaceRequest(url=http://37.27.227.212:8065/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
[Preparing Device] 003_006_all_user_able_to_open_text_block (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8067 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8067/api/centralappconfig/learningspace/getAll ready
14:45:00,915 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
14:45:00,916 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
14:45:00,916 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
14:45:00,916 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
14:45:00,919 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
14:45:00,919 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
14:45:00,923 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
14:45:00,923 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:45:00,923 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:45:00,924 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:45:00,925 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
14:45:00,927 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
14:45:00,965 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:45:00,965 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:45:00,976 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
14:45:00,977 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:45:00,986 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:45:00,986 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
14:45:00,988 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T12:45:00.771Z
14:45:00,988 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:45:00,988 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:45:00,989 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:45:00,994 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067/log/ustad-server.log
14:45:00,994 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067/log/ustad-server.log]
14:45:00,994 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:45:00,994 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:45:00,995 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:45:00,995 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:45:00,995 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
14:45:00,995 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
14:45:00,995 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 72 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067
CreateLearningSpaceRequest(url=http://37.27.227.212:8067/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8067
[Preparing Device] 002_005_admin_grant_permissions_test (0s)
TestServerController: start server requested
TestServerController: attempt: 1 failed to start server - will try again: java.net.BindException: Address already in use
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8068 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8068/api/centralappconfig/learningspace/getAll ready
14:53:19,328 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
14:53:19,328 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
14:53:19,328 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
14:53:19,329 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
14:53:19,332 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
14:53:19,332 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
14:53:19,336 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
14:53:19,336 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:53:19,336 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:53:19,336 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:53:19,338 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
14:53:19,339 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
14:53:19,377 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:53:19,377 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:53:19,390 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
14:53:19,390 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:53:19,399 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:53:19,399 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
14:53:19,402 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T12:53:19.167Z
14:53:19,402 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:53:19,402 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:53:19,403 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:53:19,407 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068/log/ustad-server.log
14:53:19,408 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068/log/ustad-server.log]
14:53:19,408 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:53:19,408 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:53:19,408 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:53:19,408 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:53:19,408 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
14:53:19,409 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
14:53:19,409 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 73 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068
CreateLearningSpaceRequest(url=http://37.27.227.212:8068/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8068
[Preparing Device] 007_005_student_registered_by_admin_or_teacher_dont_need_parentConsent (0s)
TestServerController: stopping server on port: 8065
[Preparing Device] 001_001_admin_can_add_content_004-pdf (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8061 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8061/api/centralappconfig/learningspace/getAll ready
15:00:24,949 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:00:24,949 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:00:24,949 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:00:24,949 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:00:24,952 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:00:24,953 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:00:24,956 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:00:24,956 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:00:24,956 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:00:24,956 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:00:24,958 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:00:24,959 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:00:24,996 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:00:24,996 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:00:25,008 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:00:25,008 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:00:25,017 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:00:25,017 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:00:25,020 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:00:24.781Z
15:00:25,020 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:00:25,020 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:00:25,021 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:00:25,026 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061/log/ustad-server.log
15:00:25,026 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061/log/ustad-server.log]
15:00:25,026 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:00:25,026 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:00:25,027 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:00:25,027 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:00:25,027 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:00:25,027 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:00:25,027 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 71 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061
CreateLearningSpaceRequest(url=http://37.27.227.212:8061/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8060 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8060/api/centralappconfig/learningspace/getAll ready
15:00:56,831 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:00:56,831 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:00:56,831 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:00:56,831 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:00:56,834 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:00:56,834 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:00:56,838 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:00:56,838 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:00:56,839 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:00:56,839 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:00:56,840 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:00:56,842 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:00:56,879 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:00:56,879 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:00:56,892 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:00:56,892 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:00:56,901 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:00:56,901 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:00:56,903 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:00:56.667Z
15:00:56,903 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:00:56,903 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:00:56,904 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:00:56,909 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.log
15:00:56,909 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.log]
15:00:56,909 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:00:56,910 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:00:56,910 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:00:56,910 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:00:56,910 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:00:56,910 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:00:56,910 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 71 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060
CreateLearningSpaceRequest(url=http://37.27.227.212:8060/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8061
[Preparing Device] 007_004_username_validation_and_add_profile_pic (0s)
TestServerController: stopping server on port: 8060
[Preparing Device] 007_001_admin_enable_or_disable_user_registration (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8065 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8065/api/centralappconfig/learningspace/getAll ready
15:06:40,343 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:06:40,343 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:06:40,343 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:06:40,344 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:06:40,347 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:06:40,347 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:06:40,351 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:06:40,351 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:06:40,351 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:06:40,351 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:06:40,352 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:06:40,354 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:06:40,390 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:06:40,390 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:06:40,402 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:06:40,402 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:06:40,411 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:06:40,411 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:06:40,413 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:06:40.170Z
15:06:40,413 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:06:40,413 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:06:40,415 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:06:40,419 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.log
15:06:40,419 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.log]
15:06:40,420 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:06:40,420 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:06:40,420 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:06:40,420 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:06:40,420 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:06:40,420 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:06:40,420 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 69 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065
CreateLearningSpaceRequest(url=http://37.27.227.212:8065/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8066 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8066/api/centralappconfig/learningspace/getAll ready
15:08:39,897 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:08:39,897 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:08:39,897 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:08:39,898 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:08:39,901 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:08:39,901 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:08:39,905 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:08:39,905 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:08:39,905 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:08:39,906 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:08:39,907 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:08:39,909 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:08:39,949 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:08:39,949 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:08:39,962 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:08:39,962 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:08:39,971 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:08:39,971 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:08:39,974 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:08:39.750Z
15:08:39,974 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:08:39,974 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:08:39,975 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:08:39,980 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.log
15:08:39,980 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.log]
15:08:39,980 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:08:39,980 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:08:39,981 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:08:39,981 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:08:39,981 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:08:39,981 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:08:39,981 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 76 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066
CreateLearningSpaceRequest(url=http://37.27.227.212:8066/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8065
[Preparing Device] 001_003_admin_can_permanently_delete_the_content_002 (0s)
TestServerController: stopping server on port: 8066
[Preparing Device] 002_003_teacher_record_attendance (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8063 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8063/api/centralappconfig/learningspace/getAll ready
15:16:24,822 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:16:24,823 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:16:24,823 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:16:24,823 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:16:24,827 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:16:24,827 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:16:24,831 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:16:24,831 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:16:24,831 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:16:24,831 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:16:24,832 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:16:24,834 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:16:24,872 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:16:24,872 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:16:24,885 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:16:24,885 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:16:24,894 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:16:24,894 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:16:24,897 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:16:24.674Z
15:16:24,897 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:16:24,897 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:16:24,898 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:16:24,903 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063/log/ustad-server.log
15:16:24,903 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063/log/ustad-server.log]
15:16:24,903 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:16:24,903 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:16:24,904 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:16:24,904 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:16:24,904 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:16:24,904 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:16:24,904 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 73 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063
CreateLearningSpaceRequest(url=http://37.27.227.212:8063/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8067 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8067/api/centralappconfig/learningspace/getAll ready
15:17:34,541 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:17:34,542 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:17:34,542 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:17:34,542 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:17:34,546 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:17:34,546 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:17:34,550 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:17:34,550 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:17:34,550 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:17:34,550 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:17:34,551 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:17:34,553 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:17:34,589 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:17:34,589 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:17:34,601 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:17:34,601 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:17:34,610 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:17:34,610 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:17:34,613 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:17:34.374Z
15:17:34,613 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:17:34,613 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:17:34,614 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:17:34,618 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067/log/ustad-server.log
15:17:34,619 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067/log/ustad-server.log]
15:17:34,619 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:17:34,619 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:17:34,619 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:17:34,619 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:17:34,619 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:17:34,620 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:17:34,620 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 70 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8067
CreateLearningSpaceRequest(url=http://37.27.227.212:8067/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8067
[Preparing Device] 002_001_admin_add_new_course_and_teacher (0s)
TestServerController: stopping server on port: 8063
[Preparing Device] 003_003_add_content_as_block_in_course (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8066 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8066/api/centralappconfig/learningspace/getAll ready
15:22:36,387 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:22:36,387 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:22:36,387 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:22:36,388 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:22:36,390 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:22:36,391 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:22:36,394 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:22:36,394 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:22:36,394 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:22:36,394 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:22:36,395 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:22:36,397 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:22:36,433 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:22:36,433 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:22:36,445 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:22:36,445 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:22:36,454 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:22:36,454 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:22:36,456 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:22:36.222Z
15:22:36,456 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:22:36,456 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:22:36,458 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:22:36,462 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.log
15:22:36,462 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.log]
15:22:36,463 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:22:36,463 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:22:36,463 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:22:36,463 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:22:36,463 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:22:36,463 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:22:36,463 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 69 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066
CreateLearningSpaceRequest(url=http://37.27.227.212:8066/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8061 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8061/api/centralappconfig/learningspace/getAll ready
15:24:25,999 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:24:25,999 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:24:25,999 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:24:25,999 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:24:26,002 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:24:26,003 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:24:26,006 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:24:26,006 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:24:26,006 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:24:26,007 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:24:26,008 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:24:26,009 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:24:26,046 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:24:26,046 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:24:26,058 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:24:26,058 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:24:26,067 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:24:26,067 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:24:26,070 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:24:25.854Z
15:24:26,070 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:24:26,070 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:24:26,071 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:24:26,075 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061/log/ustad-server.log
15:24:26,076 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061/log/ustad-server.log]
15:24:26,076 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:24:26,076 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:24:26,076 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:24:26,076 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:24:26,076 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:24:26,077 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:24:26,077 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 71 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8061
CreateLearningSpaceRequest(url=http://37.27.227.212:8061/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8066
[Preparing Device] 007_007a_admin_send_group_invitation_to_join_course_via_username (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8062 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8062/api/centralappconfig/learningspace/getAll ready
15:39:34,361 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:39:34,362 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:39:34,362 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:39:34,362 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:39:34,365 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:39:34,365 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:39:34,368 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:39:34,368 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:39:34,368 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:39:34,369 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:39:34,370 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:39:34,371 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:39:34,407 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:39:34,408 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:39:34,419 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:39:34,420 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:39:34,429 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:39:34,429 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:39:34,431 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:39:34.202Z
15:39:34,431 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:39:34,431 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:39:34,432 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:39:34,437 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062/log/ustad-server.log
15:39:34,437 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062/log/ustad-server.log]
15:39:34,437 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:39:34,437 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:39:34,438 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:39:34,438 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:39:34,438 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:39:34,438 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:39:34,438 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 70 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062
CreateLearningSpaceRequest(url=http://37.27.227.212:8062/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8061
[Preparing Device] 004_001_assignment_creation_submission_grading (0s)
TestServerController: stopping server on port: 8062
[Preparing Device] 002_002_student_joining_course_using_code (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8064 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8064/api/centralappconfig/learningspace/getAll ready
15:43:46,267 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:43:46,268 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:43:46,268 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:43:46,268 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:43:46,271 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:43:46,271 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:43:46,274 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:43:46,274 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:43:46,274 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:43:46,275 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:43:46,276 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:43:46,277 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:43:46,313 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:43:46,313 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:43:46,325 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:43:46,325 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:43:46,334 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:43:46,334 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:43:46,337 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:43:46.118Z
15:43:46,337 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:43:46,337 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:43:46,338 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:43:46,342 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064/log/ustad-server.log
15:43:46,343 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064/log/ustad-server.log]
15:43:46,343 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:43:46,343 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:43:46,343 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:43:46,343 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:43:46,343 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:43:46,344 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:43:46,344 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 70 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064
CreateLearningSpaceRequest(url=http://37.27.227.212:8064/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8062 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8062/api/centralappconfig/learningspace/getAll ready
15:45:56,042 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:45:56,043 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:45:56,043 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:45:56,043 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:45:56,047 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:45:56,047 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:45:56,051 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:45:56,051 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:45:56,052 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:45:56,052 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:45:56,053 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:45:56,055 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:45:56,093 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:45:56,093 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:45:56,106 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:45:56,106 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:45:56,116 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:45:56,116 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:45:56,118 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:45:55.877Z
15:45:56,119 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:45:56,119 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:45:56,120 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:45:56,124 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062/log/ustad-server.log
15:45:56,125 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062/log/ustad-server.log]
15:45:56,125 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:45:56,125 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:45:56,125 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:45:56,125 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:45:56,125 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:45:56,126 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:45:56,126 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 74 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8062
CreateLearningSpaceRequest(url=http://37.27.227.212:8062/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8062
[Preparing Device] 005_002_users_can_add_post_on_discussion_board (0s)
TestServerController: stopping server on port: 8064
[Preparing Device] 007_006_admin_enable_or_disable_guest_login (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8064 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8064/api/centralappconfig/learningspace/getAll ready
15:54:23,014 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:54:23,015 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:54:23,015 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:54:23,015 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:54:23,018 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:54:23,018 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:54:23,023 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:54:23,023 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:54:23,023 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:54:23,023 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:54:23,025 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:54:23,026 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:54:23,063 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:54:23,063 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:54:23,075 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:54:23,076 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:54:23,085 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:54:23,085 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:54:23,087 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:54:22.869Z
15:54:23,088 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:54:23,088 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:54:23,089 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:54:23,093 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064/log/ustad-server.log
15:54:23,093 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064/log/ustad-server.log]
15:54:23,094 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:54:23,094 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:54:23,094 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:54:23,094 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:54:23,094 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:54:23,094 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:54:23,094 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 71 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8064
CreateLearningSpaceRequest(url=http://37.27.227.212:8064/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8060 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8060/api/centralappconfig/learningspace/getAll ready
15:56:21,810 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:56:21,810 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
15:56:21,810 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:56:21,811 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:56:21,814 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:56:21,814 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:56:21,817 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:56:21,817 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:56:21,818 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:56:21,818 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:56:21,819 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:56:21,821 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
15:56:21,856 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:56:21,856 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:56:21,868 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
15:56:21,868 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:56:21,877 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:56:21,877 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
15:56:21,880 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T13:56:21.667Z
15:56:21,880 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:56:21,880 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:56:21,881 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:56:21,886 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.log
15:56:21,886 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.log]
15:56:21,886 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:56:21,886 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:56:21,887 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:56:21,887 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:56:21,887 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
15:56:21,887 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
15:56:21,887 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 69 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060
CreateLearningSpaceRequest(url=http://37.27.227.212:8060/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8064
[Preparing Device] 004_005_assignment_grace_period_finished (0s)
TestServerController: stopping server on port: 8060
[Preparing Device] 005_001_add_discussionBoard (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8066 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8066/api/centralappconfig/learningspace/getAll ready
16:01:25,136 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:01:25,136 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - No custom configurators were discovered as a service.
16:01:25,136 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:01:25,137 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:01:25,139 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:01:25,139 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:01:25,143 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
16:01:25,143 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:01:25,143 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:01:25,143 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:01:25,144 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
16:01:25,146 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@236e3f4e - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
16:01:25,183 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:01:25,183 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:01:25,195 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1019298652 - No compression will be used
16:01:25,195 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1019298652 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:01:25,204 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@6bf0219d - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:01:25,204 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@6bf0219d - Roll-over at midnight.
16:01:25,207 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@6bf0219d - Setting initial period to 2025-10-14T14:01:25.017Z
16:01:25,207 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@6bf0219d - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:01:25,207 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@6bf0219d - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:01:25,208 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:01:25,213 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.log
16:01:25,213 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066/log/ustad-server.log]
16:01:25,213 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:01:25,213 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:01:25,214 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:01:25,214 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:01:25,214 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@dd0c991 - End of configuration.
16:01:25,214 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@5f16132a - Registering current configuration as safe fallback point
16:01:25,214 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 71 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8066
CreateLearningSpaceRequest(url=http://37.27.227.212:8066/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8060 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8060/api/centralappconfig/learningspace/getAll ready
16:03:51,221 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:03:51,222 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
16:03:51,222 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:03:51,222 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:03:51,226 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:03:51,226 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:03:51,230 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
16:03:51,230 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:03:51,230 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:03:51,230 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:03:51,232 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
16:03:51,234 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
16:03:51,272 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:03:51,272 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:03:51,284 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
16:03:51,285 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:03:51,294 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:03:51,294 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
16:03:51,296 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T14:03:51.077Z
16:03:51,296 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:03:51,296 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:03:51,297 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:03:51,302 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.log
16:03:51,302 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.log]
16:03:51,302 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:03:51,303 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:03:51,303 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:03:51,303 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:03:51,303 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
16:03:51,303 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
16:03:51,303 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 73 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060
CreateLearningSpaceRequest(url=http://37.27.227.212:8060/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8066
[Preparing Device] 007_002b_user_registration_above_age_13_join_learning_space_username_password (0s)
TestServerController: stopping server on port: 8060
[Preparing Device] 005_004_users_can_add_external_links_as_post_on_discussion_board (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8068 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8068/api/centralappconfig/learningspace/getAll ready
16:08:22,200 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:08:22,200 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
16:08:22,200 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:08:22,201 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:08:22,204 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:08:22,204 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:08:22,208 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
16:08:22,208 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:08:22,208 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:08:22,208 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:08:22,209 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
16:08:22,211 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
16:08:22,248 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:08:22,248 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:08:22,261 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
16:08:22,261 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:08:22,270 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:08:22,270 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
16:08:22,273 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T14:08:22.055Z
16:08:22,273 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:08:22,273 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:08:22,274 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:08:22,279 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068/log/ustad-server.log
16:08:22,279 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068/log/ustad-server.log]
16:08:22,279 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:08:22,279 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:08:22,280 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:08:22,280 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:08:22,280 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
16:08:22,280 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
16:08:22,280 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 72 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8068
CreateLearningSpaceRequest(url=http://37.27.227.212:8068/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: start server requested
TestServerController: attempt: 1 failed to start server - will try again: java.net.BindException: Address already in use
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8065 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8065/api/centralappconfig/learningspace/getAll ready
16:09:39,088 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:09:39,089 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - No custom configurators were discovered as a service.
16:09:39,089 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:09:39,089 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:09:39,092 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:09:39,092 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:09:39,095 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
16:09:39,095 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:09:39,096 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:09:39,096 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:09:39,097 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
16:09:39,099 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@236e3f4e - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
16:09:39,136 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:09:39,136 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:09:39,148 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1019298652 - No compression will be used
16:09:39,148 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1019298652 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:09:39,157 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@6bf0219d - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:09:39,157 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@6bf0219d - Roll-over at midnight.
16:09:39,160 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@6bf0219d - Setting initial period to 2025-10-14T14:09:38.956Z
16:09:39,160 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@6bf0219d - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:09:39,160 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@6bf0219d - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:09:39,161 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:09:39,166 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.log
16:09:39,166 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.log]
16:09:39,166 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:09:39,166 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:09:39,166 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:09:39,166 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:09:39,166 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@dd0c991 - End of configuration.
16:09:39,167 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@5f16132a - Registering current configuration as safe fallback point
16:09:39,167 |-INFO in ch.qos.logback.classic.util.ContextInitializer@71c3b41 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 71 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065
CreateLearningSpaceRequest(url=http://37.27.227.212:8065/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8068
[Preparing Device] 001_001_admin_can_add_content_001-h5p (0s)
TestServerController: stopping server on port: 8065
[Preparing Device] 001_004_admin_can_move_content (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8065 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8065/api/centralappconfig/learningspace/getAll ready
16:14:56,385 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:14:56,386 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
16:14:56,386 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:14:56,386 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:14:56,390 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:14:56,390 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:14:56,395 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
16:14:56,395 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:14:56,395 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:14:56,395 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:14:56,397 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
16:14:56,399 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
16:14:56,437 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:14:56,437 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:14:56,449 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
16:14:56,449 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:14:56,458 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:14:56,458 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
16:14:56,460 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T14:14:56.239Z
16:14:56,461 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:14:56,461 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:14:56,462 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:14:56,466 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.log
16:14:56,466 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065/log/ustad-server.log]
16:14:56,467 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:14:56,467 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:14:56,467 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:14:56,467 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:14:56,467 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
16:14:56,467 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
16:14:56,467 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 72 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8065
CreateLearningSpaceRequest(url=http://37.27.227.212:8065/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8063 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8063/api/centralappconfig/learningspace/getAll ready
16:17:24,125 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:17:24,126 |-INFO in ch.qos.logback.classic.util.ContextInitializer@3943a2be - No custom configurators were discovered as a service.
16:17:24,126 |-INFO in ch.qos.logback.classic.util.ContextInitializer@3943a2be - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:17:24,127 |-INFO in ch.qos.logback.classic.util.ContextInitializer@3943a2be - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:17:24,132 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:17:24,132 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:17:24,140 |-INFO in ch.qos.logback.classic.util.ContextInitializer@3943a2be - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 5 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
16:17:24,140 |-INFO in ch.qos.logback.classic.util.ContextInitializer@3943a2be - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:17:24,140 |-INFO in ch.qos.logback.classic.util.ContextInitializer@3943a2be - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:17:24,142 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:17:24,147 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
16:17:24,153 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@343570b7 - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
16:17:24,272 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:17:24,272 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:17:24,303 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@360207322 - No compression will be used
16:17:24,304 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@360207322 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:17:24,324 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@71c3b41 - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:17:24,325 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@71c3b41 - Roll-over at midnight.
16:17:24,330 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@71c3b41 - Setting initial period to 2025-10-14T14:17:23.840Z
16:17:24,331 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@71c3b41 - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:17:24,331 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@71c3b41 - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:17:24,334 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:17:24,346 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063/log/ustad-server.log
16:17:24,346 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063/log/ustad-server.log]
16:17:24,347 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:17:24,347 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:17:24,348 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:17:24,348 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:17:24,348 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@236e3f4e - End of configuration.
16:17:24,348 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@3cc1435c - Registering current configuration as safe fallback point
16:17:24,348 |-INFO in ch.qos.logback.classic.util.ContextInitializer@3943a2be - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 208 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8063
CreateLearningSpaceRequest(url=http://37.27.227.212:8063/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8063
TestServerController: stopping server on port: 8065
[Preparing Device] 001_001_admin_can_add_content_003-video (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log -jar build/libs/ustad-server-all.jar runserver -config=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../app-ktor-server/src/main/resources/application.conf -P:ktor.deployment.port=8060 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8060/api/centralappconfig/learningspace/getAll ready
16:23:25,708 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:23:25,708 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - No custom configurators were discovered as a service.
16:23:25,708 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:23:25,709 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:23:25,711 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:23:25,712 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:23:25,715 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
16:23:25,715 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:23:25,715 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:23:25,715 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:23:25,717 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
16:23:25,718 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@157853da - URL [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml] is not of type file
16:23:25,754 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:23:25,754 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:23:25,767 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - No compression will be used
16:23:25,767 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@119290689 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:23:25,777 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:23:25,777 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Roll-over at midnight.
16:23:25,779 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - Setting initial period to 2025-10-14T14:23:25.561Z
16:23:25,779 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:23:25,779 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@236e3f4e - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:23:25,781 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:23:25,785 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.log
16:23:25,785 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060/log/ustad-server.log]
16:23:25,786 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:23:25,786 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:23:25,786 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:23:25,786 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:23:25,786 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3cc1435c - End of configuration.
16:23:25,786 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6bf0219d - Registering current configuration as safe fallback point
16:23:25,786 |-INFO in ch.qos.logback.classic.util.ContextInitializer@343570b7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 71 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY
DataDir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8060
CreateLearningSpaceRequest(url=http://37.27.227.212:8060/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8060
32/32 Flows Passed
==== View details in the console ====
https://app.robintest.com/project/proj_01jjbq65hrff8a3jkm7v2s0ty8/maestro-test/app/app_01jjbx8fgxf4fbbfm8gwh990zz/upload/mupload_01k7h9xpwje9f8gkgf171p902n
Process will exit with code 0 (SUCCESS)
[htmlpublisher] Archiving HTML reports...
Recording test results
[Checks API] No suitable checks publisher found.
[Slack Notifications] found #702 as previous completed, non-aborted build
Finished: SUCCESS
