14:40:19,623 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:40:19,623 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:40:19,623 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:40:19,625 |-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:19,626 |-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:19,668 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:40:19,668 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:40:19,681 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
14:40:19,681 |-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-8268/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:40:19,691 |-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-8268/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:40:19,691 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
14:40:19,693 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T12:40:19.448Z
14:40:19,693 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:40:19,693 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:40:19,695 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:40:19,699 |-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-8268/log/ustad-server.log
14:40:19,699 |-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-8268/log/ustad-server.log]
14:40:19,700 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:40:19,700 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:40:19,700 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:40:19,700 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:40:19,700 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
14:40:19,700 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
14:40:19,700 |-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-8268
CreateLearningSpaceRequest(url=http://37.27.227.212:8268/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8221
[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-8637/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=8637 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8637 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8637/api/centralappconfig/learningspace/getAll ready
14:43:24,006 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
14:43:24,006 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
14:43:24,006 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
14:43:24,007 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
14:43:24,010 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
14:43:24,010 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
14:43:24,014 |-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:43:24,014 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:43:24,015 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:43:24,015 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:43:24,016 |-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:43:24,018 |-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:43:24,056 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:43:24,056 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:43:24,068 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
14:43:24,069 |-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-8637/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:43:24,077 |-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-8637/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:43:24,077 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
14:43:24,080 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T12:43:23.848Z
14:43:24,080 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:43:24,080 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:43:24,081 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:43:24,086 |-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-8637/log/ustad-server.log
14:43:24,086 |-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-8637/log/ustad-server.log]
14:43:24,086 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:43:24,086 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:43:24,087 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:43:24,087 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:43:24,087 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
14:43:24,087 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
14:43:24,087 |-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-8637
CreateLearningSpaceRequest(url=http://37.27.227.212:8637/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8268
[Preparing Device] 003_003_add_content_as_block_in_course (0s)
TestServerController: stopping server on port: 8637
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-8661/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=8661 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8661 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8661/api/centralappconfig/learningspace/getAll ready
14:46:06,503 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
14:46:06,504 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - No custom configurators were discovered as a service.
14:46:06,504 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
14:46:06,504 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
14:46:06,507 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
14:46:06,507 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
14:46:06,510 |-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
14:46:06,510 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:46:06,510 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:46:06,510 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:46:06,512 |-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:46:06,513 |-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
14:46:06,549 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:46:06,549 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:46:06,561 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1810899357 - No compression will be used
14:46:06,561 |-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-8661/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:46:06,570 |-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-8661/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:46:06,570 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - Roll-over at midnight.
14:46:06,573 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - Setting initial period to 2025-07-27T12:46:06.344Z
14:46:06,573 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:46:06,573 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:46:06,574 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:46:06,578 |-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-8661/log/ustad-server.log
14:46:06,578 |-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-8661/log/ustad-server.log]
14:46:06,579 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:46:06,579 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:46:06,579 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:46:06,579 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:46:06,579 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@5f16132a - End of configuration.
14:46:06,580 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@69fb6037 - Registering current configuration as safe fallback point
14:46:06,580 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - 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-8661
CreateLearningSpaceRequest(url=http://37.27.227.212:8661/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
[Preparing Device] 001_001_admin_can_add_content_002-epub (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-8856/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=8856 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8856 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8856/api/centralappconfig/learningspace/getAll ready
14:48:30,926 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
14:48:30,926 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - No custom configurators were discovered as a service.
14:48:30,926 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
14:48:30,926 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
14:48:30,929 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
14:48:30,929 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
14:48:30,933 |-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
14:48:30,933 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:48:30,933 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:48:30,933 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:48:30,935 |-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:48:30,936 |-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
14:48:30,973 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:48:30,973 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:48:30,986 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1810899357 - No compression will be used
14:48:30,986 |-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-8856/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:48:30,996 |-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-8856/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:48:30,996 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - Roll-over at midnight.
14:48:30,999 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - Setting initial period to 2025-07-27T12:48:30.763Z
14:48:30,999 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:48:30,999 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:48:31,000 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:48:31,005 |-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-8856/log/ustad-server.log
14:48:31,005 |-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-8856/log/ustad-server.log]
14:48:31,005 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:48:31,005 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:48:31,005 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:48:31,005 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:48:31,006 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@5f16132a - End of configuration.
14:48:31,006 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@69fb6037 - Registering current configuration as safe fallback point
14:48:31,006 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - 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-8856
CreateLearningSpaceRequest(url=http://37.27.227.212:8856/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8856
[Preparing Device] 001_002_admin_add_invalid_content_gets_error_msg (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-8559/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=8559 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8559 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8559/api/centralappconfig/learningspace/getAll ready
14:55:21,232 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
14:55:21,232 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - No custom configurators were discovered as a service.
14:55:21,232 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
14:55:21,232 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
14:55:21,236 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
14:55:21,236 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
14:55:21,239 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 4 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
14:55:21,239 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:55:21,240 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:55:21,240 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:55:21,241 |-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:55:21,243 |-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
14:55:21,279 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:55:21,279 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:55:21,291 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1810899357 - No compression will be used
14:55:21,291 |-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-8559/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:55:21,300 |-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-8559/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:55:21,300 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - Roll-over at midnight.
14:55:21,303 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - Setting initial period to 2025-07-27T12:55:21.067Z
14:55:21,303 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:55:21,303 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:55:21,304 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:55:21,308 |-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-8559/log/ustad-server.log
14:55:21,309 |-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-8559/log/ustad-server.log]
14:55:21,309 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:55:21,309 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:55:21,309 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:55:21,309 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:55:21,309 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@5f16132a - End of configuration.
14:55:21,310 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@69fb6037 - Registering current configuration as safe fallback point
14:55:21,310 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - 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-8559
CreateLearningSpaceRequest(url=http://37.27.227.212:8559/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8559
[Preparing Device] 005_004_users_can_add_external_links_as_post_on_discussion_board (0s)
TestServerController: start server requested
TestServerController: exec /usr/bin/java -Dlogs_dir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8799/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=8799 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8799 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8799/api/centralappconfig/learningspace/getAll ready
14:59:53,972 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
14:59:53,972 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
14:59:53,972 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
14:59:53,973 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
14:59:53,976 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
14:59:53,976 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
14:59:53,980 |-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:59:53,980 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
14:59:53,980 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
14:59:53,980 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:59:53,981 |-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:59:53,983 |-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:59:54,021 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
14:59:54,021 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
14:59:54,033 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
14:59:54,033 |-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-8799/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
14:59:54,042 |-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-8799/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
14:59:54,042 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
14:59:54,045 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T12:59:53.827Z
14:59:54,045 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
14:59:54,045 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
14:59:54,046 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:59:54,050 |-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-8799/log/ustad-server.log
14:59:54,050 |-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-8799/log/ustad-server.log]
14:59:54,051 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
14:59:54,051 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
14:59:54,051 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
14:59:54,051 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
14:59:54,051 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
14:59:54,051 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
14:59:54,051 |-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-8799
CreateLearningSpaceRequest(url=http://37.27.227.212:8799/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8661
[Preparing Device] 001_004_admin_can_move_content (0s)
TestServerController: stopping server on port: 8799
[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-8152/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=8152 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8152 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8152/api/centralappconfig/learningspace/getAll ready
15:06:23,024 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:06:23,025 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:06:23,025 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:06:23,025 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:06:23,029 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:06:23,029 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:06:23,033 |-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:06:23,033 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:06:23,033 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:06:23,033 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:06:23,035 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:06:23,036 |-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:06:23,079 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:06:23,079 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:06:23,091 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:06:23,091 |-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-8152/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:06:23,100 |-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-8152/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:06:23,100 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:06:23,103 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:06:22.865Z
15:06:23,103 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:06:23,103 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:06:23,104 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:06:23,109 |-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-8152/log/ustad-server.log
15:06:23,109 |-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-8152/log/ustad-server.log]
15:06:23,109 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:06:23,109 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:06:23,109 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:06:23,110 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:06:23,110 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:06:23,110 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:06:23,110 |-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-8152
CreateLearningSpaceRequest(url=http://37.27.227.212:8152/, 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-8785/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=8785 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8785 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8785/api/centralappconfig/learningspace/getAll ready
15:06:57,500 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:06:57,501 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:06:57,501 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:06:57,501 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:06:57,504 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:06:57,504 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:06:57,508 |-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:06:57,508 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:06:57,508 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:06:57,508 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:06:57,509 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:06:57,511 |-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:06:57,547 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:06:57,547 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:06:57,560 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:06:57,560 |-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-8785/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:06:57,569 |-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-8785/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:06:57,569 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:06:57,572 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:06:57.338Z
15:06:57,572 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:06:57,572 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:06:57,573 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:06:57,578 |-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-8785/log/ustad-server.log
15:06:57,578 |-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-8785/log/ustad-server.log]
15:06:57,578 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:06:57,578 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:06:57,579 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:06:57,579 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:06:57,579 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:06:57,579 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:06:57,579 |-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-8785
CreateLearningSpaceRequest(url=http://37.27.227.212:8785/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8152
[Preparing Device] 003_005_course_view_and_modules_can_expand_collapse (0s)
TestServerController: stopping server on port: 8785
[Preparing Device] 005_005_users_can_delete_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-8651/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=8651 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8651 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8651/api/centralappconfig/learningspace/getAll ready
15:11:35,985 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:11:35,986 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:11:35,986 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:11:35,986 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:11:35,990 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:11:35,990 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:11:35,994 |-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:35,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:11:35,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:11:35,994 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:11:35,996 |-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:35,997 |-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:11:36,038 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:11:36,038 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:11:36,051 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:11:36,051 |-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-8651/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:11:36,060 |-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-8651/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:11:36,060 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:11:36,063 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:11:35.839Z
15:11:36,063 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:11:36,063 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:11:36,064 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:11:36,069 |-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-8651/log/ustad-server.log
15:11:36,069 |-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-8651/log/ustad-server.log]
15:11:36,069 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:11:36,069 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:11:36,069 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:11:36,069 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:11:36,069 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:11:36,070 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:11:36,070 |-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-8651
CreateLearningSpaceRequest(url=http://37.27.227.212:8651/, 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-8144/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=8144 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8144 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8144/api/centralappconfig/learningspace/getAll ready
15:13:16,387 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:13:16,388 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:13:16,388 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:13:16,388 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:13:16,391 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:13:16,392 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:13:16,395 |-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:13:16,395 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:13:16,396 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:13:16,396 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:13:16,397 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:13:16,399 |-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:13:16,437 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:13:16,437 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:13:16,449 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:13:16,449 |-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-8144/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:13:16,459 |-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-8144/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:13:16,459 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:13:16,462 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:13:16.216Z
15:13:16,462 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:13:16,462 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:13:16,463 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:13:16,468 |-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-8144/log/ustad-server.log
15:13:16,468 |-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-8144/log/ustad-server.log]
15:13:16,468 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:13:16,468 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:13:16,469 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:13:16,469 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:13:16,469 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:13:16,469 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:13:16,469 |-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-8144
CreateLearningSpaceRequest(url=http://37.27.227.212:8144/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8651
[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-8130/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=8130 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8130 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8130/api/centralappconfig/learningspace/getAll ready
15:17:27,510 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:17:27,511 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:17:27,511 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:17:27,511 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:17:27,514 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:17:27,514 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:17:27,518 |-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:17:27,518 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:17:27,518 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:17:27,518 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:17:27,519 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:17:27,521 |-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:17:27,557 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:17:27,557 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:17:27,569 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:17:27,570 |-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-8130/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:17:27,579 |-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-8130/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:17:27,579 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:17:27,581 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:17:27.342Z
15:17:27,581 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:17:27,581 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:17:27,583 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:17:27,587 |-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-8130/log/ustad-server.log
15:17:27,588 |-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-8130/log/ustad-server.log]
15:17:27,588 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:17:27,588 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:17:27,588 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:17:27,588 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:17:27,588 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:17:27,589 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:17:27,589 |-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-8130
CreateLearningSpaceRequest(url=http://37.27.227.212:8130/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8144
[Preparing Device] 002_002_student_joining_course_using_code (0s)
TestServerController: stopping server on port: 8130
[Preparing Device] 003_002_add_module_text_blocks_and_perform_indent_hide_delete_actions (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-8139/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=8139 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8139 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8139/api/centralappconfig/learningspace/getAll ready
15:20:40,820 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:20:40,820 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:20:40,820 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:20:40,820 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:20:40,824 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:20:40,824 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:20:40,827 |-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:20:40,827 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:20:40,827 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:20:40,828 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:20:40,829 |-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:20:40,830 |-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:20:40,867 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:20:40,867 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:20:40,879 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:20:40,879 |-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-8139/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:20:40,888 |-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-8139/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:20:40,888 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:20:40,891 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:20:40.652Z
15:20:40,891 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:20:40,891 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:20:40,892 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:20:40,897 |-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-8139/log/ustad-server.log
15:20:40,897 |-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-8139/log/ustad-server.log]
15:20:40,897 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:20:40,898 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:20:40,898 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:20:40,898 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:20:40,898 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:20:40,898 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:20:40,898 |-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-8139
CreateLearningSpaceRequest(url=http://37.27.227.212:8139/, 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-8902/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=8902 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8902 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8902/api/centralappconfig/learningspace/getAll ready
15:22:09,069 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:22:09,069 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:22:09,069 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:22:09,069 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:22:09,072 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:22:09,072 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:22:09,076 |-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:22:09,076 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:22:09,076 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:22:09,076 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:22:09,078 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
15:22:09,079 |-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:22:09,115 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:22:09,115 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:22:09,127 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:22:09,128 |-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-8902/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:22:09,137 |-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-8902/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:22:09,137 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:22:09,140 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:22:08.892Z
15:22:09,140 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:22:09,140 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:22:09,141 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:22:09,146 |-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-8902/log/ustad-server.log
15:22:09,146 |-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-8902/log/ustad-server.log]
15:22:09,146 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:22:09,146 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:22:09,147 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:22:09,147 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:22:09,147 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:22:09,147 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:22:09,147 |-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-8902
CreateLearningSpaceRequest(url=http://37.27.227.212:8902/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8139
[Preparing Device] 001_001_admin_can_add_content_001-h5p (0s)
TestServerController: stopping server on port: 8902
[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-8304/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=8304 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8304 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8304/api/centralappconfig/learningspace/getAll ready
15:27:52,832 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:27:52,833 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:27:52,833 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:27:52,833 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:27:52,836 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:27:52,836 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:27:52,840 |-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:27:52,840 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:27:52,841 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:27:52,841 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:27:52,842 |-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:27:52,844 |-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:27:52,881 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:27:52,881 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:27:52,893 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:27:52,894 |-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-8304/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:27:52,902 |-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-8304/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:27:52,902 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:27:52,905 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:27:52.669Z
15:27:52,905 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:27:52,905 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:27:52,906 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:27:52,911 |-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-8304/log/ustad-server.log
15:27:52,911 |-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-8304/log/ustad-server.log]
15:27:52,911 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:27:52,911 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:27:52,912 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:27:52,912 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:27:52,912 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:27:52,912 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:27:52,912 |-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-8304
CreateLearningSpaceRequest(url=http://37.27.227.212:8304/, 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-8145/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=8145 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8145 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8145/api/centralappconfig/learningspace/getAll ready
15:28:21,317 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:28:21,318 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:28:21,318 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:28:21,318 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:28:21,322 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:28:21,322 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:28:21,326 |-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:28:21,326 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:28:21,326 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:28:21,326 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:28:21,328 |-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:28:21,329 |-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:28:21,370 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:28:21,370 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:28:21,382 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:28:21,382 |-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-8145/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:28:21,391 |-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-8145/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:28:21,391 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:28:21,394 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:28:21.158Z
15:28:21,394 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:28:21,394 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:28:21,395 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:28:21,400 |-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-8145/log/ustad-server.log
15:28:21,400 |-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-8145/log/ustad-server.log]
15:28:21,400 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:28:21,400 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:28:21,400 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:28:21,400 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:28:21,400 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:28:21,401 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:28:21,401 |-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-8145
CreateLearningSpaceRequest(url=http://37.27.227.212:8145/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8145
[Preparing Device] 002_005_admin_grant_permissions_test (0s)
TestServerController: stopping server on port: 8304
[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-8796/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=8796 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8796 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8796/api/centralappconfig/learningspace/getAll ready
15:34:40,681 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:34:40,681 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:34:40,681 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:34:40,682 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:34:40,685 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:34:40,685 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:34:40,689 |-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:34:40,689 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:34:40,690 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:34:40,690 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:34:40,691 |-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:34:40,693 |-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:34:40,730 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:34:40,730 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:34:40,742 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:34:40,743 |-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-8796/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:34:40,752 |-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-8796/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:34:40,752 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:34:40,754 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:34:40.518Z
15:34:40,754 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:34:40,754 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:34:40,755 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:34:40,760 |-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-8796/log/ustad-server.log
15:34:40,760 |-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-8796/log/ustad-server.log]
15:34:40,761 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:34:40,761 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:34:40,761 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:34:40,761 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:34:40,761 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:34:40,761 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:34:40,761 |-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-8796
CreateLearningSpaceRequest(url=http://37.27.227.212:8796/, 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-8137/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=8137 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8137 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8137/api/centralappconfig/learningspace/getAll ready
15:35:12,902 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:35:12,902 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:35:12,902 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:35:12,903 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:35:12,906 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:35:12,906 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:35:12,910 |-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:35:12,910 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:35:12,910 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:35:12,910 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:35:12,911 |-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:35:12,913 |-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:35:12,950 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:35:12,950 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:35:12,963 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:35:12,963 |-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-8137/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:35:12,972 |-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-8137/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:35:12,972 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:35:12,975 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:35:12.753Z
15:35:12,975 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:35:12,975 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:35:12,976 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:35:12,981 |-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-8137/log/ustad-server.log
15:35:12,981 |-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-8137/log/ustad-server.log]
15:35:12,981 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:35:12,981 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:35:12,982 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:35:12,982 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:35:12,982 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:35:12,982 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:35:12,982 |-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-8137
CreateLearningSpaceRequest(url=http://37.27.227.212:8137/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8796
[Preparing Device] 001_003_admin_can_delete_and_restore_content_001 (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-8130/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=8130 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8130 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8130/api/centralappconfig/learningspace/getAll ready
15:40:32,186 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:40:32,186 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - No custom configurators were discovered as a service.
15:40:32,186 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:40:32,187 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:40:32,189 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:40:32,190 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:40:32,193 |-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
15:40:32,193 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:40:32,193 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:40:32,193 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:40:32,195 |-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:40:32,196 |-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
15:40:32,233 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:40:32,233 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:40:32,245 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1810899357 - No compression will be used
15:40:32,245 |-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-8130/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:40:32,254 |-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-8130/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:40:32,254 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - Roll-over at midnight.
15:40:32,257 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - Setting initial period to 2025-07-27T13:40:32.060Z
15:40:32,257 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:40:32,257 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@dd0c991 - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:40:32,258 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:40:32,263 |-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-8130/log/ustad-server.log
15:40:32,263 |-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-8130/log/ustad-server.log]
15:40:32,263 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:40:32,264 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:40:32,264 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:40:32,264 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:40:32,264 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@5f16132a - End of configuration.
15:40:32,264 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@69fb6037 - Registering current configuration as safe fallback point
15:40:32,264 |-INFO in ch.qos.logback.classic.util.ContextInitializer@236e3f4e - 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-8130
CreateLearningSpaceRequest(url=http://37.27.227.212:8130/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8137
[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-8333/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=8333 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8333 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8333/api/centralappconfig/learningspace/getAll ready
15:43:12,116 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:43:12,116 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:43:12,116 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:43:12,117 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:43:12,120 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:43:12,120 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:43:12,123 |-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:12,123 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:43:12,123 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:43:12,124 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:43:12,125 |-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:12,126 |-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:12,162 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:43:12,162 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:43:12,174 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:43:12,174 |-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-8333/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:43:12,183 |-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-8333/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:43:12,183 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:43:12,186 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:43:11.967Z
15:43:12,186 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:43:12,186 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:43:12,187 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:43:12,191 |-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-8333/log/ustad-server.log
15:43:12,192 |-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-8333/log/ustad-server.log]
15:43:12,192 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:43:12,192 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:43:12,192 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:43:12,192 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:43:12,192 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:43:12,193 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:43:12,193 |-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-8333
CreateLearningSpaceRequest(url=http://37.27.227.212:8333/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8130
[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-8717/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=8717 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8717 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8717/api/centralappconfig/learningspace/getAll ready
15:47:40,627 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:47:40,627 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:47:40,628 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:47:40,628 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:47:40,631 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:47:40,631 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:47:40,634 |-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:47:40,634 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:47:40,634 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:47:40,635 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:47:40,636 |-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:47:40,637 |-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:47:40,674 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:47:40,674 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:47:40,686 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:47:40,686 |-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-8717/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:47:40,695 |-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-8717/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:47:40,695 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:47:40,698 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:47:40.462Z
15:47:40,698 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:47:40,698 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:47:40,699 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:47:40,704 |-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-8717/log/ustad-server.log
15:47:40,704 |-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-8717/log/ustad-server.log]
15:47:40,704 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:47:40,704 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:47:40,704 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:47:40,705 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:47:40,705 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:47:40,705 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:47:40,705 |-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-8717
CreateLearningSpaceRequest(url=http://37.27.227.212:8717/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8717
[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-8818/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=8818 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8818 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8818/api/centralappconfig/learningspace/getAll ready
15:55:06,348 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
15:55:06,349 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
15:55:06,349 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
15:55:06,349 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
15:55:06,352 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
15:55:06,352 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
15:55:06,355 |-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:55:06,355 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
15:55:06,355 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
15:55:06,356 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
15:55:06,357 |-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:55:06,358 |-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:55:06,394 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
15:55:06,394 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
15:55:06,406 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
15:55:06,407 |-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-8818/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
15:55:06,415 |-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-8818/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
15:55:06,415 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
15:55:06,418 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T13:55:06.191Z
15:55:06,418 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
15:55:06,418 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
15:55:06,419 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:55:06,424 |-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-8818/log/ustad-server.log
15:55:06,424 |-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-8818/log/ustad-server.log]
15:55:06,424 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
15:55:06,424 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
15:55:06,425 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
15:55:06,425 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
15:55:06,425 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
15:55:06,425 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
15:55:06,425 |-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-8818
CreateLearningSpaceRequest(url=http://37.27.227.212:8818/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8333
[Preparing Device] 004_004_assignment_after_deadline_and_before_grace_Period (0s)
TestServerController: stopping server on port: 8818
[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-8311/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=8311 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8311 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8311/api/centralappconfig/learningspace/getAll ready
16:01:27,741 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:01:27,741 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
16:01:27,741 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:01:27,742 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:01:27,744 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:01:27,745 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:01:27,748 |-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
16:01:27,748 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:01:27,748 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:01:27,748 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:01:27,750 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/app-ktor-server/build/libs/ustad-server-all.jar!/logback.xml]
16:01:27,751 |-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:01:27,787 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:01:27,788 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:01:27,800 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
16:01:27,800 |-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-8311/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:01:27,809 |-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-8311/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:01:27,809 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
16:01:27,812 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T14:01:27.573Z
16:01:27,812 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:01:27,812 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:01:27,813 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:01:27,818 |-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-8311/log/ustad-server.log
16:01:27,818 |-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-8311/log/ustad-server.log]
16:01:27,818 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:01:27,818 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:01:27,818 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:01:27,818 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:01:27,818 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
16:01:27,819 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
16:01:27,819 |-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-8311
CreateLearningSpaceRequest(url=http://37.27.227.212:8311/, 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-8785/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=8785 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8785 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8785/api/centralappconfig/learningspace/getAll ready
16:02:01,179 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:02:01,179 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
16:02:01,179 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:02:01,179 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:02:01,182 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:02:01,182 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:02:01,186 |-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:02:01,186 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:02:01,186 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:02:01,186 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:02:01,187 |-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:01,189 |-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:02:01,225 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:02:01,225 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:02:01,237 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
16:02:01,237 |-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-8785/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:02:01,246 |-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-8785/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:02:01,246 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
16:02:01,249 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T14:02:01.020Z
16:02:01,249 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:02:01,249 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:02:01,250 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:02:01,255 |-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-8785/log/ustad-server.log
16:02:01,255 |-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-8785/log/ustad-server.log]
16:02:01,255 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:02:01,255 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:02:01,255 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:02:01,255 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:02:01,255 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
16:02:01,256 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
16:02:01,256 |-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-8785
CreateLearningSpaceRequest(url=http://37.27.227.212:8785/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8311
TestServerController: stopping server on port: 8785
[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-8369/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=8369 -P:ktor.ustad.datadir=/home/jenkins/.jenkins/workspace/Primary-End-To-End-Tests/test-end-to-end/android-maestro/../../testserver-controller/build/data/server-8369 -P:ktor.ustad.jsDevServer= -P:ktor.ustad.useMockEmail=true
http://37.27.227.212:8369/api/centralappconfig/learningspace/getAll ready
16:10:48,630 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
16:10:48,630 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - No custom configurators were discovered as a service.
16:10:48,630 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
16:10:48,631 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
16:10:48,634 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
16:10:48,634 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
16:10:48,638 |-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:10:48,638 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
16:10:48,638 |-INFO in ch.qos.logback.classic.util.ContextInitializer@157853da - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
16:10:48,639 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:10:48,640 |-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:10:48,642 |-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:10:48,681 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [FILE]
16:10:48,681 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:10:48,693 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@594427726 - No compression will be used
16:10:48,694 |-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-8369/log/ustad-server.%d{yyyy-MM-dd}.%i.log for the active file
16:10:48,703 |-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-8369/log/ustad-server.%d{yyyy-MM-dd}.%i.log'.
16:10:48,703 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Roll-over at midnight.
16:10:48,705 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - Setting initial period to 2025-07-27T14:10:48.461Z
16:10:48,705 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:10:48,705 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@3cc1435c - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:10:48,706 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:10:48,711 |-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-8369/log/ustad-server.log
16:10:48,711 |-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-8369/log/ustad-server.log]
16:10:48,711 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to TRACE
16:10:48,711 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [FILE] to Logger[ROOT]
16:10:48,712 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [org.eclipse.jetty] to INFO
16:10:48,712 |-INFO in ch.qos.logback.classic.model.processor.LoggerModelHandler - Setting level of logger [io.netty] to INFO
16:10:48,712 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@6bf0219d - End of configuration.
16:10:48,712 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@dd0c991 - Registering current configuration as safe fallback point
16:10:48,712 |-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-8369
CreateLearningSpaceRequest(url=http://37.27.227.212:8369/, title=TestLearningSpace, dbUrl=null, dbUsername=, dbPassword=, adminUsername=admin, adminPassword=testpass)
Done: OK
TestServerController: stopping server on port: 8369
32/32 Flows Passed
==== View details in the console ====
https://app.robintest.com/project/proj_01jjbq65hrff8a3jkm7v2s0ty8/maestro-test/app/app_01jjbx8fgxf4fbbfm8gwh990zz/upload/mupload_01k15whczgf9v8hw3s5nm4wn36
Process will exit with code 0 (SUCCESS)
[htmlpublisher] Archiving HTML reports...
Recording test results
[Checks API] No suitable checks publisher found.
[Slack Notifications] found #605 as previous completed, non-aborted build
Finished: SUCCESS
![[Jenkins]](/jenkins/static/feafc6d9/images/svgs/logo.svg){kind=logo}
