+01:01:01 p1235 Exim version x.yz uid=CALLER_UID gid=CALLER_GID pid=p1235 D=fff9ffff
+01:01:01 p1235 Hints DB:
+01:01:01 p1235 macros_trusted overridden to true by whitelisting
+01:01:01 p1235 changed uid/gid: forcing real = effective
+01:01:01 p1235 uid=uuuu gid=CALLER_GID pid=p1235
+01:01:01 p1235 environment after trimming:
+01:01:01 p1235 PATH=<munged>
+01:01:01 p1235 adding SSLKEYLOGFILE=TESTSUITE/spool/sslkeys
+01:01:01 p1235 configuration file is TESTSUITE/test-config
+01:01:01 p1235 admin user
+01:01:01 p1235 changed uid/gid: privilege not needed
+01:01:01 p1235 uid=EXIM_UID gid=EXIM_GID pid=p1235
+01:01:01 p1235 seeking password data for user "CALLER": cache not available
+01:01:01 p1235 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
+01:01:01 p1235 try option gecos_pattern
+01:01:01 p1235 try option gecos_name
+01:01:01 p1235 try option unknown_login
+01:01:01 p1235 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
+01:01:01 p1235 sender address = CALLER@myhost.test.ex
+01:01:01 p1235 try option smtp_active_hostname
+01:01:01 p1235 sender_fullhost = [10.0.0.0]
+01:01:01 p1235 sender_rcvhost = [10.0.0.0]
+01:01:01 p1235 host in hosts_connection_nolog? no (option unset)
+01:01:01 p1235 LOG: smtp_connection MAIN
+01:01:01 p1235 SMTP connection from [10.0.0.0]
+01:01:01 p1235 try option message_size_limit
+01:01:01 p1235 host in host_lookup? no (option unset)
+01:01:01 p1235 set_process_info: pppp handling incoming connection from [10.0.0.0]
+01:01:01 p1235 host in host_reject_connection? no (option unset)
+01:01:01 p1235 host in sender_unqualified_hosts? no (option unset)
+01:01:01 p1235 host in recipient_unqualified_hosts? no (option unset)
+01:01:01 p1235 host in helo_verify_hosts? no (option unset)
+01:01:01 p1235 host in helo_try_verify_hosts? no (option unset)
+01:01:01 p1235 host in helo_accept_junk_hosts? no (option unset)
+01:01:01 p1235 try option acl_smtp_connect
+01:01:01 p1235 try option smtp_banner
+01:01:01 p1235 ╭considering: $smtp_active_hostname░ESMTP░Exim░$version_number░$tod_full
+01:01:01 p1235 ├──────value: myhost.test.ex
+01:01:01 p1235 ├considering: ░ESMTP░Exim░$version_number░$tod_full
+01:01:01 p1235 ├───────text: ░ESMTP░Exim░
+01:01:01 p1235 ├considering: $version_number░$tod_full
+01:01:01 p1235 ├──────value: x.yz
+01:01:01 p1235 ├considering: ░$tod_full
+01:01:01 p1235 ├───────text: ░
+01:01:01 p1235 ├considering: $tod_full
+01:01:01 p1235 ├──────value: Tue,░2░Mar░1999░09:44:33░+0000
+01:01:01 p1235 ├───expanded: $smtp_active_hostname░ESMTP░Exim░$version_number░$tod_full
+01:01:01 p1235 ╰─────result: myhost.test.ex░ESMTP░Exim░x.yz░Tue,░2░Mar░1999░09:44:33░+0000
+01:01:01 p1235 host in pipelining_connect_advertise_hosts?
+01:01:01 p1235 list element:
+01:01:01 p1235 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
+01:01:01 p1235 smtp_setup_msg entered
+01:01:01 p1235 SMTP<< helo test
+01:01:01 p1235 test in helo_lookup_domains?
+01:01:01 p1235 list element: @
+01:01:01 p1235 list element: @[]
+01:01:01 p1235 test in helo_lookup_domains? no (end of list)
+01:01:01 p1235 sender_fullhost = (test) [10.0.0.0]
+01:01:01 p1235 sender_rcvhost = [10.0.0.0] (helo=test)
+01:01:01 p1235 set_process_info: pppp handling incoming connection from (test) [10.0.0.0]
+01:01:01 p1235 spf_conn_init: test 10.0.0.0
+01:01:01 p1235 SPF_dns_exim_new
+01:01:01 p1235 try option acl_smtp_helo
+01:01:01 p1235 SMTP>> 250 myhost.test.ex Hello test [10.0.0.0]
+01:01:01 p1235 SMTP<< mail from:<a@b>
+01:01:01 p1235 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
+01:01:01 p1235 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
+01:01:01 p1235 try option acl_smtp_mail
+01:01:01 p1235 SMTP>> 250 OK
+01:01:01 p1235 SMTP<< rcpt to:<c@d>
+01:01:01 p1235 try option acl_smtp_rcpt
+01:01:01 p1235 using ACL "check_recipient"
+01:01:01 p1235 processing "warn" (TESTSUITE/test-config 26)
+01:01:01 p1235 ╭considering: ok:░░░░${lookup░mysql░░░░░░░░░░░░░░░░░░░░{select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ├───────text: ok:░░░░
+01:01:01 p1235 ├considering: ${lookup░mysql░░░░░░░░░░░░░░░░░░░░{select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ╭considering: select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ├───────text: select░name░from░them░where░id░=░'
+01:01:01 p1235 ├considering: ${quote_mysql:$local_part}'}}
+01:01:01 p1235 ╭considering: $local_part}'}}
+01:01:01 p1235 ├──────value: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├considering: }'}}
+01:01:01 p1235 ├───expanded: $local_part
+01:01:01 p1235 ╰─────result: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├─────op-res: c
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 ├considering: '}}
+01:01:01 p1235 ├───────text: '
+01:01:01 p1235 ├considering: }}
+01:01:01 p1235 ├───expanded: select░name░from░them░where░id░=░'${quote_mysql:$local_part}'
+01:01:01 p1235 ╰─────result: select░name░from░them░where░id░=░'c'
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 search_open: mysql "NULL"
+01:01:01 p1235 search_find: file="NULL"
+01:01:01 p1235 key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
+01:01:01 p1235 LRU list:
+01:01:01 p1235 internal_search_find: file="NULL"
+01:01:01 p1235 type=mysql key="select name from them where id = 'c'" opts=NULL
+01:01:01 p1235 database lookup required for select name from them where id = 'c'
+01:01:01 p1235 (tainted, quoted:mysql)
+01:01:01 p1235 MySQL query: "select name from them where id = 'c'" opts 'NULL'
+01:01:01 p1235 MYSQL new connection: host=127.0.0.1 port=PORT_N socket=NULL database=test user=root
+01:01:01 p1235 MYSQL: no data found
+01:01:01 p1235 creating new cache entry
+01:01:01 p1235 lookup failed
+01:01:01 p1235 ├───item-res:
+01:01:01 p1235 ├───expanded: ok:░░░░${lookup░mysql░░░░░░░░░░░░░░░░░░░░{select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ╰─────result: ok:░░░░
+01:01:01 p1235 check set acl_m0 = ok: ${lookup mysql {select name from them where id = '${quote_mysql:$local_part}'}}
+01:01:01 p1235 = ok:
+01:01:01 p1235 ╭considering: FAIL1:░${lookup░mysql,no_rd░░░░░░░░░░░░░░{select░name░from░them░where░id░=░'$local_part'}}
+01:01:01 p1235 ├───────text: FAIL1:░
+01:01:01 p1235 ├considering: ${lookup░mysql,no_rd░░░░░░░░░░░░░░{select░name░from░them░where░id░=░'$local_part'}}
+01:01:01 p1235 ╭considering: select░name░from░them░where░id░=░'$local_part'}}
+01:01:01 p1235 ├───────text: select░name░from░them░where░id░=░'
+01:01:01 p1235 ├considering: $local_part'}}
+01:01:01 p1235 ├──────value: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├considering: '}}
+01:01:01 p1235 ├───────text: '
+01:01:01 p1235 ├considering: }}
+01:01:01 p1235 ├───expanded: select░name░from░them░where░id░=░'$local_part'
+01:01:01 p1235 ╰─────result: select░name░from░them░where░id░=░'c'
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 search_open: mysql "NULL"
+01:01:01 p1235 cached open
+01:01:01 p1235 search_find: file="NULL"
+01:01:01 p1235 key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="no_rd"
+01:01:01 p1235 LRU list:
+01:01:01 p1235 internal_search_find: file="NULL"
+01:01:01 p1235 type=mysql key="select name from them where id = 'c'" opts="no_rd"
+01:01:01 p1235 cached data found but wrong opts; database lookup required for select name from them where id = 'c'
+01:01:01 p1235 (tainted)
+01:01:01 p1235 LOG: MAIN PANIC
+01:01:01 p1235 tainted search query is not properly quoted (ACL warn, TESTSUITE/test-config 26): select name from them where id = 'c'
+01:01:01 p1235 required_quoter_id NN (mysql) quoting -1 (NULL)
+01:01:01 p1235 MySQL query: "select name from them where id = 'c'" opts 'no_rd'
+01:01:01 p1235 MYSQL using cached connection for 127.0.0.1:PORT_N/test/root
+01:01:01 p1235 MYSQL: no data found
+01:01:01 p1235 replacing old cache entry
+01:01:01 p1235 lookup failed
+01:01:01 p1235 ├───item-res:
+01:01:01 p1235 ├───expanded: FAIL1:░${lookup░mysql,no_rd░░░░░░░░░░░░░░{select░name░from░them░where░id░=░'$local_part'}}
+01:01:01 p1235 ╰─────result: FAIL1:░
+01:01:01 p1235 check set acl_m0 = FAIL1: ${lookup mysql,no_rd {select name from them where id = '$local_part'}}
+01:01:01 p1235 = FAIL1:
+01:01:01 p1235 warn: condition test succeeded in ACL "check_recipient"
+01:01:01 p1235 processing "warn" (TESTSUITE/test-config 31)
+01:01:01 p1235 ╭considering: ok:░░░░${lookup░mysql,servers=127.0.0.1::PORT_N/test/root/pass░░░░░░{select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ├───────text: ok:░░░░
+01:01:01 p1235 ├considering: ${lookup░mysql,servers=127.0.0.1::PORT_N/test/root/pass░░░░░░{select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ╭considering: select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ├───────text: select░name░from░them░where░id░=░'
+01:01:01 p1235 ├considering: ${quote_mysql:$local_part}'}}
+01:01:01 p1235 ╭considering: $local_part}'}}
+01:01:01 p1235 ├──────value: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├considering: }'}}
+01:01:01 p1235 ├───expanded: $local_part
+01:01:01 p1235 ╰─────result: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├─────op-res: c
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 ├considering: '}}
+01:01:01 p1235 ├───────text: '
+01:01:01 p1235 ├considering: }}
+01:01:01 p1235 ├───expanded: select░name░from░them░where░id░=░'${quote_mysql:$local_part}'
+01:01:01 p1235 ╰─────result: select░name░from░them░where░id░=░'c'
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 search_open: mysql "NULL"
+01:01:01 p1235 cached open
+01:01:01 p1235 search_find: file="NULL"
+01:01:01 p1235 key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=127.0.0.1::PORT_N/test/root/pass"
+01:01:01 p1235 LRU list:
+01:01:01 p1235 internal_search_find: file="NULL"
+01:01:01 p1235 type=mysql key="select name from them where id = 'c'" opts="servers=127.0.0.1::PORT_N/test/root/pass"
+01:01:01 p1235 cached data found but wrong opts; database lookup required for select name from them where id = 'c'
+01:01:01 p1235 (tainted, quoted:mysql)
+01:01:01 p1235 MySQL query: "select name from them where id = 'c'" opts 'servers=127.0.0.1::PORT_N/test/root/pass'
+01:01:01 p1235 MYSQL using cached connection for 127.0.0.1:PORT_N/test/root
+01:01:01 p1235 MYSQL: no data found
+01:01:01 p1235 replacing old cache entry
+01:01:01 p1235 lookup failed
+01:01:01 p1235 ├───item-res:
+01:01:01 p1235 ├───expanded: ok:░░░░${lookup░mysql,servers=127.0.0.1::PORT_N/test/root/pass░░░░░░{select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ╰─────result: ok:░░░░
+01:01:01 p1235 check set acl_m0 = ok: ${lookup mysql,servers=127.0.0.1::PORT_N/test/root/pass {select name from them where id = '${quote_mysql:$local_part}'}}
+01:01:01 p1235 = ok:
+01:01:01 p1235 ╭considering: ok:░░░░${lookup░mysql,servers=127.0.0.1::PORT_N░░░░{select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ├───────text: ok:░░░░
+01:01:01 p1235 ├considering: ${lookup░mysql,servers=127.0.0.1::PORT_N░░░░{select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ╭considering: select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ├───────text: select░name░from░them░where░id░=░'
+01:01:01 p1235 ├considering: ${quote_mysql:$local_part}'}}
+01:01:01 p1235 ╭considering: $local_part}'}}
+01:01:01 p1235 ├──────value: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├considering: }'}}
+01:01:01 p1235 ├───expanded: $local_part
+01:01:01 p1235 ╰─────result: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├─────op-res: c
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 ├considering: '}}
+01:01:01 p1235 ├───────text: '
+01:01:01 p1235 ├considering: }}
+01:01:01 p1235 ├───expanded: select░name░from░them░where░id░=░'${quote_mysql:$local_part}'
+01:01:01 p1235 ╰─────result: select░name░from░them░where░id░=░'c'
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 search_open: mysql "NULL"
+01:01:01 p1235 cached open
+01:01:01 p1235 search_find: file="NULL"
+01:01:01 p1235 key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=127.0.0.1::PORT_N"
+01:01:01 p1235 LRU list:
+01:01:01 p1235 internal_search_find: file="NULL"
+01:01:01 p1235 type=mysql key="select name from them where id = 'c'" opts="servers=127.0.0.1::PORT_N"
+01:01:01 p1235 cached data found but wrong opts; database lookup required for select name from them where id = 'c'
+01:01:01 p1235 (tainted, quoted:mysql)
+01:01:01 p1235 MySQL query: "select name from them where id = 'c'" opts 'servers=127.0.0.1::PORT_N'
+01:01:01 p1235 MYSQL using cached connection for 127.0.0.1:PORT_N/test/root
+01:01:01 p1235 MYSQL: no data found
+01:01:01 p1235 replacing old cache entry
+01:01:01 p1235 lookup failed
+01:01:01 p1235 ├───item-res:
+01:01:01 p1235 ├───expanded: ok:░░░░${lookup░mysql,servers=127.0.0.1::PORT_N░░░░{select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ╰─────result: ok:░░░░
+01:01:01 p1235 check set acl_m0 = ok: ${lookup mysql,servers=127.0.0.1::PORT_N {select name from them where id = '${quote_mysql:$local_part}'}}
+01:01:01 p1235 = ok:
+01:01:01 p1235 ╭considering: FAIL2:░${lookup░mysql░░░░░{servers=127.0.0.1::PORT_N/test/root/pass;░select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ├───────text: FAIL2:░
+01:01:01 p1235 ├considering: ${lookup░mysql░░░░░{servers=127.0.0.1::PORT_N/test/root/pass;░select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ╭considering: servers=127.0.0.1::PORT_N/test/root/pass;░select░name░from░them░where░id░=░'${quote_mysql:$local_part}'}}
+01:01:01 p1235 ├───────text: servers=127.0.0.1::PORT_N/test/root/pass;░select░name░from░them░where░id░=░'
+01:01:01 p1235 ├considering: ${quote_mysql:$local_part}'}}
+01:01:01 p1235 ╭considering: $local_part}'}}
+01:01:01 p1235 ├──────value: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├considering: }'}}
+01:01:01 p1235 ├───expanded: $local_part
+01:01:01 p1235 ╰─────result: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├─────op-res: c
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 ├considering: '}}
+01:01:01 p1235 ├───────text: '
+01:01:01 p1235 ├considering: }}
+01:01:01 p1235 ├───expanded: servers=127.0.0.1::PORT_N/test/root/pass;░select░name░from░them░where░id░=░'${quote_mysql:$local_part}'
+01:01:01 p1235 ╰─────result: servers=127.0.0.1::PORT_N/test/root/pass;░select░name░from░them░where░id░=░'c'
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 search_open: mysql "NULL"
+01:01:01 p1235 cached open
+01:01:01 p1235 search_find: file="NULL"
+01:01:01 p1235 key="servers=127.0.0.1::PORT_N/test/root/pass; select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
+01:01:01 p1235 LRU list:
+01:01:01 p1235 internal_search_find: file="NULL"
+01:01:01 p1235 type=mysql key="servers=127.0.0.1::PORT_N/test/root/pass; select name from them where id = 'c'" opts=NULL
+01:01:01 p1235 database lookup required for servers=127.0.0.1::PORT_N/test/root/pass; select name from them where id = 'c'
+01:01:01 p1235 (tainted, quoted:mysql)
+01:01:01 p1235 MySQL query: "servers=127.0.0.1::PORT_N/test/root/pass; select name from them where id = 'c'" opts 'NULL'
+01:01:01 p1235 LOG: MAIN
+01:01:01 p1235 Exim configuration error in line 89 of TESTSUITE/test-config:
+01:01:01 p1235 WARNING: obsolete syntax used for lookup
+01:01:01 p1235 lookup deferred: MySQL server "127.0.0.1:PORT_N/test" is tainted
+01:01:01 p1235 ├failed to expand: FAIL2: ${lookup mysql {servers=127.0.0.1::PORT_N/test/root/pass; select name from them where id = '${quote_mysql:$local_part}'}}
+01:01:01 p1235 ╰───error message: lookup of "servers=127.0.0.1::PORT_N/test/root/pass; select name from them where id = 'c'" gave DEFER: MySQL server "127.0.0.1:1223/test" is tainted
+01:01:01 p1235 warn: condition test deferred in ACL "check_recipient"
+01:01:01 p1235 LOG: MAIN
+01:01:01 p1235 H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: MySQL server "127.0.0.1:PORT_N/test" is tainted
+01:01:01 p1235 processing "warn" (TESTSUITE/test-config 39)
+01:01:01 p1235 ╭considering: FAIL3:░${lookup░mysql░░░░░{servers=127.0.0.1::PORT_N;░select░name░from░them░where░id░=░'$local_part'}}
+01:01:01 p1235 ├───────text: FAIL3:░
+01:01:01 p1235 ├considering: ${lookup░mysql░░░░░{servers=127.0.0.1::PORT_N;░select░name░from░them░where░id░=░'$local_part'}}
+01:01:01 p1235 ╭considering: servers=127.0.0.1::PORT_N;░select░name░from░them░where░id░=░'$local_part'}}
+01:01:01 p1235 ├───────text: servers=127.0.0.1::PORT_N;░select░name░from░them░where░id░=░'
+01:01:01 p1235 ├considering: $local_part'}}
+01:01:01 p1235 ├──────value: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├considering: '}}
+01:01:01 p1235 ├───────text: '
+01:01:01 p1235 ├considering: }}
+01:01:01 p1235 ├───expanded: servers=127.0.0.1::PORT_N;░select░name░from░them░where░id░=░'$local_part'
+01:01:01 p1235 ╰─────result: servers=127.0.0.1::PORT_N;░select░name░from░them░where░id░=░'c'
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 search_open: mysql "NULL"
+01:01:01 p1235 cached open
+01:01:01 p1235 search_find: file="NULL"
+01:01:01 p1235 key="servers=127.0.0.1::PORT_N; select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
+01:01:01 p1235 LRU list:
+01:01:01 p1235 internal_search_find: file="NULL"
+01:01:01 p1235 type=mysql key="servers=127.0.0.1::PORT_N; select name from them where id = 'c'" opts=NULL
+01:01:01 p1235 database lookup required for servers=127.0.0.1::PORT_N; select name from them where id = 'c'
+01:01:01 p1235 (tainted)
+01:01:01 p1235 LOG: MAIN PANIC
+01:01:01 p1235 tainted search query is not properly quoted (ACL warn, TESTSUITE/test-config 39): select name from them where id = 'c'
+01:01:01 p1235 required_quoter_id NN (mysql) quoting -1 (NULL)
+01:01:01 p1235 MySQL query: "servers=127.0.0.1::PORT_N; select name from them where id = 'c'" opts 'NULL'
+01:01:01 p1235 LOG: MAIN
+01:01:01 p1235 Exim configuration error in line 89 of TESTSUITE/test-config:
+01:01:01 p1235 WARNING: obsolete syntax used for lookup
+01:01:01 p1235 MYSQL using cached connection for 127.0.0.1:PORT_N/test/root
+01:01:01 p1235 MYSQL: no data found
+01:01:01 p1235 creating new cache entry
+01:01:01 p1235 lookup failed
+01:01:01 p1235 ├───item-res:
+01:01:01 p1235 ├───expanded: FAIL3:░${lookup░mysql░░░░░{servers=127.0.0.1::PORT_N;░select░name░from░them░where░id░=░'$local_part'}}
+01:01:01 p1235 ╰─────result: FAIL3:░
+01:01:01 p1235 check set acl_m0 = FAIL3: ${lookup mysql {servers=127.0.0.1::PORT_N; select name from them where id = '$local_part'}}
+01:01:01 p1235 = FAIL3:
+01:01:01 p1235 warn: condition test succeeded in ACL "check_recipient"
+01:01:01 p1235 processing "warn" (TESTSUITE/test-config 42)
+01:01:01 p1235 check set acl_m0 = ok: hostlist
+01:01:01 p1235 check hosts = net-mysql;select * from them where id='${quote_mysql:$local_part}'
+01:01:01 p1235 ╭considering: net-mysql;select░*░from░them░where░id='${quote_mysql:$local_part}'
+01:01:01 p1235 ├───────text: net-mysql;select░*░from░them░where░id='
+01:01:01 p1235 ├considering: ${quote_mysql:$local_part}'
+01:01:01 p1235 ╭considering: $local_part}'
+01:01:01 p1235 ├──────value: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├considering: }'
+01:01:01 p1235 ├───expanded: $local_part
+01:01:01 p1235 ╰─────result: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├─────op-res: c
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 ├considering: '
+01:01:01 p1235 ├───────text: '
+01:01:01 p1235 ├───expanded: net-mysql;select░*░from░them░where░id='${quote_mysql:$local_part}'
+01:01:01 p1235 ╰─────result: net-mysql;select░*░from░them░where░id='c'
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 host in "net-mysql;select * from them where id='c'"?
+01:01:01 p1235 list element: net-mysql;select░*░from░them░where░id='c'
+01:01:01 p1235 search_open: mysql "NULL"
+01:01:01 p1235 cached open
+01:01:01 p1235 search_find: file="NULL"
+01:01:01 p1235 key="select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
+01:01:01 p1235 LRU list:
+01:01:01 p1235 internal_search_find: file="NULL"
+01:01:01 p1235 type=mysql key="select * from them where id='c'" opts=NULL
+01:01:01 p1235 database lookup required for select * from them where id='c'
+01:01:01 p1235 (tainted, quoted:mysql)
+01:01:01 p1235 MySQL query: "select * from them where id='c'" opts 'NULL'
+01:01:01 p1235 MYSQL using cached connection for 127.0.0.1:PORT_N/test/root
+01:01:01 p1235 MYSQL: no data found
+01:01:01 p1235 creating new cache entry
+01:01:01 p1235 lookup failed
+01:01:01 p1235 host in "net-mysql;select * from them where id='c'"? no (end of list)
+01:01:01 p1235 warn: condition test failed in ACL "check_recipient"
+01:01:01 p1235 processing "warn" (TESTSUITE/test-config 45)
+01:01:01 p1235 check set acl_m0 = FAIL4: hostlist
+01:01:01 p1235 check hosts = <& net-mysql;servers=127.0.0.1::PORT_N/test/root/pass; select * from them where id='${quote_mysql:$local_part}'
+01:01:01 p1235 ╭considering: <&░net-mysql;servers=127.0.0.1::PORT_N/test/root/pass;░select░*░from░them░where░id='${quote_mysql:$local_part}'
+01:01:01 p1235 ├───────text: <&░net-mysql;servers=127.0.0.1::PORT_N/test/root/pass;░select░*░from░them░where░id='
+01:01:01 p1235 ├considering: ${quote_mysql:$local_part}'
+01:01:01 p1235 ╭considering: $local_part}'
+01:01:01 p1235 ├──────value: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├considering: }'
+01:01:01 p1235 ├───expanded: $local_part
+01:01:01 p1235 ╰─────result: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├─────op-res: c
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 ├considering: '
+01:01:01 p1235 ├───────text: '
+01:01:01 p1235 ├───expanded: <&░net-mysql;servers=127.0.0.1::PORT_N/test/root/pass;░select░*░from░them░where░id='${quote_mysql:$local_part}'
+01:01:01 p1235 ╰─────result: <&░net-mysql;servers=127.0.0.1::PORT_N/test/root/pass;░select░*░from░them░where░id='c'
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 host in "<& net-mysql;servers=127.0.0.1::PORT_N/test/root/pass; select * from them where id='c'"?
+01:01:01 p1235 list element: net-mysql;servers=127.0.0.1::PORT_N/test/root/pass;░select░*░from░them░where░id='c'
+01:01:01 p1235 search_open: mysql "NULL"
+01:01:01 p1235 cached open
+01:01:01 p1235 search_find: file="NULL"
+01:01:01 p1235 key="servers=127.0.0.1::PORT_N/test/root/pass; select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
+01:01:01 p1235 LRU list:
+01:01:01 p1235 internal_search_find: file="NULL"
+01:01:01 p1235 type=mysql key="servers=127.0.0.1::PORT_N/test/root/pass; select * from them where id='c'" opts=NULL
+01:01:01 p1235 database lookup required for servers=127.0.0.1::PORT_N/test/root/pass; select * from them where id='c'
+01:01:01 p1235 (tainted, quoted:mysql)
+01:01:01 p1235 MySQL query: "servers=127.0.0.1::PORT_N/test/root/pass; select * from them where id='c'" opts 'NULL'
+01:01:01 p1235 LOG: MAIN
+01:01:01 p1235 Exim configuration error in line 89 of TESTSUITE/test-config:
+01:01:01 p1235 WARNING: obsolete syntax used for lookup
+01:01:01 p1235 lookup deferred: MySQL server "127.0.0.1:PORT_N/test" is tainted
+01:01:01 p1235 host in "<& net-mysql;servers=127.0.0.1::PORT_N/test/root/pass; select * from them where id='c'"? list match deferred for net-mysql;servers=127.0.0.1::1223/test/root/pass; select * from them where id='c'
+01:01:01 p1235 warn: condition test deferred in ACL "check_recipient"
+01:01:01 p1235 LOG: MAIN
+01:01:01 p1235 H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: MySQL server "127.0.0.1:PORT_N/test" is tainted
+01:01:01 p1235 processing "warn" (TESTSUITE/test-config 50)
+01:01:01 p1235 check set acl_m0 = FAIL5: hostlist
+01:01:01 p1235 check hosts = <& net-mysql,servers=127.0.0.1::PORT_N/test/root/pass; select * from them where id='${quote_mysql:$local_part}'
+01:01:01 p1235 ╭considering: <&░net-mysql,servers=127.0.0.1::PORT_N/test/root/pass;░select░*░from░them░where░id='${quote_mysql:$local_part}'
+01:01:01 p1235 ├───────text: <&░net-mysql,servers=127.0.0.1::PORT_N/test/root/pass;░select░*░from░them░where░id='
+01:01:01 p1235 ├considering: ${quote_mysql:$local_part}'
+01:01:01 p1235 ╭considering: $local_part}'
+01:01:01 p1235 ├──────value: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├considering: }'
+01:01:01 p1235 ├───expanded: $local_part
+01:01:01 p1235 ╰─────result: c
+01:01:01 p1235 ╰──(tainted)
+01:01:01 p1235 ├─────op-res: c
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 ├considering: '
+01:01:01 p1235 ├───────text: '
+01:01:01 p1235 ├───expanded: <&░net-mysql,servers=127.0.0.1::PORT_N/test/root/pass;░select░*░from░them░where░id='${quote_mysql:$local_part}'
+01:01:01 p1235 ╰─────result: <&░net-mysql,servers=127.0.0.1::PORT_N/test/root/pass;░select░*░from░them░where░id='c'
+01:01:01 p1235 ╰──(tainted, quoted:mysql)
+01:01:01 p1235 host in "<& net-mysql,servers=127.0.0.1::PORT_N/test/root/pass; select * from them where id='c'"?
+01:01:01 p1235 list element: net-mysql,servers=127.0.0.1::PORT_N/test/root/pass;░select░*░from░them░where░id='c'
+01:01:01 p1235 search_open: mysql "NULL"
+01:01:01 p1235 cached open
+01:01:01 p1235 search_find: file="NULL"
+01:01:01 p1235 key=" select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts="servers=127.0.0.1::PORT_N/test/root/pass"
+01:01:01 p1235 LRU list:
+01:01:01 p1235 internal_search_find: file="NULL"
+01:01:01 p1235 type=mysql key=" select * from them where id='c'" opts="servers=127.0.0.1::PORT_N/test/root/pass"
+01:01:01 p1235 database lookup required for select * from them where id='c'
+01:01:01 p1235 (tainted, quoted:mysql)
+01:01:01 p1235 MySQL query: " select * from them where id='c'" opts 'servers=127.0.0.1::PORT_N/test/root/pass'
+01:01:01 p1235 lookup deferred: MySQL server "127.0.0.1:PORT_N/test" is tainted
+01:01:01 p1235 host in "<& net-mysql,servers=127.0.0.1::PORT_N/test/root/pass; select * from them where id='c'"? list match deferred for net-mysql,servers=127.0.0.1::1223/test/root/pass; select * from them where id='c'
+01:01:01 p1235 warn: condition test deferred in ACL "check_recipient"
+01:01:01 p1235 LOG: MAIN
+01:01:01 p1235 H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: MySQL server "127.0.0.1:PORT_N/test" is tainted
+01:01:01 p1235 processing "accept" (TESTSUITE/test-config 53)
+01:01:01 p1235 check domains = +local_domains
+01:01:01 p1235 d in "+local_domains"?
+01:01:01 p1235 list element: +local_domains
+01:01:01 p1235 start sublist local_domains
+01:01:01 p1235 d in "@"?
+01:01:01 p1235 ╎list element: @
+01:01:01 p1235 d in "@"? no (end of list)
+01:01:01 p1235 end sublist local_domains
+01:01:01 p1235 d in "+local_domains"? no (end of list)
+01:01:01 p1235 accept: condition test failed in ACL "check_recipient"
+01:01:01 p1235 processing "accept" (TESTSUITE/test-config 56)
+01:01:01 p1235 check hosts = +relay_hosts
+01:01:01 p1235 host in "+relay_hosts"?
+01:01:01 p1235 list element: +relay_hosts
+01:01:01 p1235 start sublist relay_hosts
+01:01:01 p1235 ╎╭considering: net-mysql;select░*░from░them░where░id='$sender_host_address'
+01:01:01 p1235 ╎├───────text: net-mysql;select░*░from░them░where░id='
+01:01:01 p1235 ╎├considering: $sender_host_address'
+01:01:01 p1235 ╎├──────value: 10.0.0.0
+01:01:01 p1235 ╎├considering: '
+01:01:01 p1235 ╎├───────text: '
+01:01:01 p1235 ╎├───expanded: net-mysql;select░*░from░them░where░id='$sender_host_address'
+01:01:01 p1235 ╎╰─────result: net-mysql;select░*░from░them░where░id='10.0.0.0'
+01:01:01 p1235 host in "net-mysql;select * from them where id='10.0.0.0'"?
+01:01:01 p1235 ╎list element: net-mysql;select░*░from░them░where░id='10.0.0.0'
+01:01:01 p1235 ╎search_open: mysql "NULL"
+01:01:01 p1235 ╎ cached open
+01:01:01 p1235 ╎search_find: file="NULL"
+01:01:01 p1235 ╎ key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
+01:01:01 p1235 ╎LRU list:
+01:01:01 p1235 ╎internal_search_find: file="NULL"
+01:01:01 p1235 ╎ type=mysql key="select * from them where id='10.0.0.0'" opts=NULL
+01:01:01 p1235 ╎database lookup required for select * from them where id='10.0.0.0'
+01:01:01 p1235 ╎MySQL query: "select * from them where id='10.0.0.0'" opts 'NULL'
+01:01:01 p1235 ╎MYSQL using cached connection for 127.0.0.1:PORT_N/test/root
+01:01:01 p1235 ╎MYSQL: no data found
+01:01:01 p1235 ╎creating new cache entry
+01:01:01 p1235 ╎lookup failed
+01:01:01 p1235 host in "net-mysql;select * from them where id='10.0.0.0'"? no (end of list)
+01:01:01 p1235 end sublist relay_hosts
+01:01:01 p1235 host in "+relay_hosts"? no (end of list)
+01:01:01 p1235 accept: condition test failed in ACL "check_recipient"
+01:01:01 p1235 processing "deny" (TESTSUITE/test-config 57)
+01:01:01 p1235 message: relay not permitted
+01:01:01 p1235 deny: condition test succeeded in ACL "check_recipient"
+01:01:01 p1235 end of ACL "check_recipient": DENY
+01:01:01 p1235 SMTP>> 550 relay not permitted
+01:01:01 p1235 LOG: MAIN REJECT
+01:01:01 p1235 H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
+01:01:01 p1235 SMTP<< quit
+01:01:01 p1235 try option acl_smtp_quit
+01:01:01 p1235 SMTP>> 221 myhost.test.ex closing connection
+01:01:01 p1235 LOG: smtp_connection MAIN
+01:01:01 p1235 SMTP connection from (test) [10.0.0.0] D=qqs closed by QUIT
+01:01:01 p1235 search_tidyup called
+01:01:01 p1235 close MYSQL connection: 127.0.0.1:PORT_N/test/root
+01:01:01 p1235 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>