�[2mserver # [   28.808803] Readarr[862]: [Info] add_on_author_added_to_notifications: Starting migration of Log DB to 38�[0m
�[2mserver # [   28.811684] Readarr[862]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: INSERT INTO "VersionInfo" ("Version", "AppliedOn", "Description") VALUES (38, '2024-09-16T01:30:08', 'add_on_author_added_to_notifications')�[0m
�[2mserver # [   28.816536] Readarr[862]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Committing Transaction�[0m
�[2mserver # [   28.828428] Readarr[862]: [Info] FluentMigrator.Runner.MigrationRunner: 38: add_on_author_added_to_notifications migrated�[0m
�[2mserver # [   28.830843] Readarr[862]: [Info] FluentMigrator.Runner.MigrationRunner: => 0.0221978s�[0m
�[2mserver # [   28.832517] Readarr[862]: [Info] FluentMigrator.Runner.MigrationRunner: 39: book_last_searched_time migrating�[0m
�[2mserver # [   28.834327] Readarr[862]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Beginning Transaction�[0m
�[2mserver # [   28.836532] Readarr[862]: [Info] book_last_searched_time: Starting migration of Log DB to 39�[0m
�[2mserver # [   28.838534] Readarr[862]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: INSERT INTO "VersionInfo" ("Version", "AppliedOn", "Description") VALUES (39, '2024-09-16T01:30:08', 'book_last_searched_time')�[0m
�[2mserver # [   28.841917] Readarr[862]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Committing Transaction�[0m
�[2mserver # [   28.848381] Readarr[862]: [Info] FluentMigrator.Runner.MigrationRunner: 39: book_last_searched_time migrated�[0m
�[2mserver # [   28.852252] Readarr[862]: [Info] FluentMigrator.Runner.MigrationRunner: => 0.0136671s�[0m
�[2mserver # [   28.853751] Readarr[862]: [Info] FluentMigrator.Runner.MigrationRunner: 40: add_indexer_flags migrating�[0m
�[2mserver # [   28.856103] Readarr[862]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Beginning Transaction�[0m
�[2mserver # [   28.857812] Readarr[862]: [Info] add_indexer_flags: Starting migration of Log DB to 40�[0m
�[2mserver # [   28.860408] Readarr[862]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: INSERT INTO "VersionInfo" ("Version", "AppliedOn", "Description") VALUES (40, '2024-09-16T01:30:08', 'add_indexer_flags')�[0m
�[2mserver # [   28.865501] Readarr[862]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Committing Transaction�[0m
�[2mserver # [   28.873466] Readarr[862]: [Info] FluentMigrator.Runner.MigrationRunner: 40: add_indexer_flags migrated�[0m
�[2mserver # [   28.876514] Readarr[862]: [Info] FluentMigrator.Runner.MigrationRunner: => 0.0216731s�[0m
�[2mserver # [   28.878695] Readarr[862]: [Info] FluentMigrator.Runner.MigrationRunner: 158: cdh_per_downloadclient migrating�[0m
�[2mserver # [   28.881832] Readarr[862]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Beginning Transaction�[0m
�[2mserver # [   28.886480] Readarr[862]: [Info] cdh_per_downloadclient: Starting migration of Log DB to 158�[0m
�[2mserver # [   28.888846] Readarr[862]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: INSERT INTO "VersionInfo" ("Version", "AppliedOn", "Description") VALUES (158, '2024-09-16T01:30:08', 'cdh_per_downloadclient')�[0m
�[2mserver # [   28.893425] Readarr[862]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Committing Transaction�[0m
�[2mserver # [   28.899849] Readarr[862]: [Info] FluentMigrator.Runner.MigrationRunner: 158: cdh_per_downloadclient migrated�[0m
�[2mserver # [   28.902568] Readarr[862]: [Info] FluentMigrator.Runner.MigrationRunner: => 0.017916s�[0m
�[2mserver # [   29.380258] Readarr[862]: [Info] Microsoft.Hosting.Lifetime: Now listening on: http://[::]:8787�[0m
�[2mserver # Connection to localhost (::1) 8787 port [tcp/msgsrvr] succeeded!�[0m
(finished: waiting for TCP port 8787 on localhost, in 15.54 seconds)
�[1m�[32msubtest: access�[0m
curl --show-error --location --cookie-jar cookie.txt --cookie cookie.txt --connect-to readarr.example.com:443:server:443 --connect-to readarr.example.com:80:server:80 --connect-to auth.example.com:443:server:443 --silent --output /dev/null --write-out '{"code":%{response_code}}' http://readarr.example.com
client: �[1m�[32mwaiting for the VM to finish booting�[0m
client: Guest shell says: b'Spawning backdoor root shell...\n'
client: connected to guest root shell
client: (connecting took 0.00 seconds)
(finished: waiting for the VM to finish booting, in 0.00 seconds)
�[2mserver # [   29.942833] Readarr[862]: [Info] QualityProfileService: Setting up default quality profiles�[0m
�[2mserver # [   30.115726] Readarr[862]: [Info] MetadataProfileService: Setting up standard metadata profile�[0m
�[2mserver # [   30.148953] Readarr[862]: [Info] MetadataProfileService: Setting up empty metadata profile�[0m
�[2mserver # [   30.389668] Readarr[862]: [Info] Microsoft.Hosting.Lifetime: Application started. Press Ctrl+C to shut down.�[0m
�[2mserver # [   30.397134] Readarr[862]: [Info] Microsoft.Hosting.Lifetime: Hosting environment: Production�[0m
�[2mserver # [   30.398422] Readarr[862]: [Info] Microsoft.Hosting.Lifetime: Content root path: /�[0m
�[2mserver # [   30.480425] dotnet[862]: �[0;38;5;245mSQLite error (1): near "SHOW": syntax error in "SHOW server_version"�[0m�[0m
(finished: subtest: access, in 0.91 seconds)
�[1m�[32msubtest: extraScript�[0m
�[1m�[32msubtest: health�[0m
curl --show-error --location --cookie-jar cookie.txt --cookie cookie.txt --connect-to readarr.example.com:443:server:443 --connect-to readarr.example.com:80:server:80 --connect-to auth.example.com:443:server:443 --silent --output /dev/null --write-out '{"code":%{response_code}}' readarr.example.com/health
response = {'code': 200}
(finished: subtest: health, in 0.25 seconds)
�[1m�[32msubtest: login�[0m
curl --show-error --location --cookie-jar cookie.txt --cookie cookie.txt --connect-to readarr.example.com:443:server:443 --connect-to readarr.example.com:80:server:80 --connect-to auth.example.com:443:server:443 --silent --output /dev/null --write-out '{"code":%{response_code}}' readarr.example.com/UI/Login
�[2mserver # [   30.974266] Readarr[862]: [Info] ManagedHttpDispatcher: IPv4 is available: True, IPv6 will be disabled�[0m
�[2mserver # [   31.265279] Readarr[862]: [Error] TaskExtensions: Task Error�[0m
�[2mserver # [   31.267421] Readarr[862]: [v0.3.32.2587] System.Net.Http.HttpRequestException: Name or service not known (readarr.servarr.com:443)�[0m
�[2mserver # [   31.269344] Readarr[862]:  ---> System.Net.Sockets.SocketException (0xFFFDFFFF): Name or service not known�[0m
�[2mserver # [   31.271544] Readarr[862]:    at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)�[0m
�[2mserver # [   31.273591] Readarr[862]:    at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token)�[0m
�[2mserver # [   31.275771] Readarr[862]:    at System.Net.Sockets.Socket.<ConnectAsync>g__WaitForConnectWithCancellation|277_0(AwaitableSocketAsyncEventArgs saea, ValueTask connectTask, CancellationToken cancellationToken)�[0m
�[2mserver # [   31.278876] Readarr[862]:    at NzbDrone.Common.Http.Dispatchers.ManagedHttpDispatcher.attemptConnection(AddressFamily addressFamily, SocketsHttpConnectionContext context, CancellationToken cancellationToken) in ./Readarr.Common/Http/Dispatchers/ManagedHttpDispatcher.cs:line 329�[0m
�[2mserver # [   31.282493] Readarr[862]:    at NzbDrone.Common.Http.Dispatchers.ManagedHttpDispatcher.onConnect(SocketsHttpConnectionContext context, CancellationToken cancellationToken) in ./Readarr.Common/Http/Dispatchers/ManagedHttpDispatcher.cs:line 315�[0m
�[2mserver # [   31.285581] Readarr[862]:    at System.Net.Http.HttpConnectionPool.ConnectToTcpHostAsync(String host, Int32 port, HttpRequestMessage initialRequest, Boolean async, CancellationToken cancellationToken)�[0m
�[2mserver # [   31.288187] Readarr[862]:    --- End of inner exception stack trace ---�[0m
�[2mserver # [   31.289198] Readarr[862]:    at System.Net.Http.HttpConnectionPool.ConnectToTcpHostAsync(String host, Int32 port, HttpRequestMessage initialRequest, Boolean async, CancellationToken cancellationToken)�[0m
�[2mserver # [   31.291612] Readarr[862]:    at System.Net.Http.HttpConnectionPool.ConnectAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)�[0m
�[2mserver # [   31.294301] Readarr[862]:    at System.Net.Http.HttpConnectionPool.AddHttp2ConnectionAsync(HttpRequestMessage request)�[0m
�[2mserver # [   31.295920] Readarr[862]:    at System.Threading.Tasks.TaskCompletionSourceWithCancellation`1.WaitWithCancellationAsync(CancellationToken cancellationToken)�[0m
�[2mserver # [   31.297809] Readarr[862]:    at System.Net.Http.HttpConnectionPool.GetHttp2ConnectionAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)�[0m
�[2mserver # [   31.300406] Readarr[862]:    at System.Net.Http.HttpConnectionPool.SendWithVersionDetectionAndRetryAsync(HttpRequestMessage request, Boolean async, Boolean doRequestAuth, CancellationToken cancellationToken)�[0m
�[2mserver # [   31.302947] Readarr[862]:    at System.Net.Http.AuthenticationHelper.SendWithAuthAsync(HttpRequestMessage request, Uri authUri, Boolean async, ICredentials credentials, Boolean preAuthenticate, Boolean isProxyAuth, Boolean doRequestAuth, HttpConnectionPool pool, CancellationToken cancellationToken)�[0m
�[2mserver # [   31.307342] Readarr[862]:    at System.Net.Http.DecompressionHandler.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)�[0m
�[2mserver # [   31.309329] Readarr[862]:    at System.Net.Http.HttpClient.<SendAsync>g__Core|83_0(HttpRequestMessage request, HttpCompletionOption completionOption, CancellationTokenSource cts, Boolean disposeCts, CancellationTokenSource pendingRequestsCts, CancellationToken originalCancellationToken)�[0m
�[2mserver # [   31.312744] Readarr[862]:    at NzbDrone.Common.Http.Dispatchers.ManagedHttpDispatcher.GetResponseAsync(HttpRequest request, CookieContainer cookies) in ./Readarr.Common/Http/Dispatchers/ManagedHttpDispatcher.cs:line 116�[0m
�[2mserver # [   31.316431] Readarr[862]:    at NzbDrone.Common.Http.HttpClient.ExecuteRequestAsync(HttpRequest request, CookieContainer cookieContainer) in ./Readarr.Common/Http/HttpClient.cs:line 157�[0m
�[2mserver # [   31.319922] Readarr[862]:    at NzbDrone.Common.Http.HttpClient.ExecuteAsync(HttpRequest request) in ./Readarr.Common/Http/HttpClient.cs:line 70�[0m
�[2mserver # [   31.325881] Readarr[862]:    at NzbDrone.Common.Http.HttpClient.Execute(HttpRequest request) in ./Readarr.Common/Http/HttpClient.cs:line 128�[0m
�[2mserver # [   31.328967] Readarr[862]:    at NzbDrone.Core.HealthCheck.Checks.SystemTimeCheck.Check() in ./Readarr.Core/HealthCheck/Checks/SystemTimeCheck.cs:line 30�[0m
�[2mserver # [   31.331869] Readarr[862]:    at NzbDrone.Core.HealthCheck.HealthCheckService.PerformHealthCheck(IProvideHealthCheck[] healthChecks, IEvent message, Boolean performServerChecks) in ./Readarr.Core/HealthCheck/HealthCheckService.cs:line 92�[0m
�[2mserver # [   31.335716] Readarr[862]:    at NzbDrone.Core.HealthCheck.HealthCheckService.HandleAsync(ApplicationStartedEvent message) in ./Readarr.Core/HealthCheck/HealthCheckService.cs:line 135�[0m
�[2mserver # [   31.341520] Readarr[862]:    at NzbDrone.Core.Messaging.Events.EventAggregator.<>c__DisplayClass6_2`1.<PublishEvent>b__2() in ./Readarr.Core/Messaging/Events/EventAggregator.cs:line 122�[0m
�[2mserver # [   31.347326] Readarr[862]:    at System.Threading.Tasks.Task.InnerInvoke()�[0m
�[2mserver # [   31.348440] Readarr[862]:    at System.Threading.Tasks.Task.<>c.<.cctor>b__272_0(Object obj)�[0m
�[2mserver # [   31.349614] Readarr[862]:    at System.Threading.ExecutionContext.RunFromThreadPoolDispatchLoop(Thread threadPoolThread, ExecutionContext executionContext, ContextCallback callback, Object state)�[0m
�[2mserver # [   31.352646] Readarr[862]: --- End of stack trace from previous location ---�[0m
�[2mserver # [   31.354586] Readarr[862]:    at System.Threading.ExecutionContext.RunFromThreadPoolDispatchLoop(Thread threadPoolThread, ExecutionContext executionContext, ContextCallback callback, Object state)�[0m
�[2mserver # [   31.357042] Readarr[862]:    at System.Threading.Tasks.Task.ExecuteWithThreadLocal(Task& currentTaskSlot, Thread threadPoolThread)�[0m
(finished: subtest: login, in 0.64 seconds)
�[1m�[32msubtest: apikey�[0m
server: �[1m�[32mmust succeed: cat /var/lib/readarr/config.xml�[0m
(finished: must succeed: cat /var/lib/readarr/config.xml, in 0.04 seconds)
(finished: subtest: apikey, in 0.04 seconds)
(finished: subtest: extraScript, in 0.93 seconds)
(finished: run the VM test script, in 32.85 seconds)
test script finished in 32.96s
�[1m�[32mcleanup�[0m
kill machine (pid 8)
qemu-kvm: terminating on signal 15 from pid 5 (/nix/store/h3i0acpmr8mrjx07519xxmidv8mpax4y-python3-3.12.5/bin/python3.12)
kill machine (pid 29)
qemu-kvm: terminating on signal 15 from pid 5 (/nix/store/h3i0acpmr8mrjx07519xxmidv8mpax4y-python3-3.12.5/bin/python3.12)
(finished: cleanup, in 0.02 seconds)
kill vlan (pid 6)