REGTESTS: log: add a test for log-profile

Try to cover some common use-cases for "log-profile" feature. The tests
mainly focus on log-profile section declaration, and testing the behavior
of logformat / log-tag overriding capabilities.

For now, the use of log-profiles is somewhat limited because we lack
the ability to explicitly trigger the log building process at specific
steps during the stream handling. Indeed, for now we rely on
"option logasap" and proxy log-format string content "hacks" to force
the log emission at some specific steps, thus more tests should be added
over the time, when new mechanisms allowing the emission of logs at
expected processing steps will be added, or if new keywords are added to
the log-profile section.

This test requires versions >= 3.0-dev1

diff --git a/reg-tests/log/log_profiles.vtc b/reg-tests/log/log_profiles.vtc
new file mode 100644 (file)
index 0000000..fc09639
--- /dev/null
+++ b/reg-tests/log/log_profiles.vtc
@@ -0,0 +1,171 @@
+varnishtest "Test log-profile feature"
+feature cmd "$HAPROXY_PROGRAM -cc 'version_atleast(3.1-dev0)'"
+feature ignore_unknown_macro
+
+barrier b1 cond 4 -cyclic
+barrier b2 cond 4 -cyclic
+barrier b3 cond 3 -cyclic
+barrier b4 cond 2 -cyclic
+
+server s1 {
+    rxreq
+       txresp
+} -repeat 3 -start
+
+syslog Slg1 -level info {
+    recv
+    #rfc5424, no profile and option httplog:
+    expect ~ ".* haproxy ${h1_pid} .* \"GET / HTTP/1.1\""
+    barrier b1 sync
+
+    recv
+    #rfc5424, no profile, tcp and option logasap
+    expect ~ ".* haproxy ${h1_pid} .* \\+0"
+    barrier b2 sync
+} -start
+
+syslog Slg2 -level info {
+    recv
+    #rfc5424, logprof1 httplog
+    expect ~ ".* haproxy ${h1_pid} .* sdclose close"
+    recv
+    #rfc5424, logprof2 httplog
+    expect ~ ".* custom ${h1_pid} .* - txn_close"
+    barrier b1 sync
+
+    recv
+    #rfc5424, logprof1 tcp logasap
+    expect ~ ".* haproxy ${h1_pid} .* \\+0"
+    barrier b2 sync
+
+    recv
+    #rfc5424, logprof1 tcp
+    expect ~ ".* haproxy ${h1_pid} .* sdclose close"
+    barrier b3 sync
+} -start
+
+syslog Slg3 -level info {
+    recv
+    #rfc3164, logprof1 and option httplog
+    expect ~ "[^:\\[ ]\\[${h1_pid}\\]: close"
+    barrier b1 sync
+
+    recv
+    #rfc5424, logprof2, tcp and option logasap
+    expect ~ ".* custom ${h1_pid} .* - txn_connect"
+    barrier b2 sync
+
+    recv
+    #rfc5424, logprof2 tcp
+    expect ~ ".* custom ${h1_pid} .* - txn_close"
+    barrier b3 sync
+
+    recv
+    #rfc5424, logprof2, tcp error
+    expect ~ ".* custom ${h1_pid} .* error"
+    barrier b4 sync
+} -start
+
+haproxy h1 -conf {
+       defaults
+               timeout connect "${HAPROXY_TEST_TIMEOUT-5s}"
+               timeout client  "${HAPROXY_TEST_TIMEOUT-5s}"
+               timeout server  "${HAPROXY_TEST_TIMEOUT-5s}"
+               log-tag "haproxy"
+
+       frontend fe1
+               bind "fd@${fe_1}"
+               mode http
+               option httplog
+               log udp@${Slg1_addr}:${Slg1_port} format rfc5424 local0
+               log udp@${Slg2_addr}:${Slg2_port} format rfc5424 profile logprof1 local0
+               log udp@${Slg2_addr}:${Slg2_port} format rfc5424 profile logprof2 local0
+               log udp@${Slg3_addr}:${Slg3_port} format rfc3164 profile logprof1 local0
+
+               default_backend be
+
+       frontend fe2
+               bind "fd@${fe_2}"
+               mode tcp
+               log-format "%B"
+               option logasap
+               log udp@${Slg1_addr}:${Slg1_port} format rfc5424 local0
+               log udp@${Slg2_addr}:${Slg2_port} format rfc5424 profile logprof1 local0
+               log udp@${Slg3_addr}:${Slg3_port} format rfc5424 profile logprof2 local0
+
+               default_backend be_tcp
+
+       frontend fe3
+               bind "fd@${fe_3}"
+               mode tcp
+               log-format "%B"
+               log udp@${Slg2_addr}:${Slg2_port} format rfc5424 profile logprof1 local0
+               log udp@${Slg3_addr}:${Slg3_port} format rfc5424 profile logprof2 local0
+
+               default_backend be_tcp
+
+       listen fe4
+               bind "fd@${fe_4}"
+               timeout client 5ms
+               mode http
+               log-format "%B"
+               log udp@${Slg3_addr}:${Slg3_port} format rfc5424 profile logprof2 local0
+
+       log-profile logprof1
+               on close format "close" sd "sdclose"
+
+       log-profile logprof2
+               log-tag "custom"
+               on error format "error"
+               on any format "%OG"
+
+       backend be
+               mode http
+               server app1 ${s1_addr}:${s1_port}
+
+       backend be_tcp
+               mode tcp
+               server app1 ${s1_addr}:${s1_port}
+
+
+} -start
+
+# requests generators (to trigger log emission)
+
+client c1 -connect ${h1_fe_1_sock} {
+    txreq -url "/"
+    rxresp
+    expect resp.status == 200
+} -start -wait
+
+# Wait matching log messages
+barrier b1 sync
+
+client c2 -connect ${h1_fe_2_sock} {
+    txreq -url "/"
+    rxresp
+    expect resp.status == 200
+} -start -wait
+
+# Wait matching log messages
+barrier b2 sync
+
+client c3 -connect ${h1_fe_3_sock} {
+    txreq -url "/"
+    rxresp
+    expect resp.status == 200
+} -start -wait
+
+# Wait matching log messages
+barrier b3 sync
+
+client c4 -connect ${h1_fe_4_sock} {
+    # do nothing to trigger an error (fe_4 timeouts after 5ms)
+} -start -wait
+
+# Wait matching log messages
+barrier b4 sync
+
+syslog Slg1 -wait
+syslog Slg2 -wait
+syslog Slg3 -wait