�[2mserver # [   18.151511] authelia[950]: {"level":"info","msg":"Initializing server (metrics) for non-TLS connections on '127.0.0.1:9959' path '/metrics'","time":"2024-08-15T00:41:11Z"}�[0m
�[2mserver # [   18.170786] authelia[950]: {"level":"info","msg":"Initializing server for non-TLS connections on '127.0.0.1:9091' path '/'","time":"2024-08-15T00:41:11Z"}�[0m
�[2mserver # Connection to localhost (127.0.0.1) 9091 port [tcp/xmltec-xmlmail] succeeded!�[0m
(finished: waiting for TCP port 9091 on localhost, in 1.05 seconds)
server: �[1m�[32mmust succeed: mkdir -p /tmp/shared/tmpjhahz5br�[0m
(finished: must succeed: mkdir -p /tmp/shared/tmpjhahz5br, in 0.01 seconds)
server: �[1m�[32mmust succeed: cp -r /etc/ssl/certs/ca-certificates.crt /tmp/shared/tmpjhahz5br/ca-certificates.crt�[0m
(finished: must succeed: cp -r /etc/ssl/certs/ca-certificates.crt /tmp/shared/tmpjhahz5br/ca-certificates.crt, in 0.02 seconds)
client: �[1m�[32mmust succeed: rm -r /etc/ssl/certs�[0m
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)
(finished: must succeed: rm -r /etc/ssl/certs, in 0.02 seconds)
client: �[1m�[32mmust succeed: mkdir -p /tmp/shared/tmpbu_p7k84�[0m
(finished: must succeed: mkdir -p /tmp/shared/tmpbu_p7k84, in 0.02 seconds)
client: �[1m�[32mmust succeed: mkdir -p /etc/ssl/certs�[0m
(finished: must succeed: mkdir -p /etc/ssl/certs, in 0.02 seconds)
client: �[1m�[32mmust succeed: cp -r /tmp/shared/tmpbu_p7k84/ca-certificates.crt /etc/ssl/certs/ca-certificates.crt�[0m
(finished: must succeed: cp -r /tmp/shared/tmpbu_p7k84/ca-certificates.crt /etc/ssl/certs/ca-certificates.crt, in 0.02 seconds)
�[1m�[32msubtest: unauthenticated access is not granted�[0m
curl --show-error --location --cookie-jar cookie.txt --cookie cookie.txt --connect-to d.example.com:443:server:443 --connect-to d.example.com:80:server:80 --connect-to auth.example.com:443:server:443 --silent --output /dev/null --write-out '{"code":%{response_code},"auth_host":"%{urle.host}","auth_query":"%{urle.query}","all":%{json}}' https://d.example.com
�[2mserver # [   18.906292] authelia[950]: {"level":"debug","msg":"Check authorization of subject username= groups= ip=192.168.1.1 and object https://d.example.com/ (method ).","time":"2024-08-15T00:41:12Z"}�[0m
�[2mserver # [   18.907959] authelia[950]: {"level":"info","method":"GET","msg":"Access to https://d.example.com/ (method unknown) is not authorized to user \u003canonymous\u003e, responding with status code 401","path":"/api/verify","remote_ip":"192.168.1.1","time":"2024-08-15T00:41:12Z"}�[0m
(finished: subtest: unauthenticated access is not granted, in 0.14 seconds)
�[1m�[32msubtest: extraScript�[0m
server: �[1m�[32mmust succeed: journalctl -n100 -u deluged�[0m
(finished: must succeed: journalctl -n100 -u deluged, in 0.03 seconds)
Aug 15 00:41:02 server systemd[1]: Starting Deluge BitTorrent Daemon...
Aug 15 00:41:03 server systemd[1]: Started Deluge BitTorrent Daemon.

server: �[1m�[32mmust succeed: systemctl status deluged�[0m
(finished: must succeed: systemctl status deluged, in 0.03 seconds)
● deluged.service - Deluge BitTorrent Daemon
     Loaded: loaded (/etc/systemd/system/deluged.service; enabled; preset: enabled)
     Active: active (running) since Thu 2024-08-15 00:41:03 UTC; 9s ago
    Process: 828 ExecStartPre=/nix/store/p6j6sby3v8qbzfirgaz0z7zvy52xl2dk-unit-script-deluged-pre-start/bin/deluged-pre-start (code=exited, status=0/SUCCESS)
   Main PID: 855 (..deluged-wrapp)
         IP: 216B in, 1.4K out
         IO: 0B read, 16.0K written
      Tasks: 3 (limit: 1140)
     Memory: 63.8M (peak: 64.1M)
        CPU: 1.577s
     CGroup: /system.slice/deluged.service
             └─855 /nix/store/7hnr99nxrd2aw6lghybqdmkckq60j6l9-python3-3.11.9/bin/python3.11 /nix/store/dzpr8zq3387ixwh17p78p1v5mvcgay1d-python3.11-deluge-gtk-2.1.1/bin/..deluged-wrapped-wrapped --do-not-daemonize --config /var/lib/deluge/.config/deluge

Aug 15 00:41:02 server systemd[1]: Starting Deluge BitTorrent Daemon...
Aug 15 00:41:03 server systemd[1]: Started Deluge BitTorrent Daemon.

server: �[1m�[32mmust succeed: systemctl status delugeweb�[0m
(finished: must succeed: systemctl status delugeweb, in 0.03 seconds)
● delugeweb.service - Deluge BitTorrent WebUI
     Loaded: loaded (/etc/systemd/system/delugeweb.service; enabled; preset: enabled)
     Active: active (running) since Thu 2024-08-15 00:41:03 UTC; 9s ago
   Main PID: 859 (deluge-web)
         IP: 216B in, 164B out
         IO: 0B read, 4.0K written
      Tasks: 1 (limit: 1140)
     Memory: 67.1M (peak: 67.4M)
        CPU: 1.468s
     CGroup: /system.slice/delugeweb.service
             └─859 deluge-web

Aug 15 00:41:03 server systemd[1]: Started Deluge BitTorrent WebUI.

�[1m�[32msubtest: web connect�[0m
server: �[1m�[32mmust succeed: cat /var/lib/deluge/.config/deluge/auth�[0m
(finished: must succeed: cat /var/lib/deluge/.config/deluge/auth, in 0.02 seconds)
localclient:localclientpw:10
user:userpw:10
curl --show-error --location --cookie-jar cookie.txt --cookie cookie.txt --connect-to d.example.com:443:server:443 --connect-to d.example.com:80:server:80 --connect-to auth.example.com:443:server:443 --data ' {"method": "auth.login", "params": ["deluge"], "id": 1} '  -H "Content-Type: application/json" -H "Accept: application/json"  https://d.example.com/json
�[2mclient #   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current�[0m
�[2mclient #                                  Dload  Upload   Total   Spent    Left  Speed�[0m
�[2mserver # [   19.123047] authelia[950]: {"level":"debug","msg":"Check authorization of subject username= groups= ip=192.168.1.1 and object https://d.example.com/json (method ).","time":"2024-08-15T00:41:12Z"}�[0m
�[2mclient #   0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0100    97    0    40  100    57    482    687 --:--:-- --:--:-- --:--:--  1182�[0m
{'result': True, 'error': None, 'id': 1}
curl --show-error --location --cookie-jar cookie.txt --cookie cookie.txt --connect-to d.example.com:443:server:443 --connect-to d.example.com:80:server:80 --connect-to auth.example.com:443:server:443 --data ' {"method": "web.get_hosts", "params": [], "id": 1} '  -H "Content-Type: application/json" -H "Accept: application/json"  https://d.example.com/json
�[2mclient #   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current�[0m
�[2mclient #                                  Dload  Upload   Total   Spent    Left  Speed�[0m
�[2mserver # [   19.239176] authelia[950]: {"level":"debug","msg":"Check authorization of subject username= groups= ip=192.168.1.1 and object https://d.example.com/json (method ).","time":"2024-08-15T00:41:12Z"}�[0m
�[2mclient #   0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0100   161    0   109  100    52   1544    736 --:--:-- --:--:-- --:--:--  2300�[0m
{'result': [['52195dded7404a4b972e3eef36336051', '127.0.0.1', 58846, 'localclient']], 'error': None, 'id': 1}
curl --show-error --location --cookie-jar cookie.txt --cookie cookie.txt --connect-to d.example.com:443:server:443 --connect-to d.example.com:80:server:80 --connect-to auth.example.com:443:server:443 --data ' {"method": "web.connect", "params": ["52195dded7404a4b972e3eef36336051"], "id": 1} '  -H "Content-Type: application/json" -H "Accept: application/json"  https://d.example.com/json
�[2mclient #   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current�[0m
�[2mclient #                                  Dload  Upload   Total   Spent    Left  Speed�[0m
�[2mserver # [   19.344296] authelia[950]: {"level":"debug","msg":"Check authorization of subject username= groups= ip=192.168.1.1 and object https://d.example.com/json (method ).","time":"2024-08-15T00:41:12Z"}�[0m
�[2mclient #   0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0100  2042    0  1958  100    84  15501    665 --:--:-- --:--:-- --:--:-- 16206�[0m
{'result': ['core.add_torrent_file', 'core.add_torrent_file_async', 'core.add_torrent_files', 'core.add_torrent_magnet', 'core.add_torrent_url', 'core.connect_peer', 'core.create_account', 'core.create_torrent', 'core.disable_plugin', 'core.enable_plugin', 'core.force_reannounce', 'core.force_recheck', 'core.get_auth_levels_mappings', 'core.get_available_plugins', 'core.get_completion_paths', 'core.get_config', 'core.get_config_value', 'core.get_config_values', 'core.get_enabled_plugins', 'core.get_external_ip', 'core.get_filter_tree', 'core.get_free_space', 'core.get_known_accounts', 'core.get_libtorrent_version', 'core.get_listen_port', 'c…
(finished: subtest: web connect, in 0.40 seconds)
(finished: subtest: extraScript, in 0.48 seconds)
(finished: run the VM test script, in 21.41 seconds)
test script finished in 21.47s
�[1m�[32mcleanup�[0m
kill machine (pid 7)
qemu-kvm: terminating on signal 15 from pid 4 (/nix/store/7hnr99nxrd2aw6lghybqdmkckq60j6l9-python3-3.11.9/bin/python3.11)
kill machine (pid 28)
qemu-kvm: terminating on signal 15 from pid 4 (/nix/store/7hnr99nxrd2aw6lghybqdmkckq60j6l9-python3-3.11.9/bin/python3.11)
(finished: cleanup, in 0.02 seconds)
kill vlan (pid 5)