# Checking port 52049
# Found port 52049
# Checking port 52050
# Found port 52050
Name: node
Data directory: C:\cirrus\build/testrun/libpq/005_negotiate_encryption\data/t_005_negotiate_encryption_node_data/pgdata
Backup directory: C:\cirrus\build/testrun/libpq/005_negotiate_encryption\data/t_005_negotiate_encryption_node_data/backup
Archive directory: C:\cirrus\build/testrun/libpq/005_negotiate_encryption\data/t_005_negotiate_encryption_node_data/archives
Connection string: port=52050 host=C:/Windows/TEMP/KgnJw6vfnC
Log file: C:\cirrus\build/testrun/libpq/005_negotiate_encryption\log/005_negotiate_encryption_node.log
[22:43:36.784](0.116s) # initializing database system by copying initdb template
# Running: robocopy /E /NJS /NJH /NFL /NDL /NP C:/cirrus/build/tmp_install/initdb-template C:\cirrus\build/testrun/libpq/005_negotiate_encryption\data/t_005_negotiate_encryption_node_data/pgdata

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/libpq/005_negotiate_encryption\data/t_005_negotiate_encryption_node_data/pgdata
### Starting node "node"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/libpq/005_negotiate_encryption\data/t_005_negotiate_encryption_node_data/pgdata -l C:\cirrus\build/testrun/libpq/005_negotiate_encryption\log/005_negotiate_encryption_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 6776
### Reloading node "node"
# Running: pg_ctl -D C:\cirrus\build/testrun/libpq/005_negotiate_encryption\data/t_005_negotiate_encryption_node_data/pgdata reload
server signaled
[22:43:39.940](3.156s) # Running tests with SSL and GSS disabled in the server
[22:43:40.055](0.115s) ok 1 -  'user=testuser gssencmode=disable sslmode=disable sslnegotiation=postgres' -> connect, authok -> plain
[22:43:40.221](0.166s) ok 2 -  'user=testuser gssencmode=disable sslmode=disable sslnegotiation=direct' -> connect, authok -> plain
[22:43:40.320](0.099s) ok 3 -  'user=testuser gssencmode=disable sslmode=disable sslnegotiation=requiredirect' -> connect, authok -> plain
[22:43:40.422](0.102s) ok 4 -  'user=testuser gssencmode=disable sslmode=allow sslnegotiation=postgres' -> connect, authok -> plain
[22:43:40.551](0.128s) ok 5 -  'user=testuser gssencmode=disable sslmode=allow sslnegotiation=direct' -> connect, authok -> plain
[22:43:40.647](0.096s) ok 6 -  'user=testuser gssencmode=disable sslmode=allow sslnegotiation=requiredirect' -> connect, authok -> plain
[22:43:40.773](0.126s) ok 7 -  'user=testuser gssencmode=disable sslmode=prefer sslnegotiation=postgres' -> connect, sslreject, authok -> plain
[22:43:40.945](0.172s) ok 8 -  'user=testuser gssencmode=disable sslmode=prefer sslnegotiation=direct' -> connect, directsslreject, reconnect, sslreject, authok -> plain
[22:43:41.114](0.169s) ok 9 -  'user=testuser gssencmode=disable sslmode=prefer sslnegotiation=requiredirect' -> connect, directsslreject, reconnect, authok -> plain
[22:43:41.238](0.124s) ok 10 -  'user=testuser gssencmode=disable sslmode=require sslnegotiation=postgres' -> connect, sslreject -> fail
[22:43:41.438](0.200s) ok 11 -  'user=testuser gssencmode=disable sslmode=require sslnegotiation=direct' -> connect, directsslreject, reconnect, sslreject -> fail
[22:43:41.614](0.176s) ok 12 -  'user=testuser gssencmode=disable sslmode=require sslnegotiation=requiredirect' -> connect, directsslreject -> fail
[22:43:41.722](0.108s) ok 13 -  'user=testuser gssencmode=prefer sslmode=disable sslnegotiation=postgres' -> connect, authok -> plain
[22:43:41.845](0.123s) ok 14 -  'user=testuser gssencmode=prefer sslmode=disable sslnegotiation=direct' -> connect, authok -> plain
[22:43:41.947](0.103s) ok 15 -  'user=testuser gssencmode=prefer sslmode=disable sslnegotiation=requiredirect' -> connect, authok -> plain
[22:43:42.025](0.077s) ok 16 -  'user=testuser gssencmode=prefer sslmode=allow sslnegotiation=postgres' -> connect, authok -> plain
[22:43:42.130](0.105s) ok 17 -  'user=testuser gssencmode=prefer sslmode=allow sslnegotiation=direct' -> connect, authok -> plain
[22:43:42.211](0.082s) ok 18 -  'user=testuser gssencmode=prefer sslmode=allow sslnegotiation=requiredirect' -> connect, authok -> plain
[22:43:42.296](0.085s) ok 19 -  'user=testuser gssencmode=prefer sslmode=prefer sslnegotiation=postgres' -> connect, sslreject, authok -> plain
[22:43:42.442](0.146s) ok 20 -  'user=testuser gssencmode=prefer sslmode=prefer sslnegotiation=direct' -> connect, directsslreject, reconnect, sslreject, authok -> plain
[22:43:42.605](0.163s) ok 21 -  'user=testuser gssencmode=prefer sslmode=prefer sslnegotiation=requiredirect' -> connect, directsslreject, reconnect, authok -> plain
[22:43:42.677](0.071s) ok 22 -  'user=testuser gssencmode=prefer sslmode=require sslnegotiation=postgres' -> connect, sslreject -> fail
[22:43:42.801](0.124s) ok 23 -  'user=testuser gssencmode=prefer sslmode=require sslnegotiation=direct' -> connect, directsslreject, reconnect, sslreject -> fail
[22:43:42.924](0.123s) ok 24 -  'user=testuser gssencmode=prefer sslmode=require sslnegotiation=requiredirect' -> connect, directsslreject -> fail
[22:43:42.975](0.051s) ok 25 -  'user=testuser gssencmode=require sslmode=disable sslnegotiation=postgres' -> - -> fail
[22:43:43.047](0.072s) ok 26 -  'user=testuser gssencmode=require sslmode=disable sslnegotiation=direct' -> - -> fail
[22:43:43.119](0.072s) ok 27 -  'user=testuser gssencmode=require sslmode=disable sslnegotiation=requiredirect' -> - -> fail
[22:43:43.223](0.105s) ok 28 -  'user=testuser gssencmode=require sslmode=allow sslnegotiation=postgres' -> - -> fail
[22:43:43.294](0.070s) ok 29 -  'user=testuser gssencmode=require sslmode=allow sslnegotiation=direct' -> - -> fail
[22:43:43.338](0.044s) ok 30 -  'user=testuser gssencmode=require sslmode=allow sslnegotiation=requiredirect' -> - -> fail
[22:43:43.434](0.096s) ok 31 -  'user=testuser gssencmode=require sslmode=prefer sslnegotiation=postgres' -> - -> fail
[22:43:43.495](0.062s) ok 32 -  'user=testuser gssencmode=require sslmode=prefer sslnegotiation=direct' -> - -> fail
[22:43:43.558](0.063s) ok 33 -  'user=testuser gssencmode=require sslmode=prefer sslnegotiation=requiredirect' -> - -> fail
[22:43:43.649](0.091s) ok 34 -  'user=testuser gssencmode=require sslmode=require sslnegotiation=postgres' -> - -> fail
[22:43:43.701](0.053s) ok 35 -  'user=testuser gssencmode=require sslmode=require sslnegotiation=direct' -> - -> fail
[22:43:43.786](0.084s) ok 36 -  'user=testuser gssencmode=require sslmode=require sslnegotiation=requiredirect' -> - -> fail
### Reloading node "node"
# Running: pg_ctl -D C:\cirrus\build/testrun/libpq/005_negotiate_encryption\data/t_005_negotiate_encryption_node_data/pgdata reload
server signaled
[22:43:43.832](0.046s) # Running tests with SSL enabled in server
[22:43:43.973](0.142s) ok 37 -  'user=testuser gssencmode=disable sslmode=disable sslnegotiation=postgres' -> connect, authok -> plain
[22:43:44.091](0.117s) ok 38 -  'user=testuser gssencmode=disable sslmode=disable sslnegotiation=direct' -> connect, authok -> plain
[22:43:44.198](0.108s) ok 39 -  'user=testuser gssencmode=disable sslmode=disable sslnegotiation=requiredirect' -> connect, authok -> plain
[22:43:44.286](0.088s) ok 40 -  'user=testuser gssencmode=disable sslmode=allow sslnegotiation=postgres' -> connect, authok -> plain
[22:43:44.377](0.092s) ok 41 -  'user=testuser gssencmode=disable sslmode=allow sslnegotiation=direct' -> connect, authok -> plain
[22:43:44.475](0.097s) ok 42 -  'user=testuser gssencmode=disable sslmode=allow sslnegotiation=requiredirect' -> connect, authok -> plain
[22:43:44.578](0.103s) ok 43 -  'user=testuser gssencmode=disable sslmode=prefer sslnegotiation=postgres' -> connect, sslaccept, authok -> ssl
[22:43:44.703](0.125s) ok 44 -  'user=testuser gssencmode=disable sslmode=prefer sslnegotiation=direct' -> connect, directsslaccept, authok -> ssl
[22:43:44.848](0.145s) ok 45 -  'user=testuser gssencmode=disable sslmode=prefer sslnegotiation=requiredirect' -> connect, directsslaccept, authok -> ssl
[22:43:44.946](0.098s) ok 46 -  'user=testuser gssencmode=disable sslmode=require sslnegotiation=postgres' -> connect, sslaccept, authok -> ssl
[22:43:45.036](0.090s) ok 47 -  'user=testuser gssencmode=disable sslmode=require sslnegotiation=direct' -> connect, directsslaccept, authok -> ssl
[22:43:45.154](0.118s) ok 48 -  'user=testuser gssencmode=disable sslmode=require sslnegotiation=requiredirect' -> connect, directsslaccept, authok -> ssl
[22:43:45.279](0.125s) ok 49 -  'user=ssluser gssencmode=disable sslmode=disable sslnegotiation=postgres' -> connect, authfail -> fail
[22:43:45.349](0.071s) ok 50 -  'user=ssluser gssencmode=disable sslmode=disable sslnegotiation=direct' -> connect, authfail -> fail
[22:43:45.433](0.083s) ok 51 -  'user=ssluser gssencmode=disable sslmode=disable sslnegotiation=requiredirect' -> connect, authfail -> fail
[22:43:45.555](0.122s) not ok 52 -  'user=ssluser gssencmode=disable sslmode=allow sslnegotiation=postgres' -> connect, authfail, reconnect, sslaccept, authok -> ssl
[22:43:45.555](0.000s) #   Failed test ' 'user=ssluser gssencmode=disable sslmode=allow sslnegotiation=postgres' -> connect, authfail, reconnect, sslaccept, authok -> ssl'
#   at C:/cirrus/src/interfaces/libpq/t/005_negotiate_encryption.pl line 320.
[22:43:45.556](0.000s) #          got: 'connect, authfail -> fail'
#     expected: 'connect, authfail, reconnect, sslaccept, authok -> ssl'
[22:43:45.556](0.000s) # psql: error: connection to server at "127.0.0.1", port 52050 failed: server closed the connection unexpectedly
# 	This probably means the server terminated abnormally
# 	before or while processing the request.
[22:43:45.725](0.169s) ok 53 -  'user=ssluser gssencmode=disable sslmode=allow sslnegotiation=direct' -> connect, authfail, reconnect, directsslaccept, authok -> ssl
[22:43:45.901](0.176s) ok 54 -  'user=ssluser gssencmode=disable sslmode=allow sslnegotiation=requiredirect' -> connect, authfail, reconnect, directsslaccept, authok -> ssl
[22:43:45.992](0.091s) ok 55 -  'user=ssluser gssencmode=disable sslmode=prefer sslnegotiation=postgres' -> connect, sslaccept, authok -> ssl
[22:43:46.117](0.125s) ok 56 -  'user=ssluser gssencmode=disable sslmode=prefer sslnegotiation=direct' -> connect, directsslaccept, authok -> ssl
[22:43:46.229](0.112s) ok 57 -  'user=ssluser gssencmode=disable sslmode=prefer sslnegotiation=requiredirect' -> connect, directsslaccept, authok -> ssl
[22:43:46.381](0.152s) ok 58 -  'user=ssluser gssencmode=disable sslmode=require sslnegotiation=postgres' -> connect, sslaccept, authok -> ssl
[22:43:46.496](0.115s) ok 59 -  'user=ssluser gssencmode=disable sslmode=require sslnegotiation=direct' -> connect, directsslaccept, authok -> ssl
[22:43:46.634](0.138s) ok 60 -  'user=ssluser gssencmode=disable sslmode=require sslnegotiation=requiredirect' -> connect, directsslaccept, authok -> ssl
[22:43:46.767](0.133s) ok 61 -  'user=nossluser gssencmode=disable sslmode=disable sslnegotiation=postgres' -> connect, authok -> plain
[22:43:46.852](0.085s) ok 62 -  'user=nossluser gssencmode=disable sslmode=disable sslnegotiation=direct' -> connect, authok -> plain
[22:43:46.927](0.075s) ok 63 -  'user=nossluser gssencmode=disable sslmode=disable sslnegotiation=requiredirect' -> connect, authok -> plain
[22:43:47.059](0.132s) ok 64 -  'user=nossluser gssencmode=disable sslmode=allow sslnegotiation=postgres' -> connect, authok -> plain
[22:43:47.192](0.133s) ok 65 -  'user=nossluser gssencmode=disable sslmode=allow sslnegotiation=direct' -> connect, authok -> plain
[22:43:47.285](0.093s) ok 66 -  'user=nossluser gssencmode=disable sslmode=allow sslnegotiation=requiredirect' -> connect, authok -> plain
[22:43:47.460](0.175s) ok 67 -  'user=nossluser gssencmode=disable sslmode=prefer sslnegotiation=postgres' -> connect, sslaccept, authfail, reconnect, authok -> plain
[22:43:47.593](0.133s) ok 68 -  'user=nossluser gssencmode=disable sslmode=prefer sslnegotiation=direct' -> connect, directsslaccept, authfail, reconnect, authok -> plain
[22:43:47.737](0.144s) ok 69 -  'user=nossluser gssencmode=disable sslmode=prefer sslnegotiation=requiredirect' -> connect, directsslaccept, authfail, reconnect, authok -> plain
[22:43:47.840](0.103s) ok 70 -  'user=nossluser gssencmode=disable sslmode=require sslnegotiation=postgres' -> connect, sslaccept, authfail -> fail
[22:43:47.926](0.086s) ok 71 -  'user=nossluser gssencmode=disable sslmode=require sslnegotiation=direct' -> connect, directsslaccept, authfail -> fail
[22:43:48.122](0.196s) ok 72 -  'user=nossluser gssencmode=disable sslmode=require sslnegotiation=requiredirect' -> connect, directsslaccept, authfail -> fail
### Reloading node "node"
# Running: pg_ctl -D C:\cirrus\build/testrun/libpq/005_negotiate_encryption\data/t_005_negotiate_encryption_node_data/pgdata reload
server signaled
[22:43:48.169](0.047s) ok 73 # skip GSSAPI/Kerberos not supported by this build
[22:43:48.169](0.000s) ok 74 # skip GSSAPI/Kerberos not supported by this build
[22:43:48.262](0.093s) ok 75 -  'user=localuser gssencmode=prefer sslmode=prefer host=C:/Windows/TEMP/KgnJw6vfnC' -> connect, authok -> plain
[22:43:48.322](0.060s) ok 76 -  'user=localuser gssencmode=require sslmode=prefer host=C:/Windows/TEMP/KgnJw6vfnC' -> - -> fail
[22:43:48.322](0.000s) 1..76
### Stopping node "node" using mode immediate
# Running: pg_ctl -D C:\cirrus\build/testrun/libpq/005_negotiate_encryption\data/t_005_negotiate_encryption_node_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "node"
[22:43:48.472](0.149s) # Looks like you failed 1 test of 76.