14:40:42,606 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:40:42,606 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:40:42,606 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:40:42,608 |-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:40:42,609 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:40:42,651 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:40:42,651 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:40:42,663 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
14:40:42,663 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8702/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:40:42,672 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8702/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:40:42,672 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
14:40:42,675 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T12:40:42.431Z
14:40:42,675 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:40:42,675 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:40:42,676 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:40:42,680 |-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-8702/log/ustad-server.log
14:40:42,681 |-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-8702/log/ustad-server.log]
14:40:42,681 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:40:42,681 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:40:42,681 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:40:42,681 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:40:42,681 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
14:40:42,682 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
14:40:42,682 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8702
CreateLearningSpaceRequest(url=http://37.27.227.212:8702/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8264
[Preparing Device] 001_001_admin_can_add_content_004-pdf (0s)
TestServerController: stopping server on port: 8702
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-8676/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=8676 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8676 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8676/api/centralappconfig/learningspace/getAll ready
14:45:42,840 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
14:45:42,841 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
14:45:42,841 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
14:45:42,841 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
14:45:42,844 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
14:45:42,845 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
14:45:42,848 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
14:45:42,848 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:45:42,849 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:45:42,849 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:45:42,850 |-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:42,852 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:42,889 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:45:42,889 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:45:42,901 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
14:45:42,901 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8676/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:45:42,910 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8676/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:45:42,910 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
14:45:42,913 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T12:45:42.673Z
14:45:42,913 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:45:42,913 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:45:42,914 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:45:42,920 |-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-8676/log/ustad-server.log
14:45:42,920 |-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-8676/log/ustad-server.log]
14:45:42,920 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:45:42,921 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:45:42,921 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:45:42,921 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:45:42,921 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
14:45:42,921 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
14:45:42,921 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8676
CreateLearningSpaceRequest(url=http://37.27.227.212:8676/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
[Preparing Device] 004_003_multiple_submission_possible_for_student (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-8474/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=8474 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8474 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8474/api/centralappconfig/learningspace/getAll ready
14:47:28,578 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
14:47:28,578 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
14:47:28,579 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
14:47:28,579 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
14:47:28,582 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
14:47:28,582 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
14:47:28,586 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
14:47:28,586 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:47:28,586 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:47:28,587 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:47:28,588 |-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:47:28,590 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:47:28,627 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:47:28,627 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:47:28,639 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
14:47:28,639 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8474/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:47:28,648 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8474/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:47:28,648 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
14:47:28,650 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T12:47:28.412Z
14:47:28,650 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:47:28,650 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:47:28,652 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:47:28,656 |-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-8474/log/ustad-server.log
14:47:28,656 |-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-8474/log/ustad-server.log]
14:47:28,656 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:47:28,657 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:47:28,657 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:47:28,657 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:47:28,657 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
14:47:28,657 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
14:47:28,657 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8474
CreateLearningSpaceRequest(url=http://37.27.227.212:8474/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8676
[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-8992/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=8992 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8992 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8992/api/centralappconfig/learningspace/getAll ready
14:52:15,234 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
14:52:15,235 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
14:52:15,235 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
14:52:15,235 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
14:52:15,238 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
14:52:15,238 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
14:52:15,242 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
14:52:15,242 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:52:15,242 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:52:15,242 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:52:15,244 |-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:52:15,245 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:52:15,281 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:52:15,281 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:52:15,294 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
14:52:15,294 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8992/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:52:15,303 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8992/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:52:15,303 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
14:52:15,305 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T12:52:15.069Z
14:52:15,305 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:52:15,305 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:52:15,307 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:52:15,311 |-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-8992/log/ustad-server.log
14:52:15,311 |-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-8992/log/ustad-server.log]
14:52:15,312 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:52:15,312 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:52:15,312 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:52:15,312 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:52:15,312 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
14:52:15,312 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
14:52:15,312 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8992
CreateLearningSpaceRequest(url=http://37.27.227.212:8992/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8992
[Preparing Device] 004_001_assignment_creation_submission_grading (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-8098/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=8098 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8098 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8098/api/centralappconfig/learningspace/getAll ready
15:00:06,683 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:00:06,683 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:00:06,683 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:00:06,683 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:00:06,687 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:00:06,687 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:00:06,691 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:00:06,691 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:00:06,691 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:00:06,691 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:00:06,693 |-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:06,695 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:06,734 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:00:06,734 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:00:06,746 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:00:06,746 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8098/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:00:06,755 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8098/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:00:06,755 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:00:06,757 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:00:06.517Z
15:00:06,758 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:00:06,758 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:00:06,759 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:00:06,763 |-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-8098/log/ustad-server.log
15:00:06,763 |-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-8098/log/ustad-server.log]
15:00:06,764 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:00:06,764 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:00:06,764 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:00:06,764 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:00:06,764 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:00:06,764 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:00:06,764 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8098
CreateLearningSpaceRequest(url=http://37.27.227.212:8098/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8474
[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-8456/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=8456 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8456 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8456/api/centralappconfig/learningspace/getAll ready
15:05:44,938 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:05:44,938 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:05:44,938 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:05:44,939 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:05:44,942 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:05:44,942 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:05:44,946 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:05:44,946 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:05:44,946 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:05:44,947 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:05:44,948 |-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:05:44,950 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:05:44,991 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:05:44,991 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:05:45,003 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:05:45,003 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8456/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:05:45,013 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8456/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:05:45,013 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:05:45,015 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:05:44.771Z
15:05:45,015 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:05:45,015 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:05:45,017 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:05:45,021 |-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-8456/log/ustad-server.log
15:05:45,021 |-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-8456/log/ustad-server.log]
15:05:45,021 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:05:45,022 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:05:45,022 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:05:45,022 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:05:45,022 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:05:45,022 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:05:45,022 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8456
CreateLearningSpaceRequest(url=http://37.27.227.212:8456/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8098
[Preparing Device] 004_004_assignment_after_deadline_and_before_grace_Period (0s)
TestServerController: stopping server on port: 8456
[Preparing Device] 005_002_users_can_add_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-8206/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=8206 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8206 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8206/api/centralappconfig/learningspace/getAll ready
15:11:59,947 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:11:59,948 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:11:59,948 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:11:59,948 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:11:59,951 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:11:59,951 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:11:59,955 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:11:59,955 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:11:59,956 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:11:59,956 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:11:59,957 |-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:11:59,959 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:12:00,000 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:12:00,000 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:12:00,013 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:12:00,013 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8206/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:12:00,022 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8206/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:12:00,022 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:12:00,024 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:11:59.803Z
15:12:00,025 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:12:00,025 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:12:00,026 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:12:00,030 |-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-8206/log/ustad-server.log
15:12:00,030 |-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-8206/log/ustad-server.log]
15:12:00,031 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:12:00,031 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:12:00,031 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:12:00,031 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:12:00,031 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:12:00,031 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:12:00,031 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 75 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-8206
CreateLearningSpaceRequest(url=http://37.27.227.212:8206/, 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-8583/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=8583 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8583 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8583/api/centralappconfig/learningspace/getAll ready
15:12:59,191 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:12:59,191 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:12:59,192 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:12:59,192 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:12:59,195 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:12:59,195 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:12:59,199 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:12:59,199 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:12:59,200 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:12:59,200 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:12:59,201 |-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:12:59,203 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:12:59,243 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:12:59,243 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:12:59,255 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:12:59,256 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8583/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:12:59,266 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8583/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:12:59,266 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:12:59,269 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:12:59.032Z
15:12:59,269 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:12:59,269 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:12:59,270 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:12:59,276 |-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-8583/log/ustad-server.log
15:12:59,276 |-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-8583/log/ustad-server.log]
15:12:59,277 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:12:59,277 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:12:59,277 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:12:59,277 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:12:59,277 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:12:59,278 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:12:59,278 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 78 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-8583
CreateLearningSpaceRequest(url=http://37.27.227.212:8583/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8583
[Preparing Device] 003_005_course_view_and_modules_can_expand_collapse (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-8604/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=8604 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8604 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8604/api/centralappconfig/learningspace/getAll ready
15:19:05,857 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:19:05,857 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:19:05,857 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:19:05,858 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:19:05,861 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:19:05,861 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:19:05,865 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:19:05,865 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:19:05,865 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:19:05,866 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:19:05,867 |-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:19:05,869 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:19:05,910 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:19:05,910 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:19:05,922 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:19:05,923 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8604/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:19:05,932 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8604/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:19:05,932 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:19:05,934 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:19:05.713Z
15:19:05,934 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:19:05,934 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:19:05,935 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:19:05,940 |-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-8604/log/ustad-server.log
15:19:05,940 |-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-8604/log/ustad-server.log]
15:19:05,940 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:19:05,940 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:19:05,941 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:19:05,941 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:19:05,941 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:19:05,941 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:19:05,941 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8604
CreateLearningSpaceRequest(url=http://37.27.227.212:8604/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8206
[Preparing Device] 007_004_username_validation_and_add_profile_pic (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-8175/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=8175 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8175 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8175/api/centralappconfig/learningspace/getAll ready
15:21:43,461 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:21:43,461 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:21:43,461 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:21:43,462 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:21:43,465 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:21:43,465 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:21:43,469 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:21:43,469 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:21:43,469 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:21:43,469 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:21:43,471 |-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:21:43,472 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:21:43,510 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:21:43,510 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:21:43,523 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:21:43,523 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8175/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:21:43,532 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8175/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:21:43,532 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:21:43,535 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:21:43.319Z
15:21:43,535 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:21:43,535 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:21:43,536 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:21:43,540 |-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-8175/log/ustad-server.log
15:21:43,541 |-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-8175/log/ustad-server.log]
15:21:43,541 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:21:43,541 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:21:43,541 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:21:43,541 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:21:43,541 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:21:43,542 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:21:43,542 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8175
CreateLearningSpaceRequest(url=http://37.27.227.212:8175/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8604
[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-8427/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=8427 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8427 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8427/api/centralappconfig/learningspace/getAll ready
15:25:00,569 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:25:00,569 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:25:00,569 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:25:00,570 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:25:00,573 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:25:00,573 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:25:00,577 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:25:00,577 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:25:00,577 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:25:00,578 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:25:00,579 |-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:25:00,581 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:25:00,619 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:25:00,619 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:25:00,630 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:25:00,631 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8427/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:25:00,639 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8427/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:25:00,639 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:25:00,642 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:25:00.401Z
15:25:00,642 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:25:00,642 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:25:00,643 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:25:00,648 |-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-8427/log/ustad-server.log
15:25:00,648 |-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-8427/log/ustad-server.log]
15:25:00,648 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:25:00,648 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:25:00,649 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:25:00,649 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:25:00,649 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:25:00,649 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:25:00,649 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8427
CreateLearningSpaceRequest(url=http://37.27.227.212:8427/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8427
[Preparing Device] 005_005_users_can_delete_post_on_discussion_board (0s)
TestServerController: stopping server on port: 8175
[Preparing Device] 004_005_assignment_grace_period_finished (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-8771/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=8771 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8771 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8771/api/centralappconfig/learningspace/getAll ready
15:30:13,093 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:30:13,093 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:30:13,093 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:30:13,094 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:30:13,097 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:30:13,097 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:30:13,101 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:30:13,101 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:30:13,101 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:30:13,101 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:30:13,103 |-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:30:13,105 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:30:13,144 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:30:13,144 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:30:13,156 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:30:13,157 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8771/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:30:13,167 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8771/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:30:13,167 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:30:13,169 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:30:12.940Z
15:30:13,169 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:30:13,169 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:30:13,170 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:30:13,175 |-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-8771/log/ustad-server.log
15:30:13,175 |-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-8771/log/ustad-server.log]
15:30:13,176 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:30:13,176 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:30:13,176 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:30:13,176 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:30:13,176 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:30:13,176 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:30:13,176 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 75 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-8771
CreateLearningSpaceRequest(url=http://37.27.227.212:8771/, 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-8324/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=8324 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8324 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8324/api/centralappconfig/learningspace/getAll ready
15:30:48,980 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:30:48,980 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:30:48,980 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:30:48,981 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:30:48,984 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:30:48,984 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:30:48,989 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:30:48,989 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:30:48,989 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:30:48,989 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:30:48,991 |-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:30:48,993 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:30:49,034 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:30:49,034 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:30:49,046 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:30:49,047 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8324/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:30:49,056 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8324/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:30:49,056 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:30:49,059 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:30:48.836Z
15:30:49,059 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:30:49,059 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:30:49,060 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:30:49,065 |-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-8324/log/ustad-server.log
15:30:49,065 |-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-8324/log/ustad-server.log]
15:30:49,065 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:30:49,065 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:30:49,065 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:30:49,066 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:30:49,066 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:30:49,066 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:30:49,066 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 77 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-8324
CreateLearningSpaceRequest(url=http://37.27.227.212:8324/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8324
[Preparing Device] 001_001_admin_can_add_content_002-epub (0s)
TestServerController: stopping server on port: 8771
[Preparing Device] 001_003_admin_can_permanently_delete_the_content_002 (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-8017/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=8017 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8017 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8017/api/centralappconfig/learningspace/getAll ready
15:36:54,092 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:36:54,093 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:36:54,093 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:36:54,093 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:36:54,096 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:36:54,096 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:36:54,100 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:36:54,100 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:36:54,100 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:36:54,100 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:36:54,102 |-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:36:54,104 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:36:54,140 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:36:54,140 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:36:54,152 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:36:54,153 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8017/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:36:54,162 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8017/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:36:54,162 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:36:54,164 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:36:53.924Z
15:36:54,164 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:36:54,164 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:36:54,166 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:36:54,170 |-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-8017/log/ustad-server.log
15:36:54,170 |-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-8017/log/ustad-server.log]
15:36:54,171 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:36:54,171 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:36:54,171 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:36:54,171 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:36:54,171 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:36:54,171 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:36:54,171 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8017
CreateLearningSpaceRequest(url=http://37.27.227.212:8017/, 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-8348/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=8348 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8348 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8348/api/centralappconfig/learningspace/getAll ready
15:37:50,920 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:37:50,920 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:37:50,920 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:37:50,920 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:37:50,924 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:37:50,924 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:37:50,928 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:37:50,928 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:37:50,928 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:37:50,928 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:37:50,930 |-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:37:50,932 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:37:50,972 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:37:50,973 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:37:50,985 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:37:50,985 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8348/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:37:50,994 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8348/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:37:50,994 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:37:50,996 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:37:50.775Z
15:37:50,996 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:37:50,996 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:37:50,998 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:37:51,002 |-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-8348/log/ustad-server.log
15:37:51,002 |-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-8348/log/ustad-server.log]
15:37:51,002 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:37:51,003 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:37:51,003 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:37:51,003 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:37:51,003 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:37:51,003 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:37:51,003 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 75 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-8348
CreateLearningSpaceRequest(url=http://37.27.227.212:8348/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8017
[Preparing Device] 001_001_admin_can_add_content_001-h5p (0s)
TestServerController: stopping server on port: 8348
[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-8688/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=8688 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8688 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8688/api/centralappconfig/learningspace/getAll ready
15:43:56,835 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:43:56,836 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:43:56,836 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:43:56,836 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:43:56,839 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:43:56,839 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:43:56,843 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:43:56,843 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:43:56,843 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:43:56,843 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:43:56,845 |-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:56,846 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:56,883 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:43:56,883 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:43:56,895 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:43:56,896 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8688/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:43:56,905 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8688/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:43:56,905 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:43:56,907 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:43:56.668Z
15:43:56,907 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:43:56,907 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:43:56,908 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:43:56,913 |-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-8688/log/ustad-server.log
15:43:56,913 |-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-8688/log/ustad-server.log]
15:43:56,914 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:43:56,914 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:43:56,914 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:43:56,914 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:43:56,914 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:43:56,914 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:43:56,914 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8688
CreateLearningSpaceRequest(url=http://37.27.227.212:8688/, 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-8878/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=8878 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8878 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8878/api/centralappconfig/learningspace/getAll ready
15:45:01,790 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:45:01,790 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:45:01,790 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:45:01,791 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:45:01,794 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:45:01,794 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:45:01,798 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:45:01,798 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:45:01,798 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:45:01,799 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:45:01,800 |-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:01,802 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:01,843 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:45:01,843 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:45:01,855 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:45:01,855 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8878/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:45:01,864 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8878/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:45:01,864 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:45:01,867 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:45:01.645Z
15:45:01,867 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:45:01,867 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:45:01,868 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:45:01,872 |-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-8878/log/ustad-server.log
15:45:01,872 |-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-8878/log/ustad-server.log]
15:45:01,873 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:45:01,873 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:45:01,873 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:45:01,873 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:45:01,873 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:45:01,873 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:45:01,873 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 75 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-8878
CreateLearningSpaceRequest(url=http://37.27.227.212:8878/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8688
[Preparing Device] 001_003_admin_can_delete_and_restore_content_001 (0s)
TestServerController: stopping server on port: 8878
[Preparing Device] 003_001_add_or_edit_course_permission_test (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-8014/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=8014 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8014 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8014/api/centralappconfig/learningspace/getAll ready
15:51:20,162 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:51:20,162 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:51:20,162 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:51:20,162 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:51:20,166 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:51:20,166 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:51:20,170 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:51:20,170 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:51:20,170 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:51:20,170 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:51:20,172 |-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:51:20,174 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:51:20,215 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:51:20,215 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:51:20,228 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:51:20,228 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8014/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:51:20,237 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8014/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:51:20,237 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:51:20,239 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:51:20.017Z
15:51:20,239 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:51:20,239 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:51:20,241 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:51:20,245 |-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-8014/log/ustad-server.log
15:51:20,245 |-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-8014/log/ustad-server.log]
15:51:20,246 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:51:20,246 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:51:20,246 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:51:20,246 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:51:20,246 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:51:20,246 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:51:20,246 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8014
CreateLearningSpaceRequest(url=http://37.27.227.212:8014/, 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-8524/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=8524 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8524 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8524/api/centralappconfig/learningspace/getAll ready
15:52:16,074 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:52:16,074 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:52:16,074 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:52:16,075 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:52:16,078 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:52:16,078 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:52:16,082 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:52:16,082 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:52:16,082 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:52:16,082 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:52:16,084 |-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:52:16,085 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:52:16,122 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:52:16,123 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:52:16,134 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:52:16,135 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8524/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:52:16,144 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8524/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:52:16,144 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:52:16,146 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:52:15.911Z
15:52:16,146 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:52:16,146 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:52:16,147 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:52:16,152 |-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-8524/log/ustad-server.log
15:52:16,152 |-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-8524/log/ustad-server.log]
15:52:16,152 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:52:16,153 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:52:16,153 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:52:16,153 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:52:16,153 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:52:16,153 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:52:16,153 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8524
CreateLearningSpaceRequest(url=http://37.27.227.212:8524/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8524
[Preparing Device] 007_002b_user_registration_above_age_13_join_learning_space_username_password (0s)
TestServerController: stopping server on port: 8014
[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-8256/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=8256 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8256 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8256/api/centralappconfig/learningspace/getAll ready
15:56:58,321 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:56:58,321 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:56:58,321 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:56:58,322 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:56:58,325 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:56:58,325 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:56:58,329 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:56:58,329 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:56:58,330 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:56:58,330 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:56:58,331 |-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:58,333 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:58,374 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:56:58,374 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:56:58,387 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:56:58,387 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8256/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:56:58,397 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8256/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:56:58,397 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:56:58,400 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:56:58.174Z
15:56:58,400 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:56:58,400 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:56:58,401 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:56:58,406 |-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-8256/log/ustad-server.log
15:56:58,406 |-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-8256/log/ustad-server.log]
15:56:58,406 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:56:58,406 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:56:58,407 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:56:58,407 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:56:58,407 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:56:58,407 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:56:58,407 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 77 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-8256
CreateLearningSpaceRequest(url=http://37.27.227.212:8256/, 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-8288/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=8288 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8288 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8288/api/centralappconfig/learningspace/getAll ready
15:58:33,561 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:58:33,561 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:58:33,561 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:58:33,562 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:58:33,565 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:58:33,565 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:58:33,568 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
15:58:33,568 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:58:33,568 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:58:33,569 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:58:33,570 |-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:58:33,572 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:58:33,608 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:58:33,608 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:58:33,620 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:58:33,620 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8288/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:58:33,629 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8288/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:58:33,629 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:58:33,632 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T13:58:33.393Z
15:58:33,632 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:58:33,632 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:58:33,633 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:58:33,637 |-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-8288/log/ustad-server.log
15:58:33,638 |-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-8288/log/ustad-server.log]
15:58:33,638 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:58:33,638 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:58:33,638 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:58:33,638 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:58:33,638 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:58:33,639 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:58:33,639 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8288
CreateLearningSpaceRequest(url=http://37.27.227.212:8288/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8256
[Preparing Device] 001_001_admin_can_add_content_005_using_link (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-8970/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=8970 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8970 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8970/api/centralappconfig/learningspace/getAll ready
16:02:24,254 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:02:24,254 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - No custom configurators were discovered as a service.
16:02:24,255 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:02:24,255 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:02:24,258 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:02:24,258 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:02:24,261 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
16:02:24,261 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:02:24,261 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:02:24,261 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:02:24,263 |-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:02:24,264 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@3cc1435c - 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:02:24,300 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:02:24,300 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:02:24,311 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1810899357 - No compression will be used
16:02:24,312 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1810899357 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8970/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:02:24,321 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - 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-8970/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:02:24,321 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - Roll-over at midnight.
16:02:24,323 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - Setting initial period to 2025-07-26T14:02:24.097Z
16:02:24,323 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:02:24,323 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:02:24,324 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:02:24,329 |-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-8970/log/ustad-server.log
16:02:24,329 |-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-8970/log/ustad-server.log]
16:02:24,329 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:02:24,329 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:02:24,330 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:02:24,330 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:02:24,330 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@5f16132a - End of configuration.
16:02:24,330 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@69fb6037 - Registering current configuration as safe fallback point
16:02:24,330 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - 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-8970
CreateLearningSpaceRequest(url=http://37.27.227.212:8970/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8288
[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-8689/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=8689 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8689 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8689/api/centralappconfig/learningspace/getAll ready
16:05:34,294 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:05:34,295 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
16:05:34,295 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:05:34,295 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:05:34,298 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:05:34,298 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:05:34,302 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
16:05:34,302 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:05:34,302 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:05:34,302 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:05:34,304 |-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:05:34,306 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:05:34,344 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:05:34,344 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:05:34,356 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
16:05:34,356 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8689/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:05:34,365 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8689/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:05:34,365 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
16:05:34,368 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T14:05:34.124Z
16:05:34,368 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:05:34,368 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:05:34,370 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:05:34,375 |-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-8689/log/ustad-server.log
16:05:34,375 |-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-8689/log/ustad-server.log]
16:05:34,375 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:05:34,375 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:05:34,376 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:05:34,376 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:05:34,376 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
16:05:34,376 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
16:05:34,376 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - 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-8689
CreateLearningSpaceRequest(url=http://37.27.227.212:8689/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8970
[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-8466/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=8466 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8466 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8466/api/centralappconfig/learningspace/getAll ready
16:07:52,992 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:07:52,992 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
16:07:52,992 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:07:52,993 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:07:52,996 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:07:52,996 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:07:53,000 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 3 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
16:07:53,000 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:07:53,000 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:07:53,001 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:07:53,002 |-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:07:53,004 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@71c3b41 - 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:07:53,046 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:07:53,046 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:07:53,059 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
16:07:53,059 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - Will use the pattern /home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8466/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:07:53,068 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - 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-8466/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:07:53,068 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
16:07:53,070 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-26T14:07:52.825Z
16:07:53,070 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:07:53,070 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:07:53,072 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:07:53,076 |-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-8466/log/ustad-server.log
16:07:53,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-8466/log/ustad-server.log]
16:07:53,076 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:07:53,077 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:07:53,077 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:07:53,077 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:07:53,077 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
16:07:53,077 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
16:07:53,077 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 77 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-8466
CreateLearningSpaceRequest(url=http://37.27.227.212:8466/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8689
TestServerController: stopping server on port: 8466
32/32 Flows Passed
==== View details in the console ====
https://app.robintest.com/project/proj_01jjbq65hrff8a3jkm7v2s0ty8/maestro-test/app/app_01jjbx8fgxf4fbbfm8gwh990zz/upload/mupload_01k13a444gfnhbe3h7gmmr286b
Process will exit with code 0 (SUCCESS)
[htmlpublisher] Archiving HTML reports...
Recording test results
[Checks API] No suitable checks publisher found.
[Slack Notifications] found #604 as previous completed, non-aborted build
Finished: SUCCESS
![[Jenkins]](/jenkins/static/feafc6d9/images/svgs/logo.svg){kind=logo}
