�[2mserver # Connection to localhost (127.0.0.1) 9091 port [tcp/xmltec-xmlmail] succeeded!�[0m
(finished: waiting for TCP port 9091 on localhost, in 0.02 seconds)
server: �[1m�[32mmust succeed: mkdir -p /tmp/shared/tmp5px3t7ye�[0m
(finished: must succeed: mkdir -p /tmp/shared/tmp5px3t7ye, in 0.01 seconds)
server: �[1m�[32mmust succeed: cp -r /etc/ssl/certs/ca-certificates.crt /tmp/shared/tmp5px3t7ye/ca-certificates.crt�[0m
(finished: must succeed: cp -r /etc/ssl/certs/ca-certificates.crt /tmp/shared/tmp5px3t7ye/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/tmpk_ep_tjf�[0m
(finished: must succeed: mkdir -p /tmp/shared/tmpk_ep_tjf, in 0.01 seconds)
client: �[1m�[32mmust succeed: mkdir -p /etc/ssl/certs�[0m
(finished: must succeed: mkdir -p /etc/ssl/certs, in 0.01 seconds)
client: �[1m�[32mmust succeed: cp -r /tmp/shared/tmpk_ep_tjf/ca-certificates.crt /etc/ssl/certs/ca-certificates.crt�[0m
(finished: must succeed: cp -r /tmp/shared/tmpk_ep_tjf/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 # [   19.241748] authelia[1103]: {"level":"debug","msg":"Check authorization of subject username= groups= ip=2001:db8:1::1 and object https://d.example.com/ (method GET).","time":"2024-09-14T01:20:31Z"}�[0m
�[2mserver # [   19.243734] authelia[1103]: {"level":"info","method":"GET","msg":"Access to https://d.example.com/ (method GET) is not authorized to user \u003canonymous\u003e, responding with status code 401","path":"/api/verify","remote_ip":"2001:db8:1::1","time":"2024-09-14T01:20:31Z"}�[0m
(finished: subtest: unauthenticated access is not granted, in 0.13 seconds)
�[1m�[32msubtest: extraScript�[0m
server: �[1m�[32mmust succeed: journalctl -n100 -u deluged�[0m
(finished: must succeed: journalctl -n100 -u deluged, in 0.02 seconds)
Sep 14 01:20:19 server systemd[1]: Starting Deluge BitTorrent Daemon...
Sep 14 01:20:19 server systemd[1]: Started Deluge BitTorrent Daemon.

server: �[1m�[32mmust succeed: systemctl status deluged�[0m
(finished: must succeed: systemctl status deluged, in 0.02 seconds)
● deluged.service - Deluge BitTorrent Daemon
     Loaded: loaded (/etc/systemd/system/deluged.service; enabled; preset: enabled)
     Active: active (running) since Sat 2024-09-14 01:20:19 UTC; 11s ago
 Invocation: ccc3e5723678428b9017c27c1bba452d
    Process: 851 ExecStartPre=/nix/store/66kifwm41vbxy6r1hxhasdd6s1h122zw-unit-script-deluged-pre-start/bin/deluged-pre-start (code=exited, status=0/SUCCESS)
   Main PID: 885 (..deluged-wrapp)
         IP: 216B in, 164B out
         IO: 0B read, 32K written
      Tasks: 3 (limit: 1138)
     Memory: 65.1M (peak: 65.3M)
        CPU: 1.297s
     CGroup: /system.slice/deluged.service
             └─885 /nix/store/h3i0acpmr8mrjx07519xxmidv8mpax4y-python3-3.12.5/bin/python3.12 /nix/store/dcig2a03iazhg91in0lz85xksf5yjsr1-python3.12-deluge-gtk-2.1.1/bin/..deluged-wrapped-wrapped --do-not-daemonize --config /var/lib/deluge/.config/deluge

Sep 14 01:20:19 server systemd[1]: Starting Deluge BitTorrent Daemon...
Sep 14 01:20:19 server systemd[1]: Started Deluge BitTorrent Daemon.

server: �[1m�[32mmust succeed: systemctl status delugeweb�[0m
(finished: must succeed: systemctl status delugeweb, in 0.02 seconds)
● delugeweb.service - Deluge BitTorrent WebUI
     Loaded: loaded (/etc/systemd/system/delugeweb.service; enabled; preset: enabled)
     Active: active (running) since Sat 2024-09-14 01:20:19 UTC; 11s ago
 Invocation: e7506362626e4a08a025f233cec75083
   Main PID: 888 (deluge-web)
         IP: 216B in, 112B out
         IO: 0B read, 4K written
      Tasks: 1 (limit: 1138)
     Memory: 64.6M (peak: 64.8M)
        CPU: 1.069s
     CGroup: /system.slice/delugeweb.service
             └─888 deluge-web

Sep 14 01:20:19 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.01 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.422629] authelia[1103]: {"level":"debug","msg":"Check authorization of subject username= groups= ip=2001:db8:1::1 and object https://d.example.com/json (method GET).","time":"2024-09-14T01:20:31Z"}�[0m
�[2mclient #   0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0100    97    0    40  100    57    477    680 --:--:-- --:--:-- --:--:--  1168�[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.552890] authelia[1103]: {"level":"debug","msg":"Check authorization of subject username= groups= ip=2001:db8:1::1 and object https://d.example.com/json (method GET).","time":"2024-09-14T01:20:31Z"}�[0m
�[2mclient #   0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0100   161    0   109  100    52   1282    611 --:--:-- --:--:-- --:--:--  1916�[0m
{'result': [['d582e902984340a98159a5f849b060bf', '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": ["d582e902984340a98159a5f849b060bf"], "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.658173] authelia[1103]: {"level":"debug","msg":"Check authorization of subject username= groups= ip=2001:db8:1::1 and object https://d.example.com/json (method GET).","time":"2024-09-14T01:20:31Z"}�[0m
�[2mclient #   0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0100  2042    0  1958  100    84  15193    651 --:--:-- --:--:-- --:--:-- 15953�[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.41 seconds)
(finished: subtest: extraScript, in 0.47 seconds)
(finished: run the VM test script, in 20.52 seconds)
test script finished in 20.57s
�[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)