�[2mmachine # [    4.752230] systemd-logind[704]: Watching system buttons on /dev/input/event2 (Power Button)�[0m
�[2mmachine # [    4.753461] systemd-logind[704]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)�[0m
�[2mmachine # [    4.754376] systemd-logind[704]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)�[0m
�[2mmachine # [    4.759088] systemd[1]: Finished resolvconf update.�[0m
�[2mmachine # [    4.759623] systemd[1]: Reached target Preparation for Network.�[0m
�[2mmachine # [    4.763152] systemd[1]: Starting Address configuration of eth1...�[0m
�[2mmachine # [    4.763775] systemd[1]: Started User Login Management.�[0m
�[2mmachine # [    4.785501] systemd[1]: Started Name Service Cache Daemon (nsncd).�[0m
�[2mmachine # [    4.787403] nsncd[746]: Sep 16 01:25:35.769 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"�[0m
�[2mmachine # [    4.789402] systemd[1]: Reached target Host and Network Name Lookups.�[0m
�[2mmachine # [    4.790412] systemd[1]: Reached target User and Group Name Lookups.�[0m
�[2mmachine # [    4.886395] 8021q: adding VLAN 0 to HW filter on device eth1�[0m
�[2mmachine # [    4.816956] network-addresses-eth1-start[763]: adding address 192.168.1.1/24... done�[0m
�[2mmachine # [    4.823359] network-addresses-eth1-start[763]: adding address 2001:db8:1::1/64... done�[0m
�[2mmachine # [    4.834022] systemd[1]: Finished Address configuration of eth1.�[0m
�[2mmachine # [    4.836778] systemd[1]: Starting Networking Setup...�[0m
�[2mmachine # [    4.907062] systemd[1]: Finished Networking Setup.�[0m
�[2mmachine # [    4.910186] systemd[1]: Starting Extra networking commands....�[0m
�[2mmachine # [    4.931062] systemd[1]: Finished Extra networking commands..�[0m
�[2mmachine # [    4.931679] systemd[1]: Reached target Network.�[0m
�[2mmachine # [    4.935092] systemd[1]: Starting PostgreSQL Server...�[0m
�[2mmachine # [    4.936914] systemd[1]: Starting Permit User Sessions...�[0m
�[2mmachine # [    4.965070] systemd[1]: Finished Permit User Sessions.�[0m
�[2mmachine # [    5.020837] postgresql-pre-start[830]: The files belonging to this database system will be owned by user "postgres".�[0m
�[2mmachine # [    5.021974] postgresql-pre-start[830]: This user must also own the server process.�[0m
�[2mmachine # [    5.025986] postgresql-pre-start[830]: The database cluster will be initialized with locale "en_US.UTF-8".�[0m
�[2mmachine # [    5.026876] postgresql-pre-start[830]: The default database encoding has accordingly been set to "UTF8".�[0m
�[2mmachine # [    5.027707] postgresql-pre-start[830]: The default text search configuration will be set to "english".�[0m
�[2mmachine # [    5.028547] postgresql-pre-start[830]: Data page checksums are disabled.�[0m
�[2mmachine # [    5.029219] postgresql-pre-start[830]: fixing permissions on existing directory /var/lib/postgresql/16 ... ok�[0m
�[2mmachine # [    5.030129] postgresql-pre-start[830]: creating subdirectories ... ok�[0m
�[2mmachine # [    5.030719] postgresql-pre-start[830]: selecting dynamic shared memory implementation ... posix�[0m
�[2mmachine # [    5.072150] postgresql-pre-start[830]: selecting default max_connections ... 100�[0m
�[2mmachine # [    5.101523] postgresql-pre-start[830]: selecting default shared_buffers ... 128MB�[0m
�[2mmachine # [    5.857036] 8021q: adding VLAN 0 to HW filter on device eth0�[0m
�[2mmachine # [    5.781935] dhcpcd[682]: eth0: waiting for carrier�[0m
�[2mmachine # [    5.783150] dhcpcd[682]: eth0: carrier acquired�[0m
�[2mmachine # [    5.789824] dhcpcd[682]: DUID 00:01:00:01:2e:7a:45:90:52:54:00:12:34:56�[0m
�[2mmachine # [    5.790666] dhcpcd[682]: eth0: IAID 00:12:34:56�[0m
�[2mmachine # [    5.791714] dhcpcd[682]: eth0: adding address fe80::5054:ff:fe12:3456�[0m
�[2mmachine # [    5.901036] dhcpcd[682]: eth0: soliciting an IPv6 router�[0m
�[2mmachine # [    6.025472] dhcpcd[682]: eth0: soliciting a DHCP lease�[0m
�[2mmachine # [    6.115144] NET: Registered PF_PACKET protocol family�[0m
�[2mmachine # [    6.045069] dhcpcd[682]: eth0: offered 10.0.2.15 from 10.0.2.2�[0m
�[2mmachine # [    6.046318] dhcpcd[682]: eth0: probing address 10.0.2.15/24�[0m
�[2mmachine # [    6.199099] postgresql-pre-start[830]: selecting default time zone ... UTC�[0m
�[2mmachine # [    6.201451] postgresql-pre-start[830]: creating configuration files ... ok�[0m
�[2mmachine # [    6.408138] postgresql-pre-start[830]: running bootstrap script ... ok�[0m
�[2mmachine # [    7.099942] postgresql-pre-start[830]: performing post-bootstrap initialization ... ok�[0m
�[2mmachine # [    7.226857] postgresql-pre-start[830]: syncing data to disk ... ok�[0m
�[2mmachine # [    7.227699] postgresql-pre-start[830]: initdb: warning: enabling "trust" authentication for local connections�[0m
�[2mmachine # [    7.229053] postgresql-pre-start[830]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.�[0m
�[2mmachine # [    7.230524] postgresql-pre-start[830]: Success. You can now start the database server using:�[0m
�[2mmachine # [    7.231333] postgresql-pre-start[830]:     /nix/store/9l9cxmwzrp1pfsrvzfrbsz0c1jhrracq-postgresql-and-plugins-16.4/bin/pg_ctl -D /var/lib/postgresql/16 -l logfile start�[0m
�[2mmachine # [    7.286441] postgres[859]: [859] LOG:  starting PostgreSQL 16.4 on x86_64-pc-linux-gnu, compiled by gcc (GCC) 13.3.0, 64-bit�[0m
�[2mmachine # [    7.288113] postgres[859]: [859] LOG:  listening on IPv4 address "0.0.0.0", port 5432�[0m
�[2mmachine # [    7.288918] postgres[859]: [859] LOG:  listening on IPv6 address "::", port 5432�[0m
�[2mmachine # [    7.290406] postgres[859]: [859] LOG:  listening on Unix socket "/run/postgresql/.s.PGSQL.5432"�[0m
�[2mmachine # [    7.297201] postgres[862]: [862] LOG:  database system was shut down at 2024-09-16 01:25:38 GMT�[0m
�[2mmachine # [    7.300598] postgres[859]: [859] LOG:  database system is ready to accept connections�[0m
�[2mmachine # [    7.412600] postgresql-post-start[873]: CREATE DATABASE�[0m
�[2mmachine # [    7.441185] postgresql-post-start[878]: CREATE ROLE�[0m
�[2mmachine # [    7.455158] postgresql-post-start[880]: ALTER ROLE�[0m
�[2mmachine # [    7.469524] postgresql-post-start[882]: ALTER DATABASE�[0m
�[2mmachine # [    7.492227] postgresql-post-start[884]: DO�[0m
�[2mmachine # [    7.494722] systemd[1]: Started PostgreSQL Server.�[0m
�[2mmachine # [    7.808085] dhcpcd[682]: eth0: Router Advertisement from fe80::2�[0m
�[2mmachine # [    7.809910] dhcpcd[682]: eth0: adding address fec0::5054:ff:fe12:3456/64�[0m
�[2mmachine # [    7.810660] dhcpcd[682]: eth0: adding route to fec0::/64�[0m
�[2mmachine # [    7.811238] dhcpcd[682]: eth0: adding default route via fe80::2�[0m
(finished: waiting for unit postgresql.service, in 8.30 seconds)
machine: �[1m�[32mwaiting for TCP port 5432 on localhost�[0m
�[2mmachine # Connection to localhost (::1) 5432 port [tcp/postgresql] succeeded!�[0m
(finished: waiting for TCP port 5432 on localhost, in 0.04 seconds)
�[1m�[32msubtest: can peer login with provisioned user and database�[0m
machine: �[1m�[32mmust succeed: sudo -u me psql -U me me --command ""�[0m
�[2mmachine # [    7.977191] sudo[899]: �[0;1;39m    root : TTY=hvc0 ; PWD=/tmp ; USER=me ; COMMAND=/run/current-system/sw/bin/psql -U me me --command�[0m�[0m
�[2mmachine # [    7.985280] sudo[899]: pam_unix(sudo:session): session opened for user me(uid=998) by (uid=0)�[0m
�[2mmachine # [    8.006097] sudo[899]: pam_unix(sudo:session): session closed for user me�[0m
(finished: must succeed: sudo -u me psql -U me me --command "", in 0.07 seconds)
(finished: subtest: can peer login with provisioned user and database, in 0.07 seconds)
�[1m�[32msubtest: can tcpip login with provisioned user and database�[0m
machine: �[1m�[32mmust succeed: PGPASSWORD=secretpw psql -h 127.0.0.1 -p 5432 -U me me --command ""�[0m
(finished: must succeed: PGPASSWORD=secretpw psql -h 127.0.0.1 -p 5432 -U me me --command "", in 0.04 seconds)
(finished: subtest: can tcpip login with provisioned user and database, in 0.04 seconds)
�[1m�[32msubtest: cannot tcpip login with wrong password�[0m
machine: �[1m�[32mmust fail: PGPASSWORD=oops psql -h 127.0.0.1 -p 5432 -U me me --command ""�[0m
�[2mmachine # psql: error: connection to server at "127.0.0.1", port 5432 failed: FATAL:  password authentication failed for user "me"�[0m
�[2mmachine # [    8.077934] postgres[913]: [913] FATAL:  password authentication failed for user "me"�[0m
�[2mmachine # [    8.079817] postgres[913]: [913] DETAIL:  Password does not match for user "me".�[0m
�[2mmachine # [    8.080559] postgres[913]: 	Connection matched file "/nix/store/z54fw7ifplh3rd1p8fpaxalp7mrsvkfa-pg_hba.conf" line 4: "host  all      all    127.0.0.1/32   password"�[0m
(finished: must fail: PGPASSWORD=oops psql -h 127.0.0.1 -p 5432 -U me me --command "", in 0.04 seconds)
(finished: subtest: cannot tcpip login with wrong password, in 0.04 seconds)
(finished: run the VM test script, in 8.62 seconds)
test script finished in 8.65s
�[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)
(finished: cleanup, in 0.01 seconds)
kill vlan (pid 6)