Frédéric Lécaille | b083c83 | 2018-08-23 18:06:35 +0200 | [diff] [blame] | 1 | # commit 7b6cc52784526c32efda44b873a4258d3ae0b8c7 |
| 2 | # BUG/MINOR: lua: Bad HTTP client request duration. |
| 3 | # |
| 4 | # HTTP LUA applet callback should not update the date on which the HTTP client requests |
| 5 | # arrive. This was done just after the LUA applet has completed its job. |
| 6 | # |
Ilya Shipitsin | 47d1718 | 2020-06-21 21:42:57 +0500 | [diff] [blame] | 7 | # This patch simply removes the affected statement. The same fix has been applied |
Frédéric Lécaille | b083c83 | 2018-08-23 18:06:35 +0200 | [diff] [blame] | 8 | # to TCP LUA applet callback. |
| 9 | # |
| 10 | # To reproduce this issue, as reported by Patrick Hemmer, implement an HTTP LUA applet |
| 11 | # which sleeps a bit before replying: |
| 12 | # |
| 13 | # core.register_service("foo", "http", function(applet) |
| 14 | # core.msleep(100) |
| 15 | # applet:set_status(200) |
| 16 | # applet:start_response() |
| 17 | # end) |
| 18 | # |
Ilya Shipitsin | 0188108 | 2021-08-07 14:41:56 +0500 | [diff] [blame] | 19 | # This had as a consequence to log %TR field with approximately the same value as |
Frédéric Lécaille | b083c83 | 2018-08-23 18:06:35 +0200 | [diff] [blame] | 20 | # the LUA sleep time. |
| 21 | |
| 22 | varnishtest "LUA bug" |
Christopher Faulet | fdafd9a | 2018-12-19 11:50:17 +0100 | [diff] [blame] | 23 | #REQUIRE_OPTIONS=LUA |
Frédéric Lécaille | dc1a3bd | 2019-03-29 15:07:24 +0100 | [diff] [blame] | 24 | #REGTEST_TYPE=bug |
Frédéric Lécaille | b083c83 | 2018-08-23 18:06:35 +0200 | [diff] [blame] | 25 | |
| 26 | feature ignore_unknown_macro |
| 27 | |
| 28 | syslog Slog { |
Frédéric Lécaille | b083c83 | 2018-08-23 18:06:35 +0200 | [diff] [blame] | 29 | recv info |
Willy Tarreau | 9c27ea0 | 2018-11-16 15:54:23 +0100 | [diff] [blame] | 30 | expect ~ "[^:\\[ ]\\[[0-9]*\\]: Ta=[0-9]* Tc=[0-9]* Td=[0-9]* Th=[0-9]* Ti=[0-9]* Tq=[0-9]* TR=[0-9]* Tr=[0-9]* Tt=[0-9]* Tw=[0-9]*$" |
Frédéric Lécaille | b083c83 | 2018-08-23 18:06:35 +0200 | [diff] [blame] | 31 | |
| 32 | recv info |
Willy Tarreau | 9c27ea0 | 2018-11-16 15:54:23 +0100 | [diff] [blame] | 33 | expect ~ "[^:\\[ ]\\[[0-9]*\\]: Tc=[0-9]* Td=[0-9]* Th=[0-9]* Tt=[0-9]* Tw=[0-9]*$" |
Frédéric Lécaille | b083c83 | 2018-08-23 18:06:35 +0200 | [diff] [blame] | 34 | } -start |
| 35 | |
| 36 | haproxy h1 -conf { |
| 37 | global |
Frédéric Lécaille | b894f92 | 2019-03-29 16:13:48 +0100 | [diff] [blame] | 38 | lua-load ${testdir}/bad_http_clt_req_duration.lua |
Frédéric Lécaille | b083c83 | 2018-08-23 18:06:35 +0200 | [diff] [blame] | 39 | |
| 40 | defaults |
Willy Tarreau | f673923 | 2021-11-18 17:46:22 +0100 | [diff] [blame^] | 41 | timeout client "${HAPROXY_TEST_TIMEOUT-5s}" |
| 42 | timeout server "${HAPROXY_TEST_TIMEOUT-5s}" |
| 43 | timeout connect "${HAPROXY_TEST_TIMEOUT-5s}" |
Frédéric Lécaille | b083c83 | 2018-08-23 18:06:35 +0200 | [diff] [blame] | 44 | |
| 45 | frontend f1 |
| 46 | mode http |
| 47 | bind "fd@${f1}" |
| 48 | log ${Slog_addr}:${Slog_port} daemon |
| 49 | log-format Ta=%Ta\ Tc=%Tc\ Td=%Td\ Th=%Th\ Ti=%Ti\ Tq=%Tq\ TR=%TR\ Tr=%Tr\ Tt=%Tt\ Tw=%Tw |
| 50 | default_backend b1 |
| 51 | |
| 52 | backend b1 |
| 53 | mode http |
Frédéric Lécaille | b083c83 | 2018-08-23 18:06:35 +0200 | [diff] [blame] | 54 | http-request use-service lua.foo.http |
| 55 | |
| 56 | frontend f2 |
| 57 | mode tcp |
| 58 | bind "fd@${f2}" |
| 59 | log ${Slog_addr}:${Slog_port} daemon |
| 60 | log-format Tc=%Tc\ Td=%Td\ Th=%Th\ Tt=%Tt\ Tw=%Tw |
| 61 | |
| 62 | tcp-request inspect-delay 1s |
| 63 | tcp-request content use-service lua.foo.tcp |
| 64 | } -start |
| 65 | |
| 66 | client c1 -connect "${h1_f1_sock}" { |
| 67 | txreq |
| 68 | rxresp |
| 69 | } -run |
| 70 | |
| 71 | client c2 -connect "${h1_f2_sock}" { |
| 72 | txreq |
| 73 | rxresp |
| 74 | } -run |
| 75 | |
| 76 | syslog Slog -wait |