(finished: waiting for unit bazarr.service, in 7.75 seconds)
server: �[1m�[32mwaiting for unit nginx.service�[0m
(finished: waiting for unit nginx.service, in 0.04 seconds)
server: �[1m�[32mwaiting for TCP port 6767 on localhost�[0m
�[2mserver # [    7.232327] dhcpcd[680]: eth0: soliciting an IPv6 router�[0m
�[2mserver # [    7.611955] dhcpcd[680]: eth0: soliciting a DHCP lease�[0m
�[2mserver # [    7.739979] NET: Registered PF_PACKET protocol family�[0m
�[2mserver # [    7.633236] dhcpcd[680]: eth0: offered 10.0.2.15 from 10.0.2.2�[0m
�[2mserver # [    7.633825] dhcpcd[680]: eth0: probing address 10.0.2.15/24�[0m
�[2mserver # [    8.075644] dhcpcd[680]: eth0: Router Advertisement from fe80::2�[0m
�[2mserver # [    8.076770] dhcpcd[680]: eth0: adding address fec0::5054:ff:fe12:3456/64�[0m
�[2mserver # [    8.077619] dhcpcd[680]: eth0: adding route to fec0::/64�[0m
�[2mserver # [    8.078399] dhcpcd[680]: eth0: adding default route via fe80::2�[0m
�[2mclient # [    8.204188] dhcpcd[688]: eth0: Router Advertisement from fe80::2�[0m
�[2mclient # [    8.206189] dhcpcd[688]: eth0: adding address fec0::5054:ff:fe12:3456/64�[0m
�[2mclient # [    8.207141] dhcpcd[688]: eth0: adding route to fec0::/64�[0m
�[2mclient # [    8.207981] dhcpcd[688]: eth0: adding default route via fe80::2�[0m
�[2mserver # [   12.478868] dhcpcd[680]: eth0: leased 10.0.2.15 for 86400 seconds�[0m
�[2mserver # [   12.479924] dhcpcd[680]: eth0: adding route to 10.0.2.0/24�[0m
�[2mserver # [   12.481223] dhcpcd[680]: eth0: adding default route via 10.0.2.2�[0m
�[2mclient # [   12.665293] dhcpcd[688]: eth0: leased 10.0.2.15 for 86400 seconds�[0m
�[2mclient # [   12.666757] dhcpcd[688]: eth0: adding route to 10.0.2.0/24�[0m
�[2mclient # [   12.667668] dhcpcd[688]: eth0: adding default route via 10.0.2.2�[0m
�[2mserver # [   12.593626] systemd[1]: Stopped target Host and Network Name Lookups.�[0m
�[2mserver # [   12.594841] systemd[1]: Stopping Host and Network Name Lookups...�[0m
�[2mserver # [   12.597751] systemd[1]: Stopped target User and Group Name Lookups.�[0m
�[2mserver # [   12.599130] systemd[1]: Stopping User and Group Name Lookups...�[0m
�[2mserver # [   12.601241] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...�[0m
�[2mserver # [   12.602955] systemd[1]: nscd.service: Deactivated successfully.�[0m
�[2mserver # [   12.607202] systemd[1]: Stopped Name Service Cache Daemon (nsncd).�[0m
�[2mserver # [   12.624708] systemd[1]: Starting Name Service Cache Daemon (nsncd)...�[0m
�[2mclient # [   12.732898] systemd[1]: Stopped target Host and Network Name Lookups.�[0m
�[2mclient # [   12.734839] systemd[1]: Stopping Host and Network Name Lookups...�[0m
�[2mclient # [   12.736594] systemd[1]: Stopped target User and Group Name Lookups.�[0m
�[2mclient # [   12.737983] systemd[1]: Stopping User and Group Name Lookups...�[0m
�[2mclient # [   12.740159] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...�[0m
�[2mclient # [   12.742590] systemd[1]: nscd.service: Deactivated successfully.�[0m
�[2mclient # [   12.746774] systemd[1]: Stopped Name Service Cache Daemon (nsncd).�[0m
�[2mclient # [   12.755634] systemd[1]: Starting Name Service Cache Daemon (nsncd)...�[0m
�[2mserver # [   12.654685] systemd[1]: Started DHCP Client.�[0m
�[2mserver # [   12.659205] systemd[1]: Reached target Network is Online.�[0m
�[2mclient # [   12.766817] systemd[1]: Started DHCP Client.�[0m
�[2mclient # [   12.770712] systemd[1]: Reached target Network is Online.�[0m
�[2mserver # [   12.678051] nsncd[929]: Sep 14 01:21:09.421 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"�[0m
�[2mserver # [   12.681316] systemd[1]: Started Name Service Cache Daemon (nsncd).�[0m
�[2mserver # [   12.682215] systemd[1]: Reached target Multi-User System.�[0m
�[2mserver # [   12.684068] systemd[1]: Reached target Host and Network Name Lookups.�[0m
�[2mserver # [   12.684984] systemd[1]: Reached target User and Group Name Lookups.�[0m
�[2mclient # [   12.790191] systemd[1]: Started Name Service Cache Daemon (nsncd).�[0m
�[2mserver # [   12.686128] systemd[1]: Startup finished in 2.591s (kernel) + 10.092s (userspace) = 12.683s.�[0m
�[2mclient # [   12.791408] nsncd[880]: Sep 14 01:21:09.480 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"�[0m
�[2mclient # [   12.795730] systemd[1]: Reached target Multi-User System.�[0m
�[2mclient # [   12.796616] systemd[1]: Reached target Host and Network Name Lookups.�[0m
�[2mclient # [   12.798177] systemd[1]: Reached target User and Group Name Lookups.�[0m
�[2mclient # [   12.799087] systemd[1]: Startup finished in 2.638s (kernel) + 10.159s (userspace) = 12.797s.�[0m
�[2mserver # [   13.437584] bazarr[856]: 2024-09-14 01:21:10,181 - root                             (7f664ae7f740) :  ERROR (check_update:33) - Error trying to get releases from Github. Connection Error.�[0m
�[2mserver # [   14.010167] bazarr[856]: /nix/store/j8k87j8a3qshv2w0g7ajii59pmj6l7p1-bazarr-1.4.3/share/bazarr/bazarr/../custom_libs/subzero/language.py:165: SyntaxWarning: invalid escape sequence '\.'�[0m
�[2mserver # [   14.012261] bazarr[856]:   IETF_MATCH = ".+\.([^-.]+)(?:-[A-Za-z]+)?$"�[0m
�[2mserver # [   14.013217] bazarr[856]: /nix/store/j8k87j8a3qshv2w0g7ajii59pmj6l7p1-bazarr-1.4.3/share/bazarr/bazarr/../custom_libs/subzero/language.py:166: SyntaxWarning: invalid escape sequence '\.'�[0m
�[2mserver # [   14.016209] bazarr[856]:   ENDSWITH_LANGUAGECODE_RE = re.compile("\.([^-.]{2,3})(?:-[A-Za-z]{2,})?$")�[0m
�[2mserver # [   14.017396] bazarr[856]: /nix/store/j8k87j8a3qshv2w0g7ajii59pmj6l7p1-bazarr-1.4.3/share/bazarr/bazarr/../custom_libs/subzero/language.py:170: SyntaxWarning: invalid escape sequence '\.'�[0m
�[2mserver # [   14.019331] bazarr[856]:   language_match = re.match(".+\.([^\.]+)$" if not ietf�[0m
�[2mserver # [   16.223549] bazarr[856]: 2024-09-14 01:21:12,967 - root                             (7f664ae7f740) :  ERROR (announcements:51) - Error trying to get announcements from Github. Connection Error.�[0m
�[2mserver # [   16.496426] bazarr[856]: 2024-09-14 01:21:13,241 - root                             (7f664ae7f740) :  ERROR (check_update:33) - Error trying to get releases from Github. Connection Error.�[0m
�[2mserver # [   17.294957] bazarr[856]: /nix/store/j8k87j8a3qshv2w0g7ajii59pmj6l7p1-bazarr-1.4.3/share/bazarr/bazarr/../libs/tzlocal/unix.py:193: UserWarning: Can not find any timezone configuration, defaulting to UTC.�[0m
�[2mserver # [   17.298228] bazarr[856]:   warnings.warn("Can not find any timezone configuration, defaulting to UTC.")�[0m
�[2mserver # [   18.924860] bazarr[856]: 2024-09-14 01:21:15,669 - root                             (7f664ae7f740) :  ERROR (announcements:51) - Error trying to get announcements from Github. Connection Error.�[0m
�[2mserver # Connection to localhost (127.0.0.1) 6767 port [tcp/bmc-perf-agent] succeeded!�[0m
(finished: waiting for TCP port 6767 on localhost, in 12.46 seconds)
�[1m�[32msubtest: access�[0m
curl --show-error --location --cookie-jar cookie.txt --cookie cookie.txt --connect-to bazarr.example.com:443:server:443 --connect-to bazarr.example.com:80:server:80 --connect-to auth.example.com:443:server:443 --silent --output /dev/null --write-out '{"code":%{response_code}}' http://bazarr.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 # [   19.766477] bazarr[856]: 2024-09-14 01:21:16,511 - root                             (7f664ae7f740) :  INFO (server:79) - BAZARR is started and waiting for request on http://0.0.0.0:6767�[0m
(finished: subtest: access, in 0.10 seconds)
�[1m�[32msubtest: extraScript�[0m
�[1m�[32msubtest: health�[0m
curl --show-error --location --cookie-jar cookie.txt --cookie cookie.txt --connect-to bazarr.example.com:443:server:443 --connect-to bazarr.example.com:80:server:80 --connect-to auth.example.com:443:server:443 --silent --output /dev/null --write-out '{"code":%{response_code}}' bazarr.example.com/health
response = {'code': 200}
(finished: subtest: health, in 0.03 seconds)
�[1m�[32msubtest: login�[0m
curl --show-error --location --cookie-jar cookie.txt --cookie cookie.txt --connect-to bazarr.example.com:443:server:443 --connect-to bazarr.example.com:80:server:80 --connect-to auth.example.com:443:server:443 --silent --output /dev/null --write-out '{"code":%{response_code}}' bazarr.example.com/UI/Login
(finished: subtest: login, in 0.03 seconds)
�[1m�[32msubtest: apikey�[0m
server: �[1m�[32mmust succeed: cat /var/lib/bazarr/config.xml�[0m
(finished: must succeed: cat /var/lib/bazarr/config.xml, in 0.02 seconds)
(finished: subtest: apikey, in 0.02 seconds)
(finished: subtest: extraScript, in 0.08 seconds)
(finished: run the VM test script, in 20.79 seconds)
test script finished in 20.84s
�[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)