Mailing List Archive

time skew: SMTP delay
Hello,

I’m noticing if I have a server time-skewed into the future, and I send a message to Exim on that server, the SMTP client never receives confirmation of receipt of the message.

This causes that client to think the message send failed, though Exim does actually enqueue the message for remote delivery. Thus, the client resends the message, which causes the recipient to receive multiple copies of the message.

strace is showing:

-----
[pid 25596] write(4</var/log/exim_mainlog>, "2021-03-30 10:55:28 1lRGiG-0006eq-QA <= canada@felipe.dev.cpanel.net H=(felipes-mbp.lan) [10.3.4.84]:49684 P=esmtpsa X=TLS1.2:ECDHE-RSA-AES128-GCM-SHA256:128 CV=no A=dovecot_plain:mailer S=381 T=\"Test sent from ./smtp_send_test.pl\" for felipe@felipe.com\n", 254) = 254
[pid 25596] rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0
[pid 25596] setitimer(ITIMER_REAL, {it_interval={tv_sec=0, tv_usec=0}, it_value={tv_sec=7198, tv_usec=625000}}, NULL) = 0
[pid 25596] rt_sigsuspend(~[ALRM RTMIN RT_1], 8
-----

… and there it hangs. The socket never closes, so the client eventually times out the connection.

Has anyone else encountered something of the like? Notwithstanding that clock-skews of 2 hours are their own problem, could Exim maybe handle this a bit more gracefully?

Thank you!

cheers,
-Felipe Gasper
--
## List details at https://lists.exim.org/mailman/listinfo/exim-users
## Exim details at http://www.exim.org/
## Please use the Wiki with this list - http://wiki.exim.org/
Re: time skew: SMTP delay [ In reply to ]
On 30/03/2021 15:02, Felipe Gasper via Exim-users wrote:
> I’m noticing if I have a server time-skewed into the future, and I send a message to Exim on that server, the SMTP client never receives confirmation of receipt of the message.
>
> This causes that client to think the message send failed, though Exim does actually enqueue the message for remote delivery. Thus, the client resends the message, which causes the recipient to receive multiple copies of the message.
>
> strace is showing:
>
> -----
> [pid 25596] write(4</var/log/exim_mainlog>, "2021-03-30 10:55:28 1lRGiG-0006eq-QA <= canada@felipe.dev.cpanel.net H=(felipes-mbp.lan) [10.3.4.84]:49684 P=esmtpsa X=TLS1.2:ECDHE-RSA-AES128-GCM-SHA256:128 CV=no A=dovecot_plain:mailer S=381 T=\"Test sent from ./smtp_send_test.pl\" for felipe@felipe.com\n", 254) = 254
> [pid 25596] rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0
> [pid 25596] setitimer(ITIMER_REAL, {it_interval={tv_sec=0, tv_usec=0}, it_value={tv_sec=7198, tv_usec=625000}}, NULL) = 0
> [pid 25596] rt_sigsuspend(~[ALRM RTMIN RT_1], 8
> -----
>
> … and there it hangs. The socket never closes, so the client eventually times out the connection.
>
> Has anyone else encountered something of the like? Notwithstanding that clock-skews of 2 hours are their own problem, could Exim maybe handle this a bit more gracefully?


What Exim version? What platform?

Behaviour changed in 4.94, for platforms with _POSIX_MONOTONIC_CLOCK.
--
Cheers,
Jeremy

--
## List details at https://lists.exim.org/mailman/listinfo/exim-users
## Exim details at http://www.exim.org/
## Please use the Wiki with this list - http://wiki.exim.org/
Re: time skew: SMTP delay [ In reply to ]
> On Mar 30, 2021, at 10:33 AM, Jeremy Harris via Exim-users <exim-users@exim.org> wrote:
>
> On 30/03/2021 15:02, Felipe Gasper via Exim-users wrote:
>> I’m noticing if I have a server time-skewed into the future, and I send a message to Exim on that server, the SMTP client never receives confirmation of receipt of the message.
>> This causes that client to think the message send failed, though Exim does actually enqueue the message for remote delivery. Thus, the client resends the message, which causes the recipient to receive multiple copies of the message.
>> strace is showing:
>> -----
>> [pid 25596] write(4</var/log/exim_mainlog>, "2021-03-30 10:55:28 1lRGiG-0006eq-QA <= canada@felipe.dev.cpanel.net H=(felipes-mbp.lan) [10.3.4.84]:49684 P=esmtpsa X=TLS1.2:ECDHE-RSA-AES128-GCM-SHA256:128 CV=no A=dovecot_plain:mailer S=381 T=\"Test sent from ./smtp_send_test.pl\" for felipe@felipe.com\n", 254) = 254
>> [pid 25596] rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0
>> [pid 25596] setitimer(ITIMER_REAL, {it_interval={tv_sec=0, tv_usec=0}, it_value={tv_sec=7198, tv_usec=625000}}, NULL) = 0
>> [pid 25596] rt_sigsuspend(~[ALRM RTMIN RT_1], 8
>> -----
>> … and there it hangs. The socket never closes, so the client eventually times out the connection.
>> Has anyone else encountered something of the like? Notwithstanding that clock-skews of 2 hours are their own problem, could Exim maybe handle this a bit more gracefully?
>
>
> What Exim version? What platform?
>
> Behaviour changed in 4.94, for platforms with _POSIX_MONOTONIC_CLOCK.

Exim 4.94
CentOS 7, default kernel (3.10.0-1160.11.1.el7.x86_64)

Incidentally, I’m setting system clock via `date`.

Thank you!

-FG
--
## List details at https://lists.exim.org/mailman/listinfo/exim-users
## Exim details at http://www.exim.org/
## Please use the Wiki with this list - http://wiki.exim.org/
Re: time skew: SMTP delay [ In reply to ]
On 30/03/2021 15:40, Felipe Gasper via Exim-users wrote:
> Incidentally, I’m setting system clock via `date`.

Are you deliberately skewing the system into the future?
Before or after starting the daemon?

Can you get debug output from a repro run?
--
Cheers,
Jeremy

--
## List details at https://lists.exim.org/mailman/listinfo/exim-users
## Exim details at http://www.exim.org/
## Please use the Wiki with this list - http://wiki.exim.org/
Re: time skew: SMTP delay [ In reply to ]
On 30/03/2021 15:33, Jeremy Harris via Exim-users wrote:
> Behaviour changed in 4.94, for platforms with _POSIX_MONOTONIC_CLOCK.

Would also be affected by the fix for 2615, since 4.94
--
Cheers,
Jeremy

--
## List details at https://lists.exim.org/mailman/listinfo/exim-users
## Exim details at http://www.exim.org/
## Please use the Wiki with this list - http://wiki.exim.org/
Re: time skew: SMTP delay [ In reply to ]
> On Mar 30, 2021, at 10:48 AM, Jeremy Harris via Exim-users <exim-users@exim.org> wrote:
>
> On 30/03/2021 15:40, Felipe Gasper via Exim-users wrote:
>> Incidentally, I’m setting system clock via `date`.
>
> Are you deliberately skewing the system into the future?

I myself am deliberately skewing, yes (date -s 'now + 2 hours'), but we have reports of similar hangs in the wild.

> Before or after starting the daemon?

After.

>
> Can you get debug output from a repro run?

I tried to find in the docs how to up the verbosity, but I didn’t see. What should I do to get debug output?

Thank you!

-FG


--
## List details at https://lists.exim.org/mailman/listinfo/exim-users
## Exim details at http://www.exim.org/
## Please use the Wiki with this list - http://wiki.exim.org/
Re: time skew: SMTP delay [ In reply to ]
On 30/03/2021 16:11, Felipe Gasper via Exim-users wrote:
> I tried to find in the docs how to up the verbosity, but I didn’t see. What should I do to get debug output?

Command line switch "-d" and variants thereof, for the daemon.

I'm fond of
"service exim stop && exim -d+all -bd 2>&1 | tee log"

--
Cheers,
Jeremy

--
## List details at https://lists.exim.org/mailman/listinfo/exim-users
## Exim details at http://www.exim.org/
## Please use the Wiki with this list - http://wiki.exim.org/
Re: time skew: SMTP delay [ In reply to ]
> On Mar 30, 2021, at 11:17 AM, Jeremy Harris via Exim-users <exim-users@exim.org> wrote:
>
> On 30/03/2021 16:11, Felipe Gasper via Exim-users wrote:
>> I tried to find in the docs how to up the verbosity, but I didn’t see. What should I do to get debug output?
>
> Command line switch "-d" and variants thereof, for the daemon.
>
> I'm fond of
> "service exim stop && exim -d+all -bd 2>&1 | tee log"

(Out of curiosity, can it also be done via exim.conf?)

The log output … hopefully it doesn’t exceed this mailing list’s limits …

-----
14:23:14 1775 Exim version 4.94 uid=0 gid=0 pid=1775 D=fff9ffff
Support for: crypteq iconv() IPv6 PAM Perl OpenSSL Content_Scanning DANE DKIM DNSSEC Event I18N OCSP PIPE_CONNECT PRDR SPF Experimental_SRS
Lookups (built-in): lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmjz dbmnz dnsdb dsearch passwd sqlite
Authenticators: cram_md5 dovecot plaintext spa
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir autoreply lmtp pipe smtp
Malware: f-protd f-prot6d drweb fsecure sophie clamd avast sock cmdline
Configure owner: 0:0
Size of off_t: 8
Compiler: GCC [4.8.2 20140120 (Red Hat 4.8.2-16)]
Library version: Glibc: Compile: 2.17
Runtime: 2.17
Library version: BDB: Compile: Berkeley DB 5.3.21: (May 11, 2012)
Runtime: Berkeley DB 5.3.21: (May 11, 2012)
Library version: OpenSSL: Compile: OpenSSL 1.0.2k-fips 26 Jan 2017
Runtime: OpenSSL 1.0.2k-fips 26 Jan 2017
: built on: reproducible build, date unspecified
Library version: IDN: Compile: 1.28
Runtime: 1.28
Library version: spf2: Compile: 1.2.10
Runtime: 1.2.10
Library version: PCRE: Compile: 8.32
Runtime: 8.32 2012-11-30
14:23:14 1775 Total 14 lookups
Library version: SQLite: Compile: 3.7.17
Runtime: 3.32.3
WHITELIST_D_MACROS unset
TRUSTED_CONFIG_LIST: "/etc/exim_trusted_configs"
14:23:14 1775 changed uid/gid: forcing real = effective
14:23:14 1775 uid=0 gid=0 pid=1775
14:23:14 1775 auxiliary group list: <none>
14:23:14 1775 seeking password data for user "cpaneleximfilter": cache not available
14:23:14 1775 getpwnam() succeeded uid=203 gid=203
14:23:14 1775 seeking password data for user "root": cache not available
14:23:14 1775 getpwnam() succeeded uid=0 gid=0
14:23:14 1775 openssl option, adding to 03104000: 01000000 (no_sslv2 +no_sslv3 +no_tlsv1 +no_tlsv1_1)
14:23:14 1775 openssl option, adding to 03104000: 02000000 (no_sslv3 +no_tlsv1 +no_tlsv1_1)
14:23:14 1775 openssl option, adding to 03104000: 04000000 (no_tlsv1 +no_tlsv1_1)
14:23:14 1775 openssl option, adding to 07104000: 10000000 (no_tlsv1_1)
14:23:14 1775 XDG_SESSION_ID in keep_environment? no (end of list)
14:23:14 1775 HOSTNAME in keep_environment? no (end of list)
14:23:14 1775 TERM in keep_environment? no (end of list)
14:23:14 1775 SHELL in keep_environment? no (end of list)
14:23:14 1775 HISTSIZE in keep_environment? no (end of list)
14:23:14 1775 SSH_CLIENT in keep_environment? no (end of list)
14:23:14 1775 SSH_TTY in keep_environment? no (end of list)
14:23:14 1775 USER in keep_environment? no (end of list)
14:23:14 1775 LS_COLORS in keep_environment? no (end of list)
14:23:14 1775 MAIL in keep_environment? no (end of list)
14:23:14 1775 PATH in keep_environment? no (end of list)
14:23:14 1775 PWD in keep_environment? no (end of list)
14:23:14 1775 LANG in keep_environment? no (end of list)
14:23:14 1775 HISTCONTROL in keep_environment? no (end of list)
14:23:14 1775 SHLVL in keep_environment? no (end of list)
14:23:14 1775 HOME in keep_environment? no (end of list)
14:23:14 1775 LOGNAME in keep_environment? no (end of list)
14:23:14 1775 CVS_RSH in keep_environment? no (end of list)
14:23:14 1775 SSH_CONNECTION in keep_environment? no (end of list)
14:23:14 1775 LESSOPEN in keep_environment? no (end of list)
14:23:14 1775 XDG_RUNTIME_DIR in keep_environment? no (end of list)
14:23:14 1775 HISTTIMEFORMAT in keep_environment? no (end of list)
14:23:14 1775 _ in keep_environment? no (end of list)
14:23:14 1775 adding PATH=/usr/local/sbin:/usr/local/bin:/sbin:/bin:/usr/sbin:/usr/bin:/sbin:/bin
14:23:14 1775 configuration file is /etc/exim.conf
14:23:14 1775 log selectors = 00001ffe 99805426 00000003
14:23:14 1775 Reset TZ to America/Chicago: time is 2021-03-30 14:23:14
14:23:14 1775 LOG: MAIN
14:23:14 1775 cwd=/root 3 args: exim -d+all -bd
14:23:14 1775 trusted user
14:23:14 1775 admin user
14:23:14 1775 dropping to exim gid; retaining priv uid
14:23:14 1775 seeking password data for user "mailnull": cache not available
14:23:14 1775 getpwnam() succeeded uid=47 gid=47
14:23:14 1775 seeking password data for user "mailnull": using cached result
14:23:14 1775 getpwnam() succeeded uid=47 gid=47
14:23:14 1775 seeking password data for user "mailnull": using cached result
14:23:14 1775 getpwnam() succeeded uid=47 gid=47
14:23:14 1775 seeking password data for user "mailman": cache not available
14:23:14 1775 getpwnam() succeeded uid=209 gid=209
14:23:14 1775 seeking password data for user "mailman": using cached result
14:23:14 1775 getpwnam() succeeded uid=209 gid=209
14:23:14 1775 originator: uid=0 gid=0 login=root name=root
14:23:14 1775 fresh-exec forking for cipher-validate
14:23:14 1775 fresh-exec forked for cipher-validate: 1777
14:23:14 1777 postfork: cipher-validate
14:23:14 1777 changed uid/gid: calling tls_validate_require_cipher
14:23:14 1777 uid=47 gid=12 pid=1777
14:23:14 1777 auxiliary group list: <none>
14:23:14 1777 tls_require_ciphers expands to "ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-CHACHA20-POLY1305:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-RSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-RSA-AES128-SHA256"
14:23:14 1777 >>>>>>>>>>>>>>>> Exim pid=1777 (cipher-validate) terminating with rc=0 >>>>>>>>>>>>>>>>
14:23:14 1775 tls_validate_require_cipher child 1777 ended: status=0x0
14:23:14 1775 creating notifier socket
14:23:14 1775 ?considering: $spool_directory/exim_daemon_notify
14:23:14 1775 ???expanding: $spool_directory/exim_daemon_notify
14:23:14 1775 ??????result: /var/spool/exim/exim_daemon_notify
14:23:14 1775 @/var/spool/exim/exim_daemon_notify
14:23:14 1775 listening on all interfaces (IPv6) port 25
14:23:14 1775 listening on all interfaces (IPv4) port 25
14:23:14 1775 listening on all interfaces (IPv6) port 465
14:23:14 1775 listening on all interfaces (IPv4) port 465
14:23:14 1775 listening on all interfaces (IPv6) port 587
14:23:14 1775 listening on all interfaces (IPv4) port 587
14:23:14 1775 pid written to /var/spool/exim/exim-daemon.pid
14:23:14 1775 changed uid/gid: running as a daemon
14:23:14 1775 uid=47 gid=12 pid=1775
14:23:14 1775 auxiliary group list: 12
14:23:14 1775 LOG: MAIN
14:23:14 1775 exim 4.94 daemon started: pid=1775, no queue runs, listening for SMTP on port 25 (IPv6 and IPv4) port 587 (IPv6 and IPv4) and for SMTPS on port 465 (IPv6 and IPv4)
14:23:14 1775 set_process_info: 1775 daemon(4.94): no queue runs, listening for SMTP on port 25 (IPv6 and IPv4) port 587 (IPv6 and IPv4) and for SMTPS on port 465 (IPv6 and IPv4)
14:23:14 1775 SPF_dns_exim_new
spf_compile.c:523 Debug: Parsing macro starting at Please%_see%_http://www.openspf.org/Why?id=%{S}&ip=%{C}&receiver=%{R}
spf_compile.c:1210 Debug: Compiling record v=spf1
spf_compile.c:523 Debug: Parsing macro starting at Please%_see%_http://www.open-spf.org/Why
14:23:14 1775 daemon running with uid=47 gid=12 euid=47 egid=12
14:23:14 1775 Listening...
16:23:33 1775 Connection request from 10.1.34.231 port 33586
16:23:33 1775 interface address=172.16.1.13 port=465
16:23:33 1775 LOG: smtp_connection MAIN
16:23:33 1775 SMTP connection from [10.1.34.231]:33586 (TCP/IP connection count = 1)
16:23:33 1775 search_tidyup called
16:23:33 1775 daemon forking for daemon-accept
16:23:33 1775 daemon forked for daemon-accept: 1783
16:23:33 1775 1 SMTP accept process running
16:23:33 1775 Listening...
16:23:33 1783 postfork: daemon-accept
16:23:33 1783 sender_fullhost = [10.1.34.231]:33586
16:23:33 1783 sender_rcvhost = [10.1.34.231] (port=33586)
16:23:33 1783 Process 1783 is handling incoming connection from [10.1.34.231]:33586
16:23:33 1783 host in host_lookup? no (option unset)
16:23:33 1783 set_process_info: 1783 handling incoming connection from [10.1.34.231]:33586
16:23:33 1783 host in host_reject_connection? no (option unset)
16:23:33 1783 host in sender_unqualified_hosts? no (option unset)
16:23:33 1783 host in recipient_unqualified_hosts? no (option unset)
16:23:33 1783 host in helo_verify_hosts? no (option unset)
16:23:33 1783 host in helo_try_verify_hosts? no (option unset)
16:23:33 1783 host in helo_accept_junk_hosts? yes (matched "*")
16:23:33 1783 openssl option, adding to 03104000: 01000000 (no_sslv2 +no_sslv3 +no_tlsv1 +no_tlsv1_1)
16:23:33 1783 openssl option, adding to 03104000: 02000000 (no_sslv3 +no_tlsv1 +no_tlsv1_1)
16:23:33 1783 openssl option, adding to 03104000: 04000000 (no_tlsv1 +no_tlsv1_1)
16:23:33 1783 openssl option, adding to 07104000: 10000000 (no_tlsv1_1)
16:23:33 1783 setting SSL CTX options: 0x17104000
16:23:33 1783 Diffie-Hellman initialized from default with 2048-bit prime
16:23:33 1783 ECDH OpenSSL 1.0.2+ temp key parameter settings: autoselection
16:23:33 1783 ?considering: ${if and { {gt{$tls_in_sni}{}} {!match{$tls_in_sni}{/}} } {${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ?considering: $tls_in_sni}{}} {!match{$tls_in_sni}{/}} } {${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ???expanding: $tls_in_sni
16:23:33 1783 ??????result:
16:23:33 1783 ?considering: }} {!match{$tls_in_sni}{/}} } {${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ???expanding:
16:23:33 1783 ??????result:
16:23:33 1783 ????scanning: $tls_in_sni}{/}} } {${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ???expanding: $tls_in_sni
16:23:33 1783 ??????result:
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ????scanning: /}} } {${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ???expanding: /
16:23:33 1783 ??????result: /
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???condition: and { {gt{$tls_in_sni}{}} {!match{$tls_in_sni}{/}} }
16:23:33 1783 ??????result: false
16:23:33 1783 ????scanning: ${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ????scanning: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ???expanding: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined
16:23:33 1783 ??????result: /var/cpanel/ssl/domain_tls//combined
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???condition: exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}
16:23:33 1783 ??????result: false
16:23:33 1783 ????scanning: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ???expanding: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined
16:23:33 1783 ??????result: /var/cpanel/ssl/domain_tls//combined
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ????scanning: ${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ?????scanning: ${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ? ????scanning: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ? ???expanding: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined
16:23:33 1783 ? ??????result: /var/cpanel/ssl/domain_tls//combined
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ????scanning: (.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ? ???expanding: (.+/)[^.]+(.+/combined)
16:23:33 1783 ? ??????result: (.+/)[^.]+(.+/combined)
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ????scanning: \$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ? ???expanding: \$1*\$2
16:23:33 1783 ? ??????result: $1*$2
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ?considering: $1*$2
16:23:33 1783 ? ???expanding: $1*$2
16:23:33 1783 ? ??????result: /var/cpanel/ssl/*//combined
16:23:33 1783 ????expanding: ${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}
16:23:33 1783 ???????result: /var/cpanel/ssl/*//combined
16:23:33 1783 ?????skipping: result is not used
16:23:33 1783 ???condition: exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}}
16:23:33 1783 ??????result: false
16:23:33 1783 ?????scanning: ${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ? ????scanning: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ? ???expanding: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined
16:23:33 1783 ? ??????result: /var/cpanel/ssl/domain_tls//combined
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ????scanning: (.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ? ???expanding: (.+/)[^.]+(.+/combined)
16:23:33 1783 ? ??????result: (.+/)[^.]+(.+/combined)
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ????scanning: \$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ? ???expanding: \$1*\$2
16:23:33 1783 ? ??????result: $1*$2
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ?considering: $1*$2
16:23:33 1783 ? ???expanding: $1*$2
16:23:33 1783 ? ??????result: /var/cpanel/ssl/*//combined
16:23:33 1783 ????expanding: ${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}
16:23:33 1783 ???????result: /var/cpanel/ssl/*//combined
16:23:33 1783 ?????skipping: result is not used
16:23:33 1783 ?????scanning: /etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ????expanding: /etc/exim.crt
16:23:33 1783 ???????result: /etc/exim.crt
16:23:33 1783 ?????skipping: result is not used
16:23:33 1783 ???expanding: ${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }
16:23:33 1783 ??????result: /etc/exim.crt
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???expanding: ${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }
16:23:33 1783 ??????result: /etc/exim.crt
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ?considering: /etc/exim.crt} }
16:23:33 1783 ???expanding: /etc/exim.crt
16:23:33 1783 ??????result: /etc/exim.crt
16:23:33 1783 ???expanding: ${if and { {gt{$tls_in_sni}{}} {!match{$tls_in_sni}{/}} } {${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.crt} }} }} {/etc/exim.crt} }
16:23:33 1783 ??????result: /etc/exim.crt
16:23:33 1783 tls_certificate file '/etc/exim.crt'
16:23:33 1783 ?considering: ${if and { {gt{$tls_in_sni}{}} {!match{$tls_in_sni}{/}} } {${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ?considering: $tls_in_sni}{}} {!match{$tls_in_sni}{/}} } {${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ???expanding: $tls_in_sni
16:23:33 1783 ??????result:
16:23:33 1783 ?considering: }} {!match{$tls_in_sni}{/}} } {${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ???expanding:
16:23:33 1783 ??????result:
16:23:33 1783 ????scanning: $tls_in_sni}{/}} } {${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ???expanding: $tls_in_sni
16:23:33 1783 ??????result:
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ????scanning: /}} } {${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ???expanding: /
16:23:33 1783 ??????result: /
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???condition: and { {gt{$tls_in_sni}{}} {!match{$tls_in_sni}{/}} }
16:23:33 1783 ??????result: false
16:23:33 1783 ????scanning: ${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ????scanning: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ???expanding: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined
16:23:33 1783 ??????result: /var/cpanel/ssl/domain_tls//combined
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???condition: exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}
16:23:33 1783 ??????result: false
16:23:33 1783 ????scanning: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ???expanding: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined
16:23:33 1783 ??????result: /var/cpanel/ssl/domain_tls//combined
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ????scanning: ${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ?????scanning: ${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ? ????scanning: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ? ???expanding: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined
16:23:33 1783 ? ??????result: /var/cpanel/ssl/domain_tls//combined
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ????scanning: (.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ? ???expanding: (.+/)[^.]+(.+/combined)
16:23:33 1783 ? ??????result: (.+/)[^.]+(.+/combined)
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ????scanning: \$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ? ???expanding: \$1*\$2
16:23:33 1783 ? ??????result: $1*$2
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ?considering: $1*$2
16:23:33 1783 ? ???expanding: $1*$2
16:23:33 1783 ? ??????result: /var/cpanel/ssl/*//combined
16:23:33 1783 ????expanding: ${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}
16:23:33 1783 ???????result: /var/cpanel/ssl/*//combined
16:23:33 1783 ?????skipping: result is not used
16:23:33 1783 ???condition: exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}}
16:23:33 1783 ??????result: false
16:23:33 1783 ?????scanning: ${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ? ????scanning: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ? ???expanding: /var/cpanel/ssl/domain_tls/$tls_in_sni/combined
16:23:33 1783 ? ??????result: /var/cpanel/ssl/domain_tls//combined
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ????scanning: (.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ? ???expanding: (.+/)[^.]+(.+/combined)
16:23:33 1783 ? ??????result: (.+/)[^.]+(.+/combined)
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ????scanning: \$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ? ???expanding: \$1*\$2
16:23:33 1783 ? ??????result: $1*$2
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ?considering: $1*$2
16:23:33 1783 ? ???expanding: $1*$2
16:23:33 1783 ? ??????result: /var/cpanel/ssl/*//combined
16:23:33 1783 ????expanding: ${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}
16:23:33 1783 ???????result: /var/cpanel/ssl/*//combined
16:23:33 1783 ?????skipping: result is not used
16:23:33 1783 ?????scanning: /etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ????expanding: /etc/exim.key
16:23:33 1783 ???????result: /etc/exim.key
16:23:33 1783 ?????skipping: result is not used
16:23:33 1783 ???expanding: ${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }
16:23:33 1783 ??????result: /etc/exim.key
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???expanding: ${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }
16:23:33 1783 ??????result: /etc/exim.key
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ?considering: /etc/exim.key} }
16:23:33 1783 ???expanding: /etc/exim.key
16:23:33 1783 ??????result: /etc/exim.key
16:23:33 1783 ???expanding: ${if and { {gt{$tls_in_sni}{}} {!match{$tls_in_sni}{/}} } {${if exists {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {/var/cpanel/ssl/domain_tls/$tls_in_sni/combined} {${if exists {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {${sg{/var/cpanel/ssl/domain_tls/$tls_in_sni/combined}{(.+/)[^.]+(.+/combined)}{\$1*\$2}}} {/etc/exim.key} }} }} {/etc/exim.key} }
16:23:33 1783 ??????result: /etc/exim.key
16:23:33 1783 tls_privatekey file '/etc/exim.key'
16:23:33 1783 Initialized TLS
16:23:33 1783 required ciphers: ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-CHACHA20-POLY1305:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-RSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-RSA-AES128-SHA256
16:23:33 1783 host in tls_verify_hosts? no (option unset)
16:23:33 1783 host in tls_try_verify_hosts? no (option unset)
16:23:33 1783 Calling SSL_accept
16:23:33 1783 SSL_accept: before/accept initialization
16:23:33 1783 SSL_accept: SSLv3 read client hello A
16:23:33 1783 SSL_accept: SSLv3 write server hello A
16:23:33 1783 SSL_accept: SSLv3 write certificate A
16:23:33 1783 SSL_accept: SSLv3 write key exchange A
16:23:33 1783 SSL_accept: SSLv3 write server done A
16:23:33 1783 SSL_accept: SSLv3 flush data
16:23:33 1783 SSL_accept: SSLv3 read client certificate A
16:23:33 1783 SSL_accept: SSLv3 read client key exchange A
16:23:33 1783 SSL_accept: SSLv3 read certificate verify A
16:23:33 1783 SSL_accept: SSLv3 read finished A
16:23:33 1783 SSL_accept: SSLv3 write change cipher spec A
16:23:33 1783 SSL_accept: SSLv3 write finished A
16:23:33 1783 SSL_accept: SSLv3 flush data
16:23:33 1783 SSL_accept was successful
16:23:33 1783 Cipher: TLS1.2:ECDHE-RSA-AES128-GCM-SHA256:128
16:23:33 1783 Shared ciphers: ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-RSA-AES128-GCM-SHA256:DHE-RSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-RSA-AES256-SHA:DHE-RSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-RSA-AES128-SHA:DHE-RSA-AES128-SHA:AES128-GCM-SHA256:AES256-SHA:AES128-SHA:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES256-GCM-SHA384:AES256-SHA256:DHE-DSS-AES256-SHA256:DHE-DSS-AES256-SHA:DHE-DSS-AES128-SHA
16:23:33 1783 Have channel bindings cached for possible auth usage 0x143e7b0
16:23:33 1783 using ACL "acl_smtp_connect"
16:23:33 1783 processing "drop" (/etc/exim.conf 420)
16:23:33 1783 message: Your country is not allowed to connect to this server.
16:23:33 1783 l_message: Country is banned
16:23:33 1783 check hosts = +blocked_incoming_email_country_ips
16:23:33 1783 ?considering: ${if exists{/etc/blocked_incoming_email_country_ips} {net-iplsearch;/etc/blocked_incoming_email_country_ips} {} }
16:23:33 1783 ?considering: /etc/blocked_incoming_email_country_ips} {net-iplsearch;/etc/blocked_incoming_email_country_ips} {} }
16:23:33 1783 ???expanding: /etc/blocked_incoming_email_country_ips
16:23:33 1783 ??????result: /etc/blocked_incoming_email_country_ips
16:23:33 1783 ???condition: exists{/etc/blocked_incoming_email_country_ips}
16:23:33 1783 ??????result: true
16:23:33 1783 ?considering: net-iplsearch;/etc/blocked_incoming_email_country_ips} {} }
16:23:33 1783 ???expanding: net-iplsearch;/etc/blocked_incoming_email_country_ips
16:23:33 1783 ??????result: net-iplsearch;/etc/blocked_incoming_email_country_ips
16:23:33 1783 ????scanning: } }
16:23:33 1783 ???expanding:
16:23:33 1783 ??????result:
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???expanding: ${if exists{/etc/blocked_incoming_email_country_ips} {net-iplsearch;/etc/blocked_incoming_email_country_ips} {} }
16:23:33 1783 ??????result: net-iplsearch;/etc/blocked_incoming_email_country_ips
16:23:33 1783 search_open: iplsearch "/etc/blocked_incoming_email_country_ips"
16:23:33 1783 search_find: file="/etc/blocked_incoming_email_country_ips"
16:23:33 1783 key="10.1.34.231" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 6/etc/blocked_incoming_email_country_ips
16:23:33 1783 End
16:23:33 1783 internal_search_find: file="/etc/blocked_incoming_email_country_ips"
16:23:33 1783 type=iplsearch key="10.1.34.231" opts=NULL
16:23:33 1783 file lookup required for 10.1.34.231
16:23:33 1783 in /etc/blocked_incoming_email_country_ips
16:23:33 1783 lookup failed
16:23:33 1783 host in "net-iplsearch;/etc/blocked_incoming_email_country_ips"? no (end of list)
16:23:33 1783 host in "+blocked_incoming_email_country_ips"? no (end of list)
16:23:33 1783 drop: condition test failed in ACL "acl_smtp_connect"
16:23:33 1783 processing "warn" (/etc/exim.conf 430)
16:23:33 1783 check !hosts = : +loopback : +neighbor_netblocks : +trustedmailhosts : +recent_authed_mail_ips : +backupmx_hosts : +skipsmtpcheck_hosts : +senderverifybypass_hosts : +greylist_trusted_netblocks : +cpanel_mail_netblocks
16:23:33 1783 Actual local interface address is 127.0.0.1 (lo)
16:23:33 1783 Actual local interface address is 172.16.1.13 (eth0)
16:23:33 1783 Actual local interface address is 4.3.2.1 (eth0:cp1)
16:23:33 1783 Actual local interface address is 1.2.3.4 (eth0:cp5)
16:23:33 1783 Actual local interface address is 4.3.2.11 (eth0:cp2)
16:23:33 1783 Actual local interface address is 4.3.2.12 (eth0:cp3)
16:23:33 1783 Actual local interface address is 4.3.2.13 (eth0:cp4)
16:23:33 1783 Actual local interface address is fe80::f816:3eff:fe6e:1989 (eth0)
16:23:33 1783 Actual local interface address is ::1 (lo)
16:23:33 1783 Actual local interface address is fe80::18f8:94ff:fe4b:3c81 (ifb0)
16:23:33 1783 Actual local interface address is 2620:0:28a4:c024:f816:3eff:fe6e:1989 (eth0)
16:23:33 1783 Configured local interface: address=10.1.35.45
16:23:33 1783 host in "<; @[]; 127.0.0.0/8 ; 0.0.0.0 ; ::1 ; 0000:0000:0000:0000:0000:ffff:7f00:0000/8"? no (end of list)
16:23:33 1783 search_open: iplsearch "/etc/neighbor_netblocks"
16:23:33 1783 search_find: file="/etc/neighbor_netblocks"
16:23:33 1783 key="10.1.34.231" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 6/etc/neighbor_netblocks
16:23:33 1783 6/etc/blocked_incoming_email_country_ips
16:23:33 1783 End
16:23:33 1783 internal_search_find: file="/etc/neighbor_netblocks"
16:23:33 1783 type=iplsearch key="10.1.34.231" opts=NULL
16:23:33 1783 file lookup required for 10.1.34.231
16:23:33 1783 in /etc/neighbor_netblocks
16:23:33 1783 lookup yielded:
16:23:33 1783 host in "net-iplsearch;/etc/neighbor_netblocks"? yes (matched "net-iplsearch;/etc/neighbor_netblocks")
16:23:33 1783 data from lookup saved for cache for +neighbor_netblocks: key '10.1.34.231' value ''
16:23:33 1783 host in ": +loopback : +neighbor_netblocks : +trustedmailhosts : +recent_authed_mail_ips : +backupmx_hosts : +skipsmtpcheck_hosts : +senderverifybypass_hosts : +greylist_trusted_netblocks : +cpanel_mail_netblocks"? yes (matched "+neighbor_netblocks")
16:23:33 1783 warn: condition test failed in ACL "acl_smtp_connect"
16:23:33 1783 processing "accept" (/etc/exim.conf 440)
16:23:33 1783 check hosts = : +loopback : +recent_authed_mail_ips : +backupmx_hosts
16:23:33 1783 cached no match for +loopback
16:23:33 1783 cached lookup data = NULL
16:23:33 1783 search_open: iplsearch "/etc/recent_authed_mail_ips"
16:23:33 1783 search_find: file="/etc/recent_authed_mail_ips"
16:23:33 1783 key="10.1.34.231" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 6/etc/recent_authed_mail_ips
16:23:33 1783 6/etc/neighbor_netblocks
16:23:33 1783 6/etc/blocked_incoming_email_country_ips
16:23:33 1783 End
16:23:33 1783 internal_search_find: file="/etc/recent_authed_mail_ips"
16:23:33 1783 type=iplsearch key="10.1.34.231" opts=NULL
16:23:33 1783 file lookup required for 10.1.34.231
16:23:33 1783 in /etc/recent_authed_mail_ips
16:23:33 1783 lookup failed
16:23:33 1783 host in "net-iplsearch;/etc/recent_authed_mail_ips"? no (end of list)
16:23:33 1783 sender host name required, to match against lsearch;/etc/backupmxhosts
16:23:33 1783 looking up host name for 10.1.34.231
16:23:33 1783 DNS lookup of 231.34.1.10.in-addr.arpa (PTR) gave HOST_NOT_FOUND
16:23:33 1783 returning DNS_NOMATCH
16:23:33 1783 faking res_search(PTR) response length as 65535
16:23:33 1783 writing neg-cache entry for 231.34.1.10.in-addr.arpa-PTR-80043, ttl 300
16:23:33 1783 IP address lookup using gethostbyaddr()
16:23:33 1783 IP address lookup failed: h_errno=1
16:23:33 1783 LOG: host_lookup_failed MAIN
16:23:33 1783 no host name found for IP address 10.1.34.231
16:23:33 1783 host in "lsearch;/etc/backupmxhosts"? no (failed to find host name for 10.1.34.231)
16:23:33 1783 host in ": +loopback : +recent_authed_mail_ips : +backupmx_hosts"? no (end of list)
16:23:33 1783 accept: condition test failed in ACL "acl_smtp_connect"
16:23:33 1783 processing "accept" (/etc/exim.conf 443)
16:23:33 1783 check hosts = +trustedmailhosts
16:23:33 1783 sender host name required, to match against lsearch;/etc/trustedmailhosts
16:23:33 1783 host in "lsearch;/etc/trustedmailhosts"? no (failed to find host name for 10.1.34.231)
16:23:33 1783 host in "+trustedmailhosts"? no (end of list)
16:23:33 1783 accept: condition test failed in ACL "acl_smtp_connect"
16:23:33 1783 processing "accept" (/etc/exim.conf 446)
16:23:33 1783 ?considering: ${if match_ip{$sender_host_address}{net-iplsearch;/etc/trustedmailhosts}{1}{0}}
16:23:33 1783 ?considering: $sender_host_address}{net-iplsearch;/etc/trustedmailhosts}{1}{0}}
16:23:33 1783 ???expanding: $sender_host_address
16:23:33 1783 ??????result: 10.1.34.231
16:23:33 1783 ?considering: net-iplsearch;/etc/trustedmailhosts}{1}{0}}
16:23:33 1783 ???expanding: net-iplsearch;/etc/trustedmailhosts
16:23:33 1783 ??????result: net-iplsearch;/etc/trustedmailhosts
16:23:33 1783 search_open: iplsearch "/etc/trustedmailhosts"
16:23:33 1783 search_find: file="/etc/trustedmailhosts"
16:23:33 1783 key="10.1.34.231" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 6/etc/trustedmailhosts
16:23:33 1783 6/etc/recent_authed_mail_ips
16:23:33 1783 6/etc/neighbor_netblocks
16:23:33 1783 6/etc/blocked_incoming_email_country_ips
16:23:33 1783 End
16:23:33 1783 internal_search_find: file="/etc/trustedmailhosts"
16:23:33 1783 type=iplsearch key="10.1.34.231" opts=NULL
16:23:33 1783 file lookup required for 10.1.34.231
16:23:33 1783 in /etc/trustedmailhosts
16:23:33 1783 lookup failed
16:23:33 1783 10.1.34.231 in "net-iplsearch;/etc/trustedmailhosts"? no (end of list)
16:23:33 1783 ???condition: match_ip{$sender_host_address}{net-iplsearch;/etc/trustedmailhosts}
16:23:33 1783 ??????result: false
16:23:33 1783 ????scanning: 1}{0}}
16:23:33 1783 ???expanding: 1
16:23:33 1783 ??????result: 1
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ?considering: 0}}
16:23:33 1783 ???expanding: 0
16:23:33 1783 ??????result: 0
16:23:33 1783 ???expanding: ${if match_ip{$sender_host_address}{net-iplsearch;/etc/trustedmailhosts}{1}{0}}
16:23:33 1783 ??????result: 0
16:23:33 1783 check condition = ${if match_ip{$sender_host_address}{net-iplsearch;/etc/trustedmailhosts}{1}{0}}
16:23:33 1783 = 0
16:23:33 1783 accept: condition test failed in ACL "acl_smtp_connect"
16:23:33 1783 processing "defer" (/etc/exim.conf 449)
16:23:33 1783 ?considering: ${if eq {$received_port}{25}{yes}{no}}
16:23:33 1783 ?considering: $received_port}{25}{yes}{no}}
16:23:33 1783 ???expanding: $received_port
16:23:33 1783 ??????result: 465
16:23:33 1783 ?considering: 25}{yes}{no}}
16:23:33 1783 ???expanding: 25
16:23:33 1783 ??????result: 25
16:23:33 1783 ???condition: eq {$received_port}{25}
16:23:33 1783 ??????result: false
16:23:33 1783 ????scanning: yes}{no}}
16:23:33 1783 ???expanding: yes
16:23:33 1783 ??????result: yes
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ?considering: no}}
16:23:33 1783 ???expanding: no
16:23:33 1783 ??????result: no
16:23:33 1783 ???expanding: ${if eq {$received_port}{25}{yes}{no}}
16:23:33 1783 ??????result: no
16:23:33 1783 check condition = ${if eq {$received_port}{25}{yes}{no}}
16:23:33 1783 = no
16:23:33 1783 defer: condition test failed in ACL "acl_smtp_connect"
16:23:33 1783 processing "warn" (/etc/exim.conf 459)
16:23:33 1783 ?considering: ${if eq {$received_port}{25}{yes}{no}}
16:23:33 1783 ?considering: $received_port}{25}{yes}{no}}
16:23:33 1783 ???expanding: $received_port
16:23:33 1783 ??????result: 465
16:23:33 1783 ?considering: 25}{yes}{no}}
16:23:33 1783 ???expanding: 25
16:23:33 1783 ??????result: 25
16:23:33 1783 ???condition: eq {$received_port}{25}
16:23:33 1783 ??????result: false
16:23:33 1783 ????scanning: yes}{no}}
16:23:33 1783 ???expanding: yes
16:23:33 1783 ??????result: yes
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ?considering: no}}
16:23:33 1783 ???expanding: no
16:23:33 1783 ??????result: no
16:23:33 1783 ???expanding: ${if eq {$received_port}{25}{yes}{no}}
16:23:33 1783 ??????result: no
16:23:33 1783 check condition = ${if eq {$received_port}{25}{yes}{no}}
16:23:33 1783 = no
16:23:33 1783 warn: condition test failed in ACL "acl_smtp_connect"
16:23:33 1783 processing "defer" (/etc/exim.conf 466)
16:23:33 1783 ?considering: ${if eq {$received_port}{25}{yes}{no}}
16:23:33 1783 ?considering: $received_port}{25}{yes}{no}}
16:23:33 1783 ???expanding: $received_port
16:23:33 1783 ??????result: 465
16:23:33 1783 ?considering: 25}{yes}{no}}
16:23:33 1783 ???expanding: 25
16:23:33 1783 ??????result: 25
16:23:33 1783 ???condition: eq {$received_port}{25}
16:23:33 1783 ??????result: false
16:23:33 1783 ????scanning: yes}{no}}
16:23:33 1783 ???expanding: yes
16:23:33 1783 ??????result: yes
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ?considering: no}}
16:23:33 1783 ???expanding: no
16:23:33 1783 ??????result: no
16:23:33 1783 ???expanding: ${if eq {$received_port}{25}{yes}{no}}
16:23:33 1783 ??????result: no
16:23:33 1783 check condition = ${if eq {$received_port}{25}{yes}{no}}
16:23:33 1783 = no
16:23:33 1783 defer: condition test failed in ACL "acl_smtp_connect"
16:23:33 1783 processing "drop" (/etc/exim.conf 478)
16:23:33 1783 message: Your host is not allowed to connect to this server.
16:23:33 1783 l_message: Host is banned
16:23:33 1783 check hosts = +spammeripblocks
16:23:33 1783 search_open: iplsearch "/etc/spammeripblocks"
16:23:33 1783 search_find: file="/etc/spammeripblocks"
16:23:33 1783 key="10.1.34.231" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 6/etc/spammeripblocks
16:23:33 1783 6/etc/trustedmailhosts
16:23:33 1783 6/etc/recent_authed_mail_ips
16:23:33 1783 6/etc/neighbor_netblocks
16:23:33 1783 6/etc/blocked_incoming_email_country_ips
16:23:33 1783 End
16:23:33 1783 internal_search_find: file="/etc/spammeripblocks"
16:23:33 1783 type=iplsearch key="10.1.34.231" opts=NULL
16:23:33 1783 file lookup required for 10.1.34.231
16:23:33 1783 in /etc/spammeripblocks
16:23:33 1783 lookup failed
16:23:33 1783 host in "net-iplsearch;/etc/spammeripblocks"? no (end of list)
16:23:33 1783 host in "+spammeripblocks"? no (end of list)
16:23:33 1783 drop: condition test failed in ACL "acl_smtp_connect"
16:23:33 1783 processing "accept" (/etc/exim.conf 493)
16:23:33 1783 accept: condition test succeeded in ACL "acl_smtp_connect"
16:23:33 1783 end of ACL "acl_smtp_connect": ACCEPT
16:23:33 1783 ?considering: ${primary_hostname} ESMTP Exim ${version_number} #${compile_number} ${tod_full}
16:23:33 1783 We do not authorize the use of this system to transport unsolicited,
16:23:33 1783 and/or bulk e-mail.
16:23:33 1783 ???expanding: ${primary_hostname} ESMTP Exim ${version_number} #${compile_number} ${tod_full}
16:23:33 1783 We do not authorize the use of this system to transport unsolicited,
16:23:33 1783 and/or bulk e-mail.
16:23:33 1783 ??????result: extra.felipe.dev ESMTP Exim 4.94 #2 Tue, 30 Mar 2021 16:23:33 -0500
16:23:33 1783 We do not authorize the use of this system to transport unsolicited,
16:23:33 1783 and/or bulk e-mail.
16:23:33 1783 host in pipelining_connect_advertise_hosts? yes (matched "*")
16:23:33 1783 SMTP>> 220-extra.felipe.dev ESMTP Exim 4.94 #2 Tue, 30 Mar 2021 16:23:33 -0500
16:23:33 1783 220-We do not authorize the use of this system to transport unsolicited,
16:23:33 1783 220 and/or bulk e-mail.
16:23:33 1783 tls_write(0x14308a0, 174)
16:23:33 1783 SSL_write(0x147d9b0, 0x14308a0, 174)
16:23:33 1783 outbytes=174 error=0
16:23:33 1783 Process 1783 is ready for new message
16:23:33 1783 smtp_setup_msg entered
16:23:33 1783 Calling SSL_read(0x147d9b0, 0x14816c0, 4096)
16:23:33 1783 SMTP<< EHLO felipe.dev.cpanel.net
16:23:33 1783 felipe.dev.cpanel.net in helo_lookup_domains? no (end of list)
16:23:33 1783 sender_fullhost = (felipe.dev.cpanel.net) [10.1.34.231]:33586
16:23:33 1783 sender_rcvhost = [10.1.34.231] (port=33586 helo=felipe.dev.cpanel.net)
16:23:33 1783 set_process_info: 1783 handling TLS incoming connection from (felipe.dev.cpanel.net) [10.1.34.231]:33586
16:23:33 1783 spf_conn_init: felipe.dev.cpanel.net 10.1.34.231
16:23:33 1783 using ACL "acl_smtp_helo"
16:23:33 1783 processing "accept" (/etc/exim.conf 628)
16:23:33 1783 accept: condition test succeeded in ACL "acl_smtp_helo"
16:23:33 1783 end of ACL "acl_smtp_helo": ACCEPT
16:23:33 1783 host in dsn_advertise_hosts? no (option unset)
16:23:33 1783 host in pipelining_advertise_hosts? yes (matched "*")
16:23:33 1783 host in auth_advertise_hosts? yes (matched "*")
16:23:33 1783 Evaluating advertise_condition for dovecot_plain PLAIN athenticator
16:23:33 1783 ?considering: ${if or {{def:tls_cipher}{match_ip{$sender_host_address}{+loopback}}}{1}{0}}
16:23:33 1783 ????scanning: $sender_host_address}{+loopback}}}{1}{0}}
16:23:33 1783 ???expanding: $sender_host_address
16:23:33 1783 ??????result:
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ????scanning: +loopback}}}{1}{0}}
16:23:33 1783 ???expanding: +loopback
16:23:33 1783 ??????result: +loopback
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???condition: or {{def:tls_cipher}{match_ip{$sender_host_address}{+loopback}}}
16:23:33 1783 ??????result: true
16:23:33 1783 ?considering: 1}{0}}
16:23:33 1783 ???expanding: 1
16:23:33 1783 ??????result: 1
16:23:33 1783 ????scanning: 0}}
16:23:33 1783 ???expanding: 0
16:23:33 1783 ??????result: 0
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???expanding: ${if or {{def:tls_cipher}{match_ip{$sender_host_address}{+loopback}}}{1}{0}}
16:23:33 1783 ??????result: 1
16:23:33 1783 Evaluating advertise_condition for dovecot_login LOGIN athenticator
16:23:33 1783 ?considering: ${if or {{def:tls_cipher}{match_ip{$sender_host_address}{+loopback}}}{1}{0}}
16:23:33 1783 ????scanning: $sender_host_address}{+loopback}}}{1}{0}}
16:23:33 1783 ???expanding: $sender_host_address
16:23:33 1783 ??????result:
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ????scanning: +loopback}}}{1}{0}}
16:23:33 1783 ???expanding: +loopback
16:23:33 1783 ??????result: +loopback
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???condition: or {{def:tls_cipher}{match_ip{$sender_host_address}{+loopback}}}
16:23:33 1783 ??????result: true
16:23:33 1783 ?considering: 1}{0}}
16:23:33 1783 ???expanding: 1
16:23:33 1783 ??????result: 1
16:23:33 1783 ????scanning: 0}}
16:23:33 1783 ???expanding: 0
16:23:33 1783 ??????result: 0
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???expanding: ${if or {{def:tls_cipher}{match_ip{$sender_host_address}{+loopback}}}{1}{0}}
16:23:33 1783 ??????result: 1
16:23:33 1783 host in chunking_advertise_hosts? no (end of list)
16:23:33 1783 host in smtputf8_advertise_hosts? no (end of list)
16:23:33 1783 tls_write(0x14a72a0, 165, more)
16:23:33 1783 SMTP>> 250-extra.felipe.dev Hello felipe.dev.cpanel.net [10.1.34.231]
16:23:33 1783 250-SIZE 52428800
16:23:33 1783 250-8BITMIME
16:23:33 1783 250-PIPELINING
16:23:33 1783 250-X_PIPE_CONNECT
16:23:33 1783 250-AUTH PLAIN LOGIN
16:23:33 1783 250 HELP
16:23:33 1783 SMTP<< AUTH PLAIN AG1haWxlcgAyMzR3ZXI=
16:23:33 1783 dovecot authentication
16:23:33 1783 auth_dovecot_server 297
16:23:33 1783 auth_dovecot_server 300
16:23:33 1783 received: 'VERSION 1 2'
16:23:33 1783 106 read but unreturned bytes; strcut() gave 3 results: {VERSION} {1} {2} last is <null>
16:23:33 1783 auth_dovecot_server 297
16:23:33 1783 auth_dovecot_server 300
16:23:33 1783 received: 'MECH PLAIN plaintext'
16:23:33 1783 85 read but unreturned bytes; strcut() gave 3 results: {MECH} {PLAIN} {plaintext} last is <null>
16:23:33 1783 auth_dovecot_server 297
16:23:33 1783 auth_dovecot_server 300
16:23:33 1783 received: 'MECH LOGIN plaintext'
16:23:33 1783 64 read but unreturned bytes; strcut() gave 3 results: {MECH} {LOGIN} {plaintext} last is <null>
16:23:33 1783 auth_dovecot_server 297
16:23:33 1783 auth_dovecot_server 300
16:23:33 1783 received: 'SPID 26702'
16:23:33 1783 53 read but unreturned bytes; strcut() gave 2 results: {SPID} {26702} last is <null>
16:23:33 1783 auth_dovecot_server 297
16:23:33 1783 auth_dovecot_server 300
16:23:33 1783 received: 'CUID 40'
16:23:33 1783 45 read but unreturned bytes; strcut() gave 2 results: {CUID} {40} last is <null>
16:23:33 1783 auth_dovecot_server 297
16:23:33 1783 auth_dovecot_server 300
16:23:33 1783 received: 'COOKIE 6802d1861941dc03ebe7bc4b2609e0d2'
16:23:33 1783 5 read but unreturned bytes; strcut() gave 2 results: {COOKIE} {6802d1861941dc03ebe7bc4b2609e0d2} last is <null>
16:23:33 1783 auth_dovecot_server 297
16:23:33 1783 auth_dovecot_server 300
16:23:33 1783 received: 'DONE'
16:23:33 1783 0 read but unreturned bytes; strcut() gave 1 results: {DONE} last is <null>
16:23:33 1783 sent: 'VERSION 1 0
16:23:33 1783 CPID 1783
16:23:33 1783 AUTH 1 PLAIN service=smtp secured rip=10.1.34.231 lip=172.16.1.13 nologin resp=AG1haWxlcgAyMzR3ZXI=
16:23:33 1783 '
16:23:33 1783 received: 'OK 1 user=mailer quota_rule=*:messages=2147483647 quota_clone_dict=file:/home/mailer/mail/dovecot-quota mail=maildir:/home/mailer/mail:UTF-8 gid=1193 uid=1191 quota_vsizes=yes quota_rule3=INBOX.Trash:ignore quota_rule4=INBOX.*@*:ignore quota2_grace=0 quota_status_overquota=552 5.2.2 Mailbox is full / Blocks limit exceeded / Inode limit exceeded quota=maildir:Mailbox:ns=INBOX. home=/home/mailer quota2=fs:cPanel Account quota_rule2=INBOX.INBOX:ignore'
16:23:33 1783 dovecot: warning: too many results from tab-splitting; saw 17 fields, room for 16
16:23:33 1783 0 read but unreturned bytes; strcut() gave 16 results: {OK} {1} {user=mailer} {quota_rule=*:messages=2147483647} {quota_clone_dict=file:/home/mailer/mail/dovecot-quota} {mail=maildir:/home/mailer/mail:UTF-8} {gid=1193} {uid=1191} {quota_vsizes=yes} {quota_rule3=INBOX.Trash:ignore} {quota_rule4=INBOX.*@*:ignore} {quota2_grace=0} {quota_status_overquota=552 5.2.2 Mailbox is full / Blocks limit exceeded / Inode limit exceeded} {quota=maildir:Mailbox:ns=INBOX.} {home=/home/mailer} {quota2=fs:cPanel Account} (max for capacity)
16:23:33 1783 dovecot_plain authenticator server_condition:
16:23:33 1783 $auth1 = mailer
16:23:33 1783 $1 = mailer
16:23:33 1783 ?considering: ${if and {{!match {$auth1}{\N[/]\N}}{eq{${if match {$auth1}{\N[+%:@]\N}{${lookup{${extract{2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ?considering: $auth1}{\N[/]\N}}{eq{${if match {$auth1}{\N[+%:@]\N}{${lookup{${extract{2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ???expanding: $auth1
16:23:33 1783 ??????result: mailer
16:23:33 1783 ?considering: \N[/]\N}}{eq{${if match {$auth1}{\N[+%:@]\N}{${lookup{${extract{2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ???expanding: \N[/]\N
16:23:33 1783 ??????result: [/]
16:23:33 1783 ?considering: ${if match {$auth1}{\N[+%:@]\N}{${lookup{${extract{2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ?considering: $auth1}{\N[+%:@]\N}{${lookup{${extract{2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ???expanding: $auth1
16:23:33 1783 ??????result: mailer
16:23:33 1783 ?considering: \N[+%:@]\N}{${lookup{${extract{2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ???expanding: \N[+%:@]\N
16:23:33 1783 ??????result: [+%:@]
16:23:33 1783 ???condition: match {$auth1}{\N[+%:@]\N}
16:23:33 1783 ??????result: false
16:23:33 1783 ????scanning: ${lookup{${extract{2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ?????scanning: ${extract{2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ? ????scanning: 2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ? ???expanding: 2
16:23:33 1783 ? ??????result: 2
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ????scanning: +%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ? ???expanding: +%:@
16:23:33 1783 ? ??????result: +%:@
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ? ????scanning: $auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ? ???expanding: $auth1
16:23:33 1783 ? ??????result: mailer
16:23:33 1783 ? ????skipping: result is not used
16:23:33 1783 ????expanding: ${extract{2}{+%:@}{$auth1}}
16:23:33 1783 ???????result:
16:23:33 1783 ?????skipping: result is not used
16:23:33 1783 ?????scanning: /etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ????expanding: /etc/demodomains
16:23:33 1783 ???????result: /etc/demodomains
16:23:33 1783 ?????skipping: result is not used
16:23:33 1783 ?????scanning: yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ????expanding: yes
16:23:33 1783 ???????result: yes
16:23:33 1783 ?????skipping: result is not used
16:23:33 1783 ???expanding: ${lookup{${extract{2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}
16:23:33 1783 ??????result:
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ?considering: ${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ??considering: $auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ????expanding: $auth1
16:23:33 1783 ???????result: mailer
16:23:33 1783 ??considering: /etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ????expanding: /etc/demousers
16:23:33 1783 ???????result: /etc/demousers
16:23:33 1783 search_open: lsearch "/etc/demousers"
16:23:33 1783 search_find: file="/etc/demousers"
16:23:33 1783 key="mailer" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 7/etc/demousers
16:23:33 1783 6/etc/spammeripblocks
16:23:33 1783 6/etc/trustedmailhosts
16:23:33 1783 6/etc/recent_authed_mail_ips
16:23:33 1783 6/etc/neighbor_netblocks
16:23:33 1783 6/etc/blocked_incoming_email_country_ips
16:23:33 1783 End
16:23:33 1783 internal_search_find: file="/etc/demousers"
16:23:33 1783 type=lsearch key="mailer" opts=NULL
16:23:33 1783 file lookup required for mailer
16:23:33 1783 in /etc/demousers
16:23:33 1783 lookup failed
16:23:33 1783 ?????scanning: yes}}}}}{}}}{true}{false}}
16:23:33 1783 ????expanding: yes
16:23:33 1783 ???????result: yes
16:23:33 1783 ?????skipping: result is not used
16:23:33 1783 ???expanding: ${lookup{$auth1}lsearch{/etc/demousers}{yes}}
16:23:33 1783 ??????result:
16:23:33 1783 ???expanding: ${if match {$auth1}{\N[+%:@]\N}{${lookup{${extract{2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}
16:23:33 1783 ??????result:
16:23:33 1783 ?considering: }}}{true}{false}}
16:23:33 1783 ???expanding:
16:23:33 1783 ??????result:
16:23:33 1783 ???condition: and {{!match {$auth1}{\N[/]\N}}{eq{${if match {$auth1}{\N[+%:@]\N}{${lookup{${extract{2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}
16:23:33 1783 ??????result: true
16:23:33 1783 ?considering: true}{false}}
16:23:33 1783 ???expanding: true
16:23:33 1783 ??????result: true
16:23:33 1783 ????scanning: false}}
16:23:33 1783 ???expanding: false
16:23:33 1783 ??????result: false
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???expanding: ${if and {{!match {$auth1}{\N[/]\N}}{eq{${if match {$auth1}{\N[+%:@]\N}{${lookup{${extract{2}{+%:@}{$auth1}}}lsearch{/etc/demodomains}{yes}}}{${lookup{$auth1}lsearch{/etc/demousers}{yes}}}}}{}}}{true}{false}}
16:23:33 1783 ??????result: true
16:23:33 1783 expanded string: true
16:23:33 1783 ?considering: $auth1
16:23:33 1783 ???expanding: $auth1
16:23:33 1783 ??????result: mailer
16:23:33 1783 SMTP>> 235 Authentication succeeded
16:23:33 1783 tls_write(0x14308a0, 30)
16:23:33 1783 SSL_write(0x147d9b0, 0x1495268, 195)
16:23:33 1783 outbytes=195 error=0
16:23:33 1783 SMTP<< MAIL FROM: canada@felipe.dev.cpanel.net
16:23:33 1783 spool directory space = 3477184K inodes = 6987952 check_space = 10240K inodes = 100 msg_size = 0
16:23:33 1783 log directory space = 3477184K inodes = 6987952 check_space = 10240K inodes = 100
16:23:33 1783 using ACL "acl_smtp_mail"
16:23:33 1783 processing "accept" (/etc/exim.conf 641)
16:23:33 1783 check authenticated = *
16:23:33 1783 dovecot_plain in "*"? yes (matched "*")
16:23:33 1783 accept: condition test succeeded in ACL "acl_smtp_mail"
16:23:33 1783 end of ACL "acl_smtp_mail": ACCEPT
16:23:33 1783 SMTP>> 250 OK
16:23:33 1783 tls_write(0x14308a0, 8)
16:23:33 1783 SSL_write(0x147d9b0, 0x14308a0, 8)
16:23:33 1783 outbytes=8 error=0
16:23:33 1783 SMTP<< RCPT TO: felipe@cpanel.com
16:23:33 1783 using ACL "acl_smtp_rcpt"
16:23:33 1783 processing "warn" (/etc/exim.conf 802)
16:23:33 1783 check !domains = +relay_domains
16:23:33 1783 search_open: lsearch "/etc/localdomains"
16:23:33 1783 search_find: file="/etc/localdomains"
16:23:33 1783 key="cpanel.com" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 7/etc/localdomains
16:23:33 1783 7/etc/demousers
16:23:33 1783 6/etc/spammeripblocks
16:23:33 1783 6/etc/trustedmailhosts
16:23:33 1783 6/etc/recent_authed_mail_ips
16:23:33 1783 6/etc/neighbor_netblocks
16:23:33 1783 6/etc/blocked_incoming_email_country_ips
16:23:33 1783 End
16:23:33 1783 internal_search_find: file="/etc/localdomains"
16:23:33 1783 type=lsearch key="cpanel.com" opts=NULL
16:23:33 1783 file lookup required for cpanel.com
16:23:33 1783 in /etc/localdomains
16:23:33 1783 lookup failed
16:23:33 1783 cpanel.com in "lsearch;/etc/localdomains"? no (end of list)
16:23:33 1783 search_open: lsearch "/etc/secondarymx"
16:23:33 1783 search_find: file="/etc/secondarymx"
16:23:33 1783 key="cpanel.com" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 7/etc/secondarymx
16:23:33 1783 7/etc/localdomains
16:23:33 1783 7/etc/demousers
16:23:33 1783 6/etc/spammeripblocks
16:23:33 1783 6/etc/trustedmailhosts
16:23:33 1783 6/etc/recent_authed_mail_ips
16:23:33 1783 6/etc/neighbor_netblocks
16:23:33 1783 6/etc/blocked_incoming_email_country_ips
16:23:33 1783 End
16:23:33 1783 internal_search_find: file="/etc/secondarymx"
16:23:33 1783 type=lsearch key="cpanel.com" opts=NULL
16:23:33 1783 file lookup required for cpanel.com
16:23:33 1783 in /etc/secondarymx
16:23:33 1783 lookup failed
16:23:33 1783 cpanel.com in "lsearch;/etc/secondarymx"? no (end of list)
16:23:33 1783 cpanel.com in "+local_domains : +secondarymx_domains"? no (end of list)
16:23:33 1783 cpanel.com in "+relay_domains"? no (end of list)
16:23:33 1783 check set acl_m_outbound_recipient = 1
16:23:33 1783 warn: condition test succeeded in ACL "acl_smtp_rcpt"
16:23:33 1783 processing "warn" (/etc/exim.conf 811)
16:23:33 1783 check !authenticated = *
16:23:33 1783 dovecot_plain in "*"? yes (matched "*")
16:23:33 1783 warn: condition test failed in ACL "acl_smtp_rcpt"
16:23:33 1783 processing "warn" (/etc/exim.conf 821)
16:23:33 1783 check control = dkim_disable_verify
16:23:33 1783 warn: condition test succeeded in ACL "acl_smtp_rcpt"
16:23:33 1783 processing "deny" (/etc/exim.conf 831)
16:23:33 1783 message: Your host is not allowed to connect to this server.
16:23:33 1783 l_message: Sender domain is banned
16:23:33 1783 check sender_domains = !+local_domains : +blocked_domains
16:23:33 1783 search_open: lsearch "/etc/localdomains"
16:23:33 1783 cached open
16:23:33 1783 search_find: file="/etc/localdomains"
16:23:33 1783 key="felipe.dev.cpanel.net" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 7/etc/localdomains
16:23:33 1783 7/etc/secondarymx
16:23:33 1783 7/etc/demousers
16:23:33 1783 6/etc/spammeripblocks
16:23:33 1783 6/etc/trustedmailhosts
16:23:33 1783 6/etc/recent_authed_mail_ips
16:23:33 1783 6/etc/neighbor_netblocks
16:23:33 1783 6/etc/blocked_incoming_email_country_ips
16:23:33 1783 End
16:23:33 1783 internal_search_find: file="/etc/localdomains"
16:23:33 1783 type=lsearch key="felipe.dev.cpanel.net" opts=NULL
16:23:33 1783 file lookup required for felipe.dev.cpanel.net
16:23:33 1783 in /etc/localdomains
16:23:33 1783 lookup failed
16:23:33 1783 felipe.dev.cpanel.net in "lsearch;/etc/localdomains"? no (end of list)
16:23:33 1783 search_open: wildlsearch "/etc/blocked_incoming_email_domains"
16:23:33 1783 search_find: file="/etc/blocked_incoming_email_domains"
16:23:33 1783 key="felipe.dev.cpanel.net" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 =/etc/blocked_incoming_email_domains
16:23:33 1783 7/etc/localdomains
16:23:33 1783 7/etc/secondarymx
16:23:33 1783 7/etc/demousers
16:23:33 1783 6/etc/spammeripblocks
16:23:33 1783 6/etc/trustedmailhosts
16:23:33 1783 6/etc/recent_authed_mail_ips
16:23:33 1783 6/etc/neighbor_netblocks
16:23:33 1783 6/etc/blocked_incoming_email_country_ips
16:23:33 1783 End
16:23:33 1783 internal_search_find: file="/etc/blocked_incoming_email_domains"
16:23:33 1783 type=wildlsearch key="felipe.dev.cpanel.net" opts=NULL
16:23:33 1783 file lookup required for felipe.dev.cpanel.net
16:23:33 1783 in /etc/blocked_incoming_email_domains
16:23:33 1783 felipe.dev.cpanel.net in "werwer.sdf"? no (end of list)
16:23:33 1783 lookup failed
16:23:33 1783 felipe.dev.cpanel.net in "wildlsearch;/etc/blocked_incoming_email_domains"? no (end of list)
16:23:33 1783 felipe.dev.cpanel.net in "!+local_domains : +blocked_domains"? no (end of list)
16:23:33 1783 deny: condition test failed in ACL "acl_smtp_rcpt"
16:23:33 1783 processing "accept" (/etc/exim.conf 838)
16:23:33 1783 check hosts = :
16:23:33 1783 host in ":"? no (end of list)
16:23:33 1783 accept: condition test failed in ACL "acl_smtp_rcpt"
16:23:33 1783 processing "accept" (/etc/exim.conf 846)
16:23:33 1783 check domains = +local_domains
16:23:33 1783 cached no match for +local_domains
16:23:33 1783 cached lookup data = NULL
16:23:33 1783 cpanel.com in "+local_domains"? no (end of list)
16:23:33 1783 accept: condition test failed in ACL "acl_smtp_rcpt"
16:23:33 1783 processing "accept" (/etc/exim.conf 853)
16:23:33 1783 ?considering: ${extract{size}{${stat:/etc/skipsmtpcheckhosts}}}
16:23:33 1783 ?considering: size}{${stat:/etc/skipsmtpcheckhosts}}}
16:23:33 1783 ???expanding: size
16:23:33 1783 ??????result: size
16:23:33 1783 ?considering: ${stat:/etc/skipsmtpcheckhosts}}}
16:23:33 1783 ?considering: /etc/skipsmtpcheckhosts}}}
16:23:33 1783 ???expanding: /etc/skipsmtpcheckhosts
16:23:33 1783 ??????result: /etc/skipsmtpcheckhosts
16:23:33 1783 ???expanding: ${stat:/etc/skipsmtpcheckhosts}
16:23:33 1783 ??????result: mode=0640 smode=-rw-r----- inode=902808 device=64769 links=1 uid=0 gid=12 size=0 atime=1603154682 mtime=1565886419 ctime=1565886419
16:23:33 1783 ???expanding: ${extract{size}{${stat:/etc/skipsmtpcheckhosts}}}
16:23:33 1783 ??????result: 0
16:23:33 1783 check condition = ${extract{size}{${stat:/etc/skipsmtpcheckhosts}}}
16:23:33 1783 = 0
16:23:33 1783 accept: condition test failed in ACL "acl_smtp_rcpt"
16:23:33 1783 processing "deny" (/etc/exim.conf 860)
16:23:33 1783 check domains = !$primary_hostname : +local_domains
16:23:33 1783 ?considering: !$primary_hostname : +local_domains
16:23:33 1783 ???expanding: !$primary_hostname : +local_domains
16:23:33 1783 ??????result: !extra.felipe.dev : +local_domains
16:23:33 1783 cached no match for +local_domains
16:23:33 1783 cached lookup data = NULL
16:23:33 1783 cpanel.com in "!extra.felipe.dev : +local_domains"? no (end of list)
16:23:33 1783 deny: condition test failed in ACL "acl_smtp_rcpt"
16:23:33 1783 processing "deny" (/etc/exim.conf 867)
16:23:33 1783 check domains = ! +local_domains
16:23:33 1783 cached no match for +local_domains
16:23:33 1783 cached lookup data = NULL
16:23:33 1783 cpanel.com in "! +local_domains"? yes (end of list)
16:23:33 1783 ?considering: ${perl{check_outgoing_mail_suspended}}
16:23:33 1783 ?considering: check_outgoing_mail_suspended}}
16:23:33 1783 ???expanding: check_outgoing_mail_suspended
16:23:33 1783 ??????result: check_outgoing_mail_suspended
16:23:33 1783 Starting Perl interpreter
16:23:33 1783 ?considering: $originator_uid
16:23:33 1783 ???expanding: $originator_uid
16:23:33 1783 ??????result: 47
16:23:33 1783 ?considering: $originator_gid
16:23:33 1783 ???expanding: $originator_gid
16:23:33 1783 ??????result: 12
16:23:33 1783 ?considering: $acl_c_vhost_owner
16:23:33 1783 ???expanding: $acl_c_vhost_owner
16:23:33 1783 ??????result:
16:23:33 1783 ?considering: $message_exim_id
16:23:33 1783 ???expanding: $message_exim_id
16:23:33 1783 ??????result:
16:23:33 1783 ?considering: $authenticated_id
16:23:33 1783 ???expanding: $authenticated_id
16:23:33 1783 ??????result: mailer
16:23:33 1783 ???(tainted)
16:23:33 1783 ?considering: ${lookup{\Nmailer\N}lsearch{/etc/domainusers}{$value}}
16:23:33 1783 ?considering: \Nmailer\N}lsearch{/etc/domainusers}{$value}}
16:23:33 1783 ???expanding: \Nmailer\N
16:23:33 1783 ??????result: mailer
16:23:33 1783 ?considering: /etc/domainusers}{$value}}
16:23:33 1783 ???expanding: /etc/domainusers
16:23:33 1783 ??????result: /etc/domainusers
16:23:33 1783 search_open: lsearch "/etc/domainusers"
16:23:33 1783 search_find: file="/etc/domainusers"
16:23:33 1783 key="mailer" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 7/etc/domainusers
16:23:33 1783 =/etc/blocked_incoming_email_domains
16:23:33 1783 7/etc/localdomains
16:23:33 1783 7/etc/secondarymx
16:23:33 1783 7/etc/demousers
16:23:33 1783 6/etc/spammeripblocks
16:23:33 1783 6/etc/trustedmailhosts
16:23:33 1783 6/etc/recent_authed_mail_ips
16:23:33 1783 6/etc/neighbor_netblocks
16:23:33 1783 6/etc/blocked_incoming_email_country_ips
16:23:33 1783 End
16:23:33 1783 internal_search_find: file="/etc/domainusers"
16:23:33 1783 type=lsearch key="mailer" opts=NULL
16:23:33 1783 file lookup required for mailer
16:23:33 1783 in /etc/domainusers
16:23:33 1783 lookup yielded: mailer.tld
16:23:33 1783 ?considering: $value}}
16:23:33 1783 ???expanding: $value
16:23:33 1783 ??????result: mailer.tld
16:23:33 1783 ???expanding: ${lookup{\Nmailer\N}lsearch{/etc/domainusers}{$value}}
16:23:33 1783 ??????result: mailer.tld
16:23:33 1783 ?considering: $sender_address
16:23:33 1783 ???expanding: $sender_address
16:23:33 1783 ??????result: canada@felipe.dev.cpanel.net
16:23:33 1783 ???(tainted)
16:23:33 1783 ?considering: $sender_address_domain
16:23:33 1783 ???expanding: $sender_address_domain
16:23:33 1783 ??????result: felipe.dev.cpanel.net
16:23:33 1783 ???(tainted)
16:23:33 1783 ?considering: ${domain:$h_from:}
16:23:33 1783 ?considering: $h_from:}
16:23:33 1783 ???expanding: $h_from:
16:23:33 1783 ??????result:
16:23:33 1783 ???expanding: ${domain:$h_from:}
16:23:33 1783 ??????result:
16:23:33 1783 ?considering: ${local_part:$h_from:}
16:23:33 1783 ?considering: $h_from:}
16:23:33 1783 ???expanding: $h_from:
16:23:33 1783 ??????result:
16:23:33 1783 ???expanding: ${local_part:$h_from:}
16:23:33 1783 ??????result:
16:23:33 1783 ?considering: $sender_address_domain
16:23:33 1783 ???expanding: $sender_address_domain
16:23:33 1783 ??????result: felipe.dev.cpanel.net
16:23:33 1783 ???(tainted)
16:23:33 1783 ?considering: $sender_address_local_part
16:23:33 1783 ???expanding: $sender_address_local_part
16:23:33 1783 ??????result: canada
16:23:33 1783 ???(tainted)
16:23:33 1783 ?considering: $primary_hostname
16:23:33 1783 ???expanding: $primary_hostname
16:23:33 1783 ??????result: extra.felipe.dev
16:23:33 1783 ?considering: ${lookup{\Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:33 1783 ?considering: \Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:33 1783 ???expanding: \Nfelipe.dev.cpanel.net\N
16:23:33 1783 ??????result: felipe.dev.cpanel.net
16:23:33 1783 ?considering: /etc/userdomains}{$value}}
16:23:33 1783 ???expanding: /etc/userdomains
16:23:33 1783 ??????result: /etc/userdomains
16:23:33 1783 search_open: lsearch "/etc/userdomains"
16:23:33 1783 search_find: file="/etc/userdomains"
16:23:33 1783 key="felipe.dev.cpanel.net" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 7/etc/userdomains
16:23:33 1783 7/etc/domainusers
16:23:33 1783 =/etc/blocked_incoming_email_domains
16:23:33 1783 7/etc/localdomains
16:23:33 1783 7/etc/secondarymx
16:23:33 1783 7/etc/demousers
16:23:33 1783 6/etc/spammeripblocks
16:23:33 1783 6/etc/trustedmailhosts
16:23:33 1783 6/etc/recent_authed_mail_ips
16:23:33 1783 6/etc/neighbor_netblocks
16:23:33 1783 6/etc/blocked_incoming_email_country_ips
16:23:33 1783 End
16:23:33 1783 internal_search_find: file="/etc/userdomains"
16:23:33 1783 type=lsearch key="felipe.dev.cpanel.net" opts=NULL
16:23:33 1783 file lookup required for felipe.dev.cpanel.net
16:23:33 1783 in /etc/userdomains
16:23:33 1783 lookup failed
16:23:33 1783 ????scanning: $value}}
16:23:33 1783 ???expanding: $value
16:23:33 1783 ??????result:
16:23:33 1783 ????skipping: result is not used
16:23:33 1783 ???expanding: ${lookup{\Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:33 1783 ??????result:
16:23:33 1783 ?considering: ${lookup{\Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:33 1783 ?considering: \Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:33 1783 ???expanding: \Nfelipe.dev.cpanel.net\N
16:23:33 1783 ??????result: felipe.dev.cpanel.net
16:23:33 1783 ?considering: /etc/userdomains}{$value}}
16:23:33 1783 ???expanding: /etc/userdomains
16:23:33 1783 ??????result: /etc/userdomains
16:23:33 1783 search_open: lsearch "/etc/userdomains"
16:23:33 1783 cached open
16:23:33 1783 search_find: file="/etc/userdomains"
16:23:33 1783 key="felipe.dev.cpanel.net" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:33 1783 LRU list:
16:23:33 1783 7/etc/userdomains
16:23:33 1783 7/etc/domainusers
16:23:33 1783 =/etc/blocked_incoming_email_domains
16:23:33 1783 7/etc/localdomains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/userdomains"
16:23:34 1783 type=lsearch key="felipe.dev.cpanel.net" opts=NULL
16:23:34 1783 cached data used for lookup of felipe.dev.cpanel.net
16:23:34 1783 in /etc/userdomains
16:23:34 1783 lookup failed
16:23:34 1783 ????scanning: $value}}
16:23:34 1783 ???expanding: $value
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup{\Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${lookup{\Nmailer.tld\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ?considering: \Nmailer.tld\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ???expanding: \Nmailer.tld\N
16:23:34 1783 ??????result: mailer.tld
16:23:34 1783 ?considering: /etc/userdomains}{$value}}
16:23:34 1783 ???expanding: /etc/userdomains
16:23:34 1783 ??????result: /etc/userdomains
16:23:34 1783 search_open: lsearch "/etc/userdomains"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="/etc/userdomains"
16:23:34 1783 key="mailer.tld" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/userdomains"
16:23:34 1783 type=lsearch key="mailer.tld" opts=NULL
16:23:34 1783 file lookup required for mailer.tld
16:23:34 1783 in /etc/userdomains
16:23:34 1783 lookup yielded: mailer
16:23:34 1783 ?considering: $value}}
16:23:34 1783 ???expanding: $value
16:23:34 1783 ??????result: mailer
16:23:34 1783 ???expanding: ${lookup{\Nmailer.tld\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??????result: mailer
16:23:34 1783 ?considering: ${lookup{\Nmailer\N}lsearch{/etc/outgoing_mail_suspended_users}{1}{0}}
16:23:34 1783 ?considering: \Nmailer\N}lsearch{/etc/outgoing_mail_suspended_users}{1}{0}}
16:23:34 1783 ???expanding: \Nmailer\N
16:23:34 1783 ??????result: mailer
16:23:34 1783 ?considering: /etc/outgoing_mail_suspended_users}{1}{0}}
16:23:34 1783 ???expanding: /etc/outgoing_mail_suspended_users
16:23:34 1783 ??????result: /etc/outgoing_mail_suspended_users
16:23:34 1783 search_open: lsearch "/etc/outgoing_mail_suspended_users"
16:23:34 1783 search_find: file="/etc/outgoing_mail_suspended_users"
16:23:34 1783 key="mailer" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/outgoing_mail_suspended_users"
16:23:34 1783 type=lsearch key="mailer" opts=NULL
16:23:34 1783 file lookup required for mailer
16:23:34 1783 in /etc/outgoing_mail_suspended_users
16:23:34 1783 lookup failed
16:23:34 1783 ????scanning: 1}{0}}
16:23:34 1783 ???expanding: 1
16:23:34 1783 ??????result: 1
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ?considering: 0}}
16:23:34 1783 ???expanding: 0
16:23:34 1783 ??????result: 0
16:23:34 1783 ???expanding: ${lookup{\Nmailer\N}lsearch{/etc/outgoing_mail_suspended_users}{1}{0}}
16:23:34 1783 ??????result: 0
16:23:34 1783 ?considering: ${lookup{\Nmailer\N}lsearch{/etc/outgoing_mail_suspended_users}{1}{0}}
16:23:34 1783 ?considering: \Nmailer\N}lsearch{/etc/outgoing_mail_suspended_users}{1}{0}}
16:23:34 1783 ???expanding: \Nmailer\N
16:23:34 1783 ??????result: mailer
16:23:34 1783 ?considering: /etc/outgoing_mail_suspended_users}{1}{0}}
16:23:34 1783 ???expanding: /etc/outgoing_mail_suspended_users
16:23:34 1783 ??????result: /etc/outgoing_mail_suspended_users
16:23:34 1783 search_open: lsearch "/etc/outgoing_mail_suspended_users"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="/etc/outgoing_mail_suspended_users"
16:23:34 1783 key="mailer" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/outgoing_mail_suspended_users"
16:23:34 1783 type=lsearch key="mailer" opts=NULL
16:23:34 1783 cached data used for lookup of mailer
16:23:34 1783 in /etc/outgoing_mail_suspended_users
16:23:34 1783 lookup failed
16:23:34 1783 ????scanning: 1}{0}}
16:23:34 1783 ???expanding: 1
16:23:34 1783 ??????result: 1
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ?considering: 0}}
16:23:34 1783 ???expanding: 0
16:23:34 1783 ??????result: 0
16:23:34 1783 ???expanding: ${lookup{\Nmailer\N}lsearch{/etc/outgoing_mail_suspended_users}{1}{0}}
16:23:34 1783 ??????result: 0
16:23:34 1783 ???expanding: ${perl{check_outgoing_mail_suspended}}
16:23:34 1783 ??????result: 0
16:23:34 1783 check condition = ${perl{check_outgoing_mail_suspended}}
16:23:34 1783 = 0
16:23:34 1783 deny: condition test failed in ACL "acl_smtp_rcpt"
16:23:34 1783 processing "accept" (/etc/exim.conf 883)
16:23:34 1783 check domains = +local_domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 cpanel.com in "+local_domains"? no (end of list)
16:23:34 1783 accept: condition test failed in ACL "acl_smtp_rcpt"
16:23:34 1783 processing "accept" (/etc/exim.conf 902)
16:23:34 1783 check authenticated = *
16:23:34 1783 dovecot_plain in "*"? yes (matched "*")
16:23:34 1783 ?considering: ${if eq{${lookup{$sender_address_domain}lsearch{/etc/userdomains}}}{$sender_address_local_part}}
16:23:34 1783 ?considering: ${lookup{$sender_address_domain}lsearch{/etc/userdomains}}}{$sender_address_local_part}}
16:23:34 1783 ?considering: $sender_address_domain}lsearch{/etc/userdomains}}}{$sender_address_local_part}}
16:23:34 1783 ???expanding: $sender_address_domain
16:23:34 1783 ??????result: felipe.dev.cpanel.net
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: /etc/userdomains}}}{$sender_address_local_part}}
16:23:34 1783 ???expanding: /etc/userdomains
16:23:34 1783 ??????result: /etc/userdomains
16:23:34 1783 search_open: lsearch "/etc/userdomains"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="/etc/userdomains"
16:23:34 1783 key="felipe.dev.cpanel.net" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/userdomains"
16:23:34 1783 type=lsearch key="felipe.dev.cpanel.net" opts=NULL
16:23:34 1783 cached data used for lookup of felipe.dev.cpanel.net
16:23:34 1783 in /etc/userdomains
16:23:34 1783 lookup failed
16:23:34 1783 ???expanding: ${lookup{$sender_address_domain}lsearch{/etc/userdomains}}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $sender_address_local_part}}
16:23:34 1783 ???expanding: $sender_address_local_part
16:23:34 1783 ??????result: canada
16:23:34 1783 ???(tainted)
16:23:34 1783 ???condition: eq{${lookup{$sender_address_domain}lsearch{/etc/userdomains}}}{$sender_address_local_part}
16:23:34 1783 ??????result: false
16:23:34 1783 ???expanding: ${if eq{${lookup{$sender_address_domain}lsearch{/etc/userdomains}}}{$sender_address_local_part}}
16:23:34 1783 ??????result:
16:23:34 1783 check condition = ${if eq{${lookup{$sender_address_domain}lsearch{/etc/userdomains}}}{$sender_address_local_part}}
16:23:34 1783 =
16:23:34 1783 accept: condition test failed in ACL "acl_smtp_rcpt"
16:23:34 1783 processing "deny" (/etc/exim.conf 909)
16:23:34 1783 check hosts = ! +loopback : ! +senderverifybypass_hosts
16:23:34 1783 cached no match for +loopback
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 search_open: iplsearch "/etc/senderverifybypasshosts"
16:23:34 1783 search_find: file="/etc/senderverifybypasshosts"
16:23:34 1783 key="10.1.34.231" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 6/etc/senderverifybypasshosts
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/senderverifybypasshosts"
16:23:34 1783 type=iplsearch key="10.1.34.231" opts=NULL
16:23:34 1783 file lookup required for 10.1.34.231
16:23:34 1783 in /etc/senderverifybypasshosts
16:23:34 1783 lookup failed
16:23:34 1783 host in "net-iplsearch;/etc/senderverifybypasshosts"? no (end of list)
16:23:34 1783 host in "! +loopback : ! +senderverifybypass_hosts"? yes (end of list)
16:23:34 1783 check !verify = sender
16:23:34 1783 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16:23:34 1783 Verifying canada@felipe.dev.cpanel.net
16:23:34 1783 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16:23:34 1783 Considering canada@felipe.dev.cpanel.net
16:23:34 1783 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16:23:34 1783 routing canada@felipe.dev.cpanel.net
16:23:34 1783 --------> blackhole_dovenull router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 checking local_parts
16:23:34 1783 canada in "@dovenull"? no (end of list)
16:23:34 1783 blackhole_dovenull router skipped: local_parts mismatch
16:23:34 1783 --------> deliver_local_outside_jail router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 checking domains
16:23:34 1783 search_open: lsearch "/etc/localdomains"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="/etc/localdomains"
16:23:34 1783 key="felipe.dev.cpanel.net" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/localdomains
16:23:34 1783 6/etc/senderverifybypasshosts
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/localdomains"
16:23:34 1783 type=lsearch key="felipe.dev.cpanel.net" opts=NULL
16:23:34 1783 cached data used for lookup of felipe.dev.cpanel.net
16:23:34 1783 in /etc/localdomains
16:23:34 1783 lookup failed
16:23:34 1783 felipe.dev.cpanel.net in "lsearch;/etc/localdomains"? no (end of list)
16:23:34 1783 felipe.dev.cpanel.net in "+local_domains"? no (end of list)
16:23:34 1783 deliver_local_outside_jail router skipped: domains mismatch
16:23:34 1783 --------> suspendedcheck router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 checking domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 felipe.dev.cpanel.net in "+local_domains"? no (end of list)
16:23:34 1783 suspendedcheck router skipped: domains mismatch
16:23:34 1783 --------> mailman_virtual_router router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 checking domains
16:23:34 1783 ?considering: !$primary_hostname : +local_domains
16:23:34 1783 ???expanding: !$primary_hostname : +local_domains
16:23:34 1783 ??????result: !extra.felipe.dev : +local_domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 felipe.dev.cpanel.net in "!extra.felipe.dev : +local_domains"? no (end of list)
16:23:34 1783 mailman_virtual_router router skipped: domains mismatch
16:23:34 1783 --------> mailman_virtual_router_suffixed router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 mailman_virtual_router_suffixed router skipped: suffix mismatch
16:23:34 1783 --------> mailman_virtual_router_nodns router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 checking domains
16:23:34 1783 ?considering: $primary_hostname
16:23:34 1783 ???expanding: $primary_hostname
16:23:34 1783 ??????result: extra.felipe.dev
16:23:34 1783 felipe.dev.cpanel.net in "extra.felipe.dev"? no (end of list)
16:23:34 1783 mailman_virtual_router_nodns router skipped: domains mismatch
16:23:34 1783 --------> mailman_virtual_router_nodns_suffixed router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 mailman_virtual_router_nodns_suffixed router skipped: suffix mismatch
16:23:34 1783 --------> democheck router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 checking require_files
16:23:34 1783 file check: +/etc/demouids
16:23:34 1783 expanded file: /etc/demouids
16:23:34 1783 stat() yielded 0
16:23:34 1783 checking "condition" "${if and{{bool_lax{${if and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bo"...
16:23:34 1783 ?considering: ${if and{{bool_lax{${if and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ?considering: ${if and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ?considering: ${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ??considering: $originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: $originator_uid
16:23:34 1783 ???????result: 47
16:23:34 1783 ??considering: 100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: 100
16:23:34 1783 ???????result: 100
16:23:34 1783 ???condition: >= {$originator_uid}{100}
16:23:34 1783 ??????result: false
16:23:34 1783 ?????scanning: 1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: 1
16:23:34 1783 ???????result: 1
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ??considering: 0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: 0
16:23:34 1783 ???????result: 0
16:23:34 1783 ???expanding: ${if >= {$originator_uid}{100}{1}{0}}
16:23:34 1783 ??????result: 0
16:23:34 1783 considering bool_lax: 0
16:23:34 1783 bool_lax: condition evaluated to false
16:23:34 1783 ????scanning: ${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ?????scanning: size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: size
16:23:34 1783 ???????result: size
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ?????scanning: ${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ? ????scanning: /etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ? ???expanding: /etc/demouids
16:23:34 1783 ? ??????result: /etc/demouids
16:23:34 1783 ? ????skipping: result is not used
16:23:34 1783 ????expanding: ${stat:/etc/demouids}
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ???expanding: ${extract{size}{${stat:/etc/demouids}}}
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 considering bool_lax: <empty>
16:23:34 1783 bool_lax: condition evaluated to false
16:23:34 1783 ???condition: and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}
16:23:34 1783 ??????result: false
16:23:34 1783 ???expanding: ${if and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}
16:23:34 1783 ??????result:
16:23:34 1783 considering bool_lax: <empty>
16:23:34 1783 bool_lax: condition evaluated to false
16:23:34 1783 ????scanning: ${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????scanning: ${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ?????scanning: $originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: $originator_uid
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ?????scanning: /etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: /etc/demouids
16:23:34 1783 ???????result: /etc/demouids
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ?????scanning: $value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: $value
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ????scanning: }{false}{true}}}}}}
16:23:34 1783 ???expanding:
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???condition: eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}
16:23:34 1783 ??????result: false
16:23:34 1783 ????scanning: false}{true}}}}}}
16:23:34 1783 ???expanding: false
16:23:34 1783 ??????result: false
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ????scanning: true}}}}}}
16:23:34 1783 ???expanding: true
16:23:34 1783 ??????result: true
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???expanding: ${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}
16:23:34 1783 ??????result: true
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 considering bool_lax: true
16:23:34 1783 bool_lax: condition evaluated to true
16:23:34 1783 ???condition: and{{bool_lax{${if and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}
16:23:34 1783 ??????result: false
16:23:34 1783 ???expanding: ${if and{{bool_lax{${if and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ??????result:
16:23:34 1783 democheck router skipped: condition failure
16:23:34 1783 --------> blackhole_cpanel_at router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 checking domains
16:23:34 1783 ?considering: !$primary_hostname
16:23:34 1783 ???expanding: !$primary_hostname
16:23:34 1783 ??????result: !extra.felipe.dev
16:23:34 1783 felipe.dev.cpanel.net in "!extra.felipe.dev"? yes (end of list)
16:23:34 1783 checking local_parts
16:23:34 1783 canada in "cpanel"? no (end of list)
16:23:34 1783 blackhole_cpanel_at router skipped: local_parts mismatch
16:23:34 1783 --------> boxtrapper_autowhitelist router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 boxtrapper_autowhitelist router skipped: verify 1 0 0
16:23:34 1783 --------> check_mail_permissions router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 check_mail_permissions router skipped: verify 1 0 0
16:23:34 1783 --------> enforce_mail_permissions router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 enforce_mail_permissions router skipped: verify 1 0 0
16:23:34 1783 --------> increment_max_emails_per_hour_if_needed router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 increment_max_emails_per_hour_if_needed router skipped: verify 1 0 0
16:23:34 1783 --------> autoreply_dkim_lookuphost router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 checking domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 felipe.dev.cpanel.net in "! +local_domains"? yes (end of list)
16:23:34 1783 checking "condition" "${if and{{bool_lax{${perl{sender_domain_can_dkim_sign}}}}{bool_lax{${if or {{mat"...
16:23:34 1783 ?considering: ${if and{{bool_lax{${perl{sender_domain_can_dkim_sign}}}}{bool_lax{${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ?considering: ${perl{sender_domain_can_dkim_sign}}}}{bool_lax{${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ?considering: sender_domain_can_dkim_sign}}}}{bool_lax{${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ???expanding: sender_domain_can_dkim_sign
16:23:34 1783 ??????result: sender_domain_can_dkim_sign
16:23:34 1783 ?considering: $originator_uid
16:23:34 1783 ???expanding: $originator_uid
16:23:34 1783 ??????result: 47
16:23:34 1783 ?considering: $originator_gid
16:23:34 1783 ???expanding: $originator_gid
16:23:34 1783 ??????result: 12
16:23:34 1783 ?considering: $acl_c_vhost_owner
16:23:34 1783 ???expanding: $acl_c_vhost_owner
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $message_exim_id
16:23:34 1783 ???expanding: $message_exim_id
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $authenticated_id
16:23:34 1783 ???expanding: $authenticated_id
16:23:34 1783 ??????result: mailer
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: $sender_address
16:23:34 1783 ???expanding: $sender_address
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $sender_address_domain
16:23:34 1783 ???expanding: $sender_address_domain
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${domain:$h_from:}
16:23:34 1783 ??considering: $h_from:}
16:23:34 1783 ????expanding: $h_from:
16:23:34 1783 ???????result:
16:23:34 1783 ???expanding: ${domain:$h_from:}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${local_part:$h_from:}
16:23:34 1783 ??considering: $h_from:}
16:23:34 1783 ????expanding: $h_from:
16:23:34 1783 ???????result:
16:23:34 1783 ???expanding: ${local_part:$h_from:}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $sender_address_domain
16:23:34 1783 ???expanding: $sender_address_domain
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $sender_address_local_part
16:23:34 1783 ???expanding: $sender_address_local_part
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${lookup{\N\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??considering: \N\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ????expanding: \N\N
16:23:34 1783 ???????result:
16:23:34 1783 ??considering: /etc/userdomains}{$value}}
16:23:34 1783 ????expanding: /etc/userdomains
16:23:34 1783 ???????result: /etc/userdomains
16:23:34 1783 search_open: lsearch "/etc/userdomains"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="/etc/userdomains"
16:23:34 1783 key="" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 6/etc/senderverifybypasshosts
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/userdomains"
16:23:34 1783 type=lsearch key="" opts=NULL
16:23:34 1783 ?????scanning: $value}}
16:23:34 1783 ????expanding: $value
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup{\N\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${lookup{\N\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??considering: \N\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ????expanding: \N\N
16:23:34 1783 ???????result:
16:23:34 1783 ??considering: /etc/userdomains}{$value}}
16:23:34 1783 ????expanding: /etc/userdomains
16:23:34 1783 ???????result: /etc/userdomains
16:23:34 1783 search_open: lsearch "/etc/userdomains"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="/etc/userdomains"
16:23:34 1783 key="" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 6/etc/senderverifybypasshosts
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/userdomains"
16:23:34 1783 type=lsearch key="" opts=NULL
16:23:34 1783 ?????scanning: $value}}
16:23:34 1783 ????expanding: $value
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup{\N\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ???expanding: ${perl{sender_domain_can_dkim_sign}}
16:23:34 1783 ??????result: 0
16:23:34 1783 considering bool_lax: 0
16:23:34 1783 bool_lax: condition evaluated to false
16:23:34 1783 ????scanning: ${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ????scanning: $h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ???expanding: $h_precedence:
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ????scanning: auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ???expanding: auto
16:23:34 1783 ??????result: auto
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ????scanning: $h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ???expanding: $h_x-precedence:
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ????scanning: auto}}}{1}{0}}}}}}
16:23:34 1783 ???expanding: auto
16:23:34 1783 ??????result: auto
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???condition: or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}
16:23:34 1783 ??????result: false
16:23:34 1783 ????scanning: 1}{0}}}}}}
16:23:34 1783 ???expanding: 1
16:23:34 1783 ??????result: 1
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ????scanning: 0}}}}}}
16:23:34 1783 ???expanding: 0
16:23:34 1783 ??????result: 0
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???expanding: ${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}
16:23:34 1783 ??????result: 0
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 considering bool_lax: 0
16:23:34 1783 bool_lax: condition evaluated to false
16:23:34 1783 ???condition: and{{bool_lax{${perl{sender_domain_can_dkim_sign}}}}{bool_lax{${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}
16:23:34 1783 ??????result: false
16:23:34 1783 ???expanding: ${if and{{bool_lax{${perl{sender_domain_can_dkim_sign}}}}{bool_lax{${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ??????result:
16:23:34 1783 autoreply_dkim_lookuphost router skipped: condition failure
16:23:34 1783 --------> dkim_lookuphost router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 checking domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 felipe.dev.cpanel.net in "! +local_domains"? yes (end of list)
16:23:34 1783 checking "condition" "${perl{sender_domain_can_dkim_sign}}"...
16:23:34 1783 ?considering: ${perl{sender_domain_can_dkim_sign}}
16:23:34 1783 ?considering: sender_domain_can_dkim_sign}}
16:23:34 1783 ???expanding: sender_domain_can_dkim_sign
16:23:34 1783 ??????result: sender_domain_can_dkim_sign
16:23:34 1783 ?considering: $originator_uid
16:23:34 1783 ???expanding: $originator_uid
16:23:34 1783 ??????result: 47
16:23:34 1783 ?considering: $originator_gid
16:23:34 1783 ???expanding: $originator_gid
16:23:34 1783 ??????result: 12
16:23:34 1783 ?considering: $acl_c_vhost_owner
16:23:34 1783 ???expanding: $acl_c_vhost_owner
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $message_exim_id
16:23:34 1783 ???expanding: $message_exim_id
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $authenticated_id
16:23:34 1783 ???expanding: $authenticated_id
16:23:34 1783 ??????result: mailer
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: $sender_address
16:23:34 1783 ???expanding: $sender_address
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $sender_address_domain
16:23:34 1783 ???expanding: $sender_address_domain
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${domain:$h_from:}
16:23:34 1783 ?considering: $h_from:}
16:23:34 1783 ???expanding: $h_from:
16:23:34 1783 ??????result:
16:23:34 1783 ???expanding: ${domain:$h_from:}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${local_part:$h_from:}
16:23:34 1783 ?considering: $h_from:}
16:23:34 1783 ???expanding: $h_from:
16:23:34 1783 ??????result:
16:23:34 1783 ???expanding: ${local_part:$h_from:}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $sender_address_domain
16:23:34 1783 ???expanding: $sender_address_domain
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $sender_address_local_part
16:23:34 1783 ???expanding: $sender_address_local_part
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${lookup{\N\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ?considering: \N\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ???expanding: \N\N
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: /etc/userdomains}{$value}}
16:23:34 1783 ???expanding: /etc/userdomains
16:23:34 1783 ??????result: /etc/userdomains
16:23:34 1783 search_open: lsearch "/etc/userdomains"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="/etc/userdomains"
16:23:34 1783 key="" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 6/etc/senderverifybypasshosts
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/userdomains"
16:23:34 1783 type=lsearch key="" opts=NULL
16:23:34 1783 ????scanning: $value}}
16:23:34 1783 ???expanding: $value
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup{\N\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${lookup{\N\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ?considering: \N\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ???expanding: \N\N
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: /etc/userdomains}{$value}}
16:23:34 1783 ???expanding: /etc/userdomains
16:23:34 1783 ??????result: /etc/userdomains
16:23:34 1783 search_open: lsearch "/etc/userdomains"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="/etc/userdomains"
16:23:34 1783 key="" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 6/etc/senderverifybypasshosts
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/userdomains"
16:23:34 1783 type=lsearch key="" opts=NULL
16:23:34 1783 ????scanning: $value}}
16:23:34 1783 ???expanding: $value
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup{\N\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ???expanding: ${perl{sender_domain_can_dkim_sign}}
16:23:34 1783 ??????result: 0
16:23:34 1783 dkim_lookuphost router skipped: condition failure
16:23:34 1783 --------> autoreply_lookuphost router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 checking domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 felipe.dev.cpanel.net in "! +local_domains"? yes (end of list)
16:23:34 1783 checking "condition" "${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}"...
16:23:34 1783 ?considering: ${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}
16:23:34 1783 ?considering: $h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}
16:23:34 1783 ???expanding: $h_precedence:
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}
16:23:34 1783 ???expanding: auto
16:23:34 1783 ??????result: auto
16:23:34 1783 ?considering: $h_x-precedence:}{auto}}}{1}{0}}
16:23:34 1783 ???expanding: $h_x-precedence:
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: auto}}}{1}{0}}
16:23:34 1783 ???expanding: auto
16:23:34 1783 ??????result: auto
16:23:34 1783 ???condition: or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}
16:23:34 1783 ??????result: false
16:23:34 1783 ????scanning: 1}{0}}
16:23:34 1783 ???expanding: 1
16:23:34 1783 ??????result: 1
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ?considering: 0}}
16:23:34 1783 ???expanding: 0
16:23:34 1783 ??????result: 0
16:23:34 1783 ???expanding: ${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}
16:23:34 1783 ??????result: 0
16:23:34 1783 autoreply_lookuphost router skipped: condition failure
16:23:34 1783 --------> lookuphost router <--------
16:23:34 1783 local_part=canada domain=felipe.dev.cpanel.net
16:23:34 1783 checking domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 felipe.dev.cpanel.net in "! +local_domains"? yes (end of list)
16:23:34 1783 calling lookuphost router
16:23:34 1783 lookuphost router called for canada@felipe.dev.cpanel.net
16:23:34 1783 domain = felipe.dev.cpanel.net
16:23:34 1783 felipe.dev.cpanel.net in "*"? yes (matched "*")
16:23:34 1783 DNS lookup of felipe.dev.cpanel.net (MX) gave NO_DATA
16:23:34 1783 returning DNS_NODATA
16:23:34 1783 faking res_search(MX) response length as 65535
16:23:34 1783 writing neg-cache entry for felipe.dev.cpanel.net-MX-880043, ttl 300
16:23:34 1783 DNS lookup of felipe.dev.cpanel.net (A) succeeded
16:23:34 1783 set_address_from_dns: A query succeeded; skipping AAAA query (cPanel patched behavior)
16:23:34 1783 10.1.34.231 in "<; @[]; 127.0.0.0/8 ; 0.0.0.0 ; ::1 ; 0000:0000:0000:0000:0000:ffff:7f00:0000/8"? no (end of list)
16:23:34 1783 10.1.34.231 in "+loopback : 64.94.110.0/24"? no (end of list)
16:23:34 1783 fully qualified name = felipe.dev.cpanel.net
16:23:34 1783 felipe.dev.cpanel.net 10.1.34.231 mx=-1 sort=-855
16:23:34 1783 ?considering: ${perl{mailtrapheaders}}
16:23:34 1783 ?considering: mailtrapheaders}}
16:23:34 1783 ???expanding: mailtrapheaders
16:23:34 1783 ??????result: mailtrapheaders
16:23:34 1783 ?considering: $original_domain
16:23:34 1783 ???expanding: $original_domain
16:23:34 1783 ??????result: felipe.dev.cpanel.net
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: $sender_address_domain
16:23:34 1783 ???expanding: $sender_address_domain
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $originator_uid
16:23:34 1783 ???expanding: $originator_uid
16:23:34 1783 ??????result: 47
16:23:34 1783 ?considering: $originator_gid
16:23:34 1783 ???expanding: $originator_gid
16:23:34 1783 ??????result: 12
16:23:34 1783 ?considering: $caller_uid
16:23:34 1783 ???expanding: $caller_uid
16:23:34 1783 ??????result: 0
16:23:34 1783 ?considering: $caller_gid
16:23:34 1783 ???expanding: $caller_gid
16:23:34 1783 ??????result: 0
16:23:34 1783 ?considering: ${extract{5}{:}{${lookup passwd{}{$value}}}}
16:23:34 1783 ?considering: 5}{:}{${lookup passwd{}{$value}}}}
16:23:34 1783 ???expanding: 5
16:23:34 1783 ??????result: 5
16:23:34 1783 ?considering: :}{${lookup passwd{}{$value}}}}
16:23:34 1783 ???expanding: :
16:23:34 1783 ??????result: :
16:23:34 1783 ?considering: ${lookup passwd{}{$value}}}}
16:23:34 1783 ??considering: }{$value}}}}
16:23:34 1783 ????expanding:
16:23:34 1783 ???????result:
16:23:34 1783 search_open: passwd "NULL"
16:23:34 1783 search_find: file="NULL"
16:23:34 1783 key="" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 6/etc/senderverifybypasshosts
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="NULL"
16:23:34 1783 type=passwd key="" opts=NULL
16:23:34 1783 ?????scanning: $value}}}}
16:23:34 1783 ????expanding: $value
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup passwd{}{$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ???expanding: ${extract{5}{:}{${lookup passwd{}{$value}}}}
16:23:34 1783 ??????result:
16:23:34 1783 ???expanding: ${perl{mailtrapheaders}}
16:23:34 1783 ??????result: X-AntiAbuse: This header was added to track abuse, please include it with any abuse report
16:23:34 1783 X-AntiAbuse: Primary Hostname - extra.felipe.dev
16:23:34 1783 X-AntiAbuse: Original Domain - felipe.dev.cpanel.net
16:23:34 1783 X-AntiAbuse: Originator/Caller UID/GID - [47 12] / [0 0]
16:23:34 1783 X-AntiAbuse: Sender Address Domain -
16:23:34 1783 X-Get-Message-Sender-Via: extra.felipe.dev: authenticated_id: mailer/only user confirmed/virtual account not confirmed
16:23:34 1783 X-Authenticated-Sender: extra.felipe.dev: mailer
16:23:34 1783 X-Source:
16:23:34 1783 X-Source-Args:
16:23:34 1783 X-Source-Dir:
16:23:34 1783 set transport remote_smtp
16:23:34 1783 queued for remote_smtp transport: local_part = canada
16:23:34 1783 domain = felipe.dev.cpanel.net
16:23:34 1783 errors_to=NULL
16:23:34 1783 domain_data=NULL localpart_data=NULL
16:23:34 1783 routed by lookuphost router
16:23:34 1783 envelope to: canada@felipe.dev.cpanel.net
16:23:34 1783 transport: remote_smtp
16:23:34 1783 host felipe.dev.cpanel.net [10.1.34.231]
16:23:34 1783 ----------- end verify ------------
16:23:34 1783 sender canada@felipe.dev.cpanel.net verified ok
16:23:34 1783 deny: condition test failed in ACL "acl_smtp_rcpt"
16:23:34 1783 processing "accept" (/etc/exim.conf 912)
16:23:34 1783 check authenticated = *
16:23:34 1783 dovecot_plain in "*"? yes (matched "*")
16:23:34 1783 check verify = recipient
16:23:34 1783 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16:23:34 1783 Verifying felipe@cpanel.com
16:23:34 1783 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16:23:34 1783 Considering felipe@cpanel.com
16:23:34 1783 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16:23:34 1783 routing felipe@cpanel.com
16:23:34 1783 --------> blackhole_dovenull router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 checking local_parts
16:23:34 1783 felipe in "@dovenull"? no (end of list)
16:23:34 1783 blackhole_dovenull router skipped: local_parts mismatch
16:23:34 1783 --------> deliver_local_outside_jail router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 checking domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 cpanel.com in "+local_domains"? no (end of list)
16:23:34 1783 deliver_local_outside_jail router skipped: domains mismatch
16:23:34 1783 --------> suspendedcheck router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 checking domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 cpanel.com in "+local_domains"? no (end of list)
16:23:34 1783 suspendedcheck router skipped: domains mismatch
16:23:34 1783 --------> mailman_virtual_router router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 checking domains
16:23:34 1783 ?considering: !$primary_hostname : +local_domains
16:23:34 1783 ???expanding: !$primary_hostname : +local_domains
16:23:34 1783 ??????result: !extra.felipe.dev : +local_domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 cpanel.com in "!extra.felipe.dev : +local_domains"? no (end of list)
16:23:34 1783 mailman_virtual_router router skipped: domains mismatch
16:23:34 1783 --------> mailman_virtual_router_suffixed router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 mailman_virtual_router_suffixed router skipped: suffix mismatch
16:23:34 1783 --------> mailman_virtual_router_nodns router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 checking domains
16:23:34 1783 ?considering: $primary_hostname
16:23:34 1783 ???expanding: $primary_hostname
16:23:34 1783 ??????result: extra.felipe.dev
16:23:34 1783 cpanel.com in "extra.felipe.dev"? no (end of list)
16:23:34 1783 mailman_virtual_router_nodns router skipped: domains mismatch
16:23:34 1783 --------> mailman_virtual_router_nodns_suffixed router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 mailman_virtual_router_nodns_suffixed router skipped: suffix mismatch
16:23:34 1783 --------> democheck router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 checking require_files
16:23:34 1783 file check: +/etc/demouids
16:23:34 1783 expanded file: /etc/demouids
16:23:34 1783 stat() yielded 0
16:23:34 1783 checking "condition" "${if and{{bool_lax{${if and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bo"...
16:23:34 1783 ?considering: ${if and{{bool_lax{${if and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ?considering: ${if and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ?considering: ${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ??considering: $originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: $originator_uid
16:23:34 1783 ???????result: 47
16:23:34 1783 ??considering: 100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: 100
16:23:34 1783 ???????result: 100
16:23:34 1783 ???condition: >= {$originator_uid}{100}
16:23:34 1783 ??????result: false
16:23:34 1783 ?????scanning: 1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: 1
16:23:34 1783 ???????result: 1
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ??considering: 0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: 0
16:23:34 1783 ???????result: 0
16:23:34 1783 ???expanding: ${if >= {$originator_uid}{100}{1}{0}}
16:23:34 1783 ??????result: 0
16:23:34 1783 considering bool_lax: 0
16:23:34 1783 bool_lax: condition evaluated to false
16:23:34 1783 ????scanning: ${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ?????scanning: size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: size
16:23:34 1783 ???????result: size
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ?????scanning: ${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ? ????scanning: /etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ? ???expanding: /etc/demouids
16:23:34 1783 ? ??????result: /etc/demouids
16:23:34 1783 ? ????skipping: result is not used
16:23:34 1783 ????expanding: ${stat:/etc/demouids}
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ???expanding: ${extract{size}{${stat:/etc/demouids}}}
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 considering bool_lax: <empty>
16:23:34 1783 bool_lax: condition evaluated to false
16:23:34 1783 ???condition: and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}
16:23:34 1783 ??????result: false
16:23:34 1783 ???expanding: ${if and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}
16:23:34 1783 ??????result:
16:23:34 1783 considering bool_lax: <empty>
16:23:34 1783 bool_lax: condition evaluated to false
16:23:34 1783 ????scanning: ${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????scanning: ${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ?????scanning: $originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: $originator_uid
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ?????scanning: /etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: /etc/demouids
16:23:34 1783 ???????result: /etc/demouids
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ?????scanning: $value}}}{}{false}{true}}}}}}
16:23:34 1783 ????expanding: $value
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ????scanning: }{false}{true}}}}}}
16:23:34 1783 ???expanding:
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???condition: eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}
16:23:34 1783 ??????result: false
16:23:34 1783 ????scanning: false}{true}}}}}}
16:23:34 1783 ???expanding: false
16:23:34 1783 ??????result: false
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ????scanning: true}}}}}}
16:23:34 1783 ???expanding: true
16:23:34 1783 ??????result: true
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???expanding: ${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}
16:23:34 1783 ??????result: true
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 considering bool_lax: true
16:23:34 1783 bool_lax: condition evaluated to true
16:23:34 1783 ???condition: and{{bool_lax{${if and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}
16:23:34 1783 ??????result: false
16:23:34 1783 ???expanding: ${if and{{bool_lax{${if and{{bool_lax{${if >= {$originator_uid}{100}{1}{0}}}}{bool_lax{${extract{size}{${stat:/etc/demouids}}}}}}}}}{bool_lax{${if eq {${lookup {$originator_uid} lsearch {/etc/demouids} {$value}}}{}{false}{true}}}}}}
16:23:34 1783 ??????result:
16:23:34 1783 democheck router skipped: condition failure
16:23:34 1783 --------> blackhole_cpanel_at router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 checking domains
16:23:34 1783 ?considering: !$primary_hostname
16:23:34 1783 ???expanding: !$primary_hostname
16:23:34 1783 ??????result: !extra.felipe.dev
16:23:34 1783 cpanel.com in "!extra.felipe.dev"? yes (end of list)
16:23:34 1783 checking local_parts
16:23:34 1783 felipe in "cpanel"? no (end of list)
16:23:34 1783 blackhole_cpanel_at router skipped: local_parts mismatch
16:23:34 1783 --------> boxtrapper_autowhitelist router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 boxtrapper_autowhitelist router skipped: verify 2 0 0
16:23:34 1783 --------> check_mail_permissions router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 check_mail_permissions router skipped: verify 2 0 0
16:23:34 1783 --------> enforce_mail_permissions router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 enforce_mail_permissions router skipped: verify 2 0 0
16:23:34 1783 --------> increment_max_emails_per_hour_if_needed router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 increment_max_emails_per_hour_if_needed router skipped: verify 2 0 0
16:23:34 1783 --------> autoreply_dkim_lookuphost router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 checking domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 cpanel.com in "! +local_domains"? yes (end of list)
16:23:34 1783 checking "condition" "${if and{{bool_lax{${perl{sender_domain_can_dkim_sign}}}}{bool_lax{${if or {{mat"...
16:23:34 1783 ?considering: ${if and{{bool_lax{${perl{sender_domain_can_dkim_sign}}}}{bool_lax{${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ?considering: ${perl{sender_domain_can_dkim_sign}}}}{bool_lax{${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ?considering: sender_domain_can_dkim_sign}}}}{bool_lax{${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ???expanding: sender_domain_can_dkim_sign
16:23:34 1783 ??????result: sender_domain_can_dkim_sign
16:23:34 1783 ?considering: $originator_uid
16:23:34 1783 ???expanding: $originator_uid
16:23:34 1783 ??????result: 47
16:23:34 1783 ?considering: $originator_gid
16:23:34 1783 ???expanding: $originator_gid
16:23:34 1783 ??????result: 12
16:23:34 1783 ?considering: $acl_c_vhost_owner
16:23:34 1783 ???expanding: $acl_c_vhost_owner
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $message_exim_id
16:23:34 1783 ???expanding: $message_exim_id
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $authenticated_id
16:23:34 1783 ???expanding: $authenticated_id
16:23:34 1783 ??????result: mailer
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: $sender_address
16:23:34 1783 ???expanding: $sender_address
16:23:34 1783 ??????result: canada@felipe.dev.cpanel.net
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: $sender_address_domain
16:23:34 1783 ???expanding: $sender_address_domain
16:23:34 1783 ??????result: felipe.dev.cpanel.net
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: ${domain:$h_from:}
16:23:34 1783 ??considering: $h_from:}
16:23:34 1783 ????expanding: $h_from:
16:23:34 1783 ???????result:
16:23:34 1783 ???expanding: ${domain:$h_from:}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${local_part:$h_from:}
16:23:34 1783 ??considering: $h_from:}
16:23:34 1783 ????expanding: $h_from:
16:23:34 1783 ???????result:
16:23:34 1783 ???expanding: ${local_part:$h_from:}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $sender_address_domain
16:23:34 1783 ???expanding: $sender_address_domain
16:23:34 1783 ??????result: felipe.dev.cpanel.net
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: $sender_address_local_part
16:23:34 1783 ???expanding: $sender_address_local_part
16:23:34 1783 ??????result: canada
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: ${lookup{\Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??considering: \Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ????expanding: \Nfelipe.dev.cpanel.net\N
16:23:34 1783 ???????result: felipe.dev.cpanel.net
16:23:34 1783 ??considering: /etc/userdomains}{$value}}
16:23:34 1783 ????expanding: /etc/userdomains
16:23:34 1783 ???????result: /etc/userdomains
16:23:34 1783 search_open: lsearch "/etc/userdomains"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="/etc/userdomains"
16:23:34 1783 key="felipe.dev.cpanel.net" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 6/etc/senderverifybypasshosts
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/userdomains"
16:23:34 1783 type=lsearch key="felipe.dev.cpanel.net" opts=NULL
16:23:34 1783 cached data used for lookup of felipe.dev.cpanel.net
16:23:34 1783 in /etc/userdomains
16:23:34 1783 lookup failed
16:23:34 1783 ?????scanning: $value}}
16:23:34 1783 ????expanding: $value
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup{\Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${lookup{\Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??considering: \Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ????expanding: \Nfelipe.dev.cpanel.net\N
16:23:34 1783 ???????result: felipe.dev.cpanel.net
16:23:34 1783 ??considering: /etc/userdomains}{$value}}
16:23:34 1783 ????expanding: /etc/userdomains
16:23:34 1783 ???????result: /etc/userdomains
16:23:34 1783 search_open: lsearch "/etc/userdomains"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="/etc/userdomains"
16:23:34 1783 key="felipe.dev.cpanel.net" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 6/etc/senderverifybypasshosts
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/userdomains"
16:23:34 1783 type=lsearch key="felipe.dev.cpanel.net" opts=NULL
16:23:34 1783 cached data used for lookup of felipe.dev.cpanel.net
16:23:34 1783 in /etc/userdomains
16:23:34 1783 lookup failed
16:23:34 1783 ?????scanning: $value}}
16:23:34 1783 ????expanding: $value
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup{\Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ???expanding: ${perl{sender_domain_can_dkim_sign}}
16:23:34 1783 ??????result: 0
16:23:34 1783 considering bool_lax: 0
16:23:34 1783 bool_lax: condition evaluated to false
16:23:34 1783 ????scanning: ${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ????scanning: $h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ???expanding: $h_precedence:
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ????scanning: auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ???expanding: auto
16:23:34 1783 ??????result: auto
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ????scanning: $h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ???expanding: $h_x-precedence:
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ????scanning: auto}}}{1}{0}}}}}}
16:23:34 1783 ???expanding: auto
16:23:34 1783 ??????result: auto
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???condition: or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}
16:23:34 1783 ??????result: false
16:23:34 1783 ????scanning: 1}{0}}}}}}
16:23:34 1783 ???expanding: 1
16:23:34 1783 ??????result: 1
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ????scanning: 0}}}}}}
16:23:34 1783 ???expanding: 0
16:23:34 1783 ??????result: 0
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???expanding: ${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}
16:23:34 1783 ??????result: 0
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 considering bool_lax: 0
16:23:34 1783 bool_lax: condition evaluated to false
16:23:34 1783 ???condition: and{{bool_lax{${perl{sender_domain_can_dkim_sign}}}}{bool_lax{${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}
16:23:34 1783 ??????result: false
16:23:34 1783 ???expanding: ${if and{{bool_lax{${perl{sender_domain_can_dkim_sign}}}}{bool_lax{${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}}}}}
16:23:34 1783 ??????result:
16:23:34 1783 autoreply_dkim_lookuphost router skipped: condition failure
16:23:34 1783 --------> dkim_lookuphost router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 checking domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 cpanel.com in "! +local_domains"? yes (end of list)
16:23:34 1783 checking "condition" "${perl{sender_domain_can_dkim_sign}}"...
16:23:34 1783 ?considering: ${perl{sender_domain_can_dkim_sign}}
16:23:34 1783 ?considering: sender_domain_can_dkim_sign}}
16:23:34 1783 ???expanding: sender_domain_can_dkim_sign
16:23:34 1783 ??????result: sender_domain_can_dkim_sign
16:23:34 1783 ?considering: $originator_uid
16:23:34 1783 ???expanding: $originator_uid
16:23:34 1783 ??????result: 47
16:23:34 1783 ?considering: $originator_gid
16:23:34 1783 ???expanding: $originator_gid
16:23:34 1783 ??????result: 12
16:23:34 1783 ?considering: $acl_c_vhost_owner
16:23:34 1783 ???expanding: $acl_c_vhost_owner
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $message_exim_id
16:23:34 1783 ???expanding: $message_exim_id
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $authenticated_id
16:23:34 1783 ???expanding: $authenticated_id
16:23:34 1783 ??????result: mailer
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: $sender_address
16:23:34 1783 ???expanding: $sender_address
16:23:34 1783 ??????result: canada@felipe.dev.cpanel.net
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: $sender_address_domain
16:23:34 1783 ???expanding: $sender_address_domain
16:23:34 1783 ??????result: felipe.dev.cpanel.net
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: ${domain:$h_from:}
16:23:34 1783 ?considering: $h_from:}
16:23:34 1783 ???expanding: $h_from:
16:23:34 1783 ??????result:
16:23:34 1783 ???expanding: ${domain:$h_from:}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${local_part:$h_from:}
16:23:34 1783 ?considering: $h_from:}
16:23:34 1783 ???expanding: $h_from:
16:23:34 1783 ??????result:
16:23:34 1783 ???expanding: ${local_part:$h_from:}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: $sender_address_domain
16:23:34 1783 ???expanding: $sender_address_domain
16:23:34 1783 ??????result: felipe.dev.cpanel.net
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: $sender_address_local_part
16:23:34 1783 ???expanding: $sender_address_local_part
16:23:34 1783 ??????result: canada
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: ${lookup{\Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ?considering: \Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ???expanding: \Nfelipe.dev.cpanel.net\N
16:23:34 1783 ??????result: felipe.dev.cpanel.net
16:23:34 1783 ?considering: /etc/userdomains}{$value}}
16:23:34 1783 ???expanding: /etc/userdomains
16:23:34 1783 ??????result: /etc/userdomains
16:23:34 1783 search_open: lsearch "/etc/userdomains"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="/etc/userdomains"
16:23:34 1783 key="felipe.dev.cpanel.net" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 6/etc/senderverifybypasshosts
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/userdomains"
16:23:34 1783 type=lsearch key="felipe.dev.cpanel.net" opts=NULL
16:23:34 1783 cached data used for lookup of felipe.dev.cpanel.net
16:23:34 1783 in /etc/userdomains
16:23:34 1783 lookup failed
16:23:34 1783 ????scanning: $value}}
16:23:34 1783 ???expanding: $value
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup{\Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: ${lookup{\Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ?considering: \Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ???expanding: \Nfelipe.dev.cpanel.net\N
16:23:34 1783 ??????result: felipe.dev.cpanel.net
16:23:34 1783 ?considering: /etc/userdomains}{$value}}
16:23:34 1783 ???expanding: /etc/userdomains
16:23:34 1783 ??????result: /etc/userdomains
16:23:34 1783 search_open: lsearch "/etc/userdomains"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="/etc/userdomains"
16:23:34 1783 key="felipe.dev.cpanel.net" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 6/etc/senderverifybypasshosts
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="/etc/userdomains"
16:23:34 1783 type=lsearch key="felipe.dev.cpanel.net" opts=NULL
16:23:34 1783 cached data used for lookup of felipe.dev.cpanel.net
16:23:34 1783 in /etc/userdomains
16:23:34 1783 lookup failed
16:23:34 1783 ????scanning: $value}}
16:23:34 1783 ???expanding: $value
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup{\Nfelipe.dev.cpanel.net\N}lsearch{/etc/userdomains}{$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ???expanding: ${perl{sender_domain_can_dkim_sign}}
16:23:34 1783 ??????result: 0
16:23:34 1783 dkim_lookuphost router skipped: condition failure
16:23:34 1783 --------> autoreply_lookuphost router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 checking domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 cpanel.com in "! +local_domains"? yes (end of list)
16:23:34 1783 checking "condition" "${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}"...
16:23:34 1783 ?considering: ${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}
16:23:34 1783 ?considering: $h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}
16:23:34 1783 ???expanding: $h_precedence:
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}
16:23:34 1783 ???expanding: auto
16:23:34 1783 ??????result: auto
16:23:34 1783 ?considering: $h_x-precedence:}{auto}}}{1}{0}}
16:23:34 1783 ???expanding: $h_x-precedence:
16:23:34 1783 ??????result:
16:23:34 1783 ?considering: auto}}}{1}{0}}
16:23:34 1783 ???expanding: auto
16:23:34 1783 ??????result: auto
16:23:34 1783 ???condition: or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}
16:23:34 1783 ??????result: false
16:23:34 1783 ????scanning: 1}{0}}
16:23:34 1783 ???expanding: 1
16:23:34 1783 ??????result: 1
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ?considering: 0}}
16:23:34 1783 ???expanding: 0
16:23:34 1783 ??????result: 0
16:23:34 1783 ???expanding: ${if or {{match{$h_precedence:}{auto}}{match{$h_x-precedence:}{auto}}}{1}{0}}
16:23:34 1783 ??????result: 0
16:23:34 1783 autoreply_lookuphost router skipped: condition failure
16:23:34 1783 --------> lookuphost router <--------
16:23:34 1783 local_part=felipe domain=cpanel.com
16:23:34 1783 checking domains
16:23:34 1783 cached no match for +local_domains
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 cpanel.com in "! +local_domains"? yes (end of list)
16:23:34 1783 calling lookuphost router
16:23:34 1783 lookuphost router called for felipe@cpanel.com
16:23:34 1783 domain = cpanel.com
16:23:34 1783 cpanel.com in "*"? yes (matched "*")
16:23:34 1783 DNS lookup of cpanel.com (MX) succeeded
16:23:34 1783 DNS lookup of mx2.cpanel.net (A) succeeded
16:23:34 1783 set_address_from_dns: A query succeeded; skipping AAAA query (cPanel patched behavior)
16:23:34 1783 208.74.123.210 in "<; @[]; 127.0.0.0/8 ; 0.0.0.0 ; ::1 ; 0000:0000:0000:0000:0000:ffff:7f00:0000/8"? no (end of list)
16:23:34 1783 208.74.123.210 in "+loopback : 64.94.110.0/24"? no (end of list)
16:23:34 1783 DNS lookup of mx1.cpanel.net (A) succeeded
16:23:34 1783 set_address_from_dns: A query succeeded; skipping AAAA query (cPanel patched behavior)
16:23:34 1783 208.74.121.68 in "<; @[]; 127.0.0.0/8 ; 0.0.0.0 ; ::1 ; 0000:0000:0000:0000:0000:ffff:7f00:0000/8"? no (end of list)
16:23:34 1783 208.74.121.68 in "+loopback : 64.94.110.0/24"? no (end of list)
16:23:34 1783 fully qualified name = cpanel.com
16:23:34 1783 host_find_bydns yield = HOST_FOUND (3); returned hosts:
16:23:34 1783 mx2.cpanel.net 208.74.123.210 MX=0
16:23:34 1783 mx1.cpanel.net 208.74.121.68 MX=10
16:23:34 1783 ?considering: ${perl{mailtrapheaders}}
16:23:34 1783 ?considering: mailtrapheaders}}
16:23:34 1783 ???expanding: mailtrapheaders
16:23:34 1783 ??????result: mailtrapheaders
16:23:34 1783 ?considering: $original_domain
16:23:34 1783 ???expanding: $original_domain
16:23:34 1783 ??????result: cpanel.com
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: $sender_address_domain
16:23:34 1783 ???expanding: $sender_address_domain
16:23:34 1783 ??????result: felipe.dev.cpanel.net
16:23:34 1783 ???(tainted)
16:23:34 1783 ?considering: $originator_uid
16:23:34 1783 ???expanding: $originator_uid
16:23:34 1783 ??????result: 47
16:23:34 1783 ?considering: $originator_gid
16:23:34 1783 ???expanding: $originator_gid
16:23:34 1783 ??????result: 12
16:23:34 1783 ?considering: $caller_uid
16:23:34 1783 ???expanding: $caller_uid
16:23:34 1783 ??????result: 0
16:23:34 1783 ?considering: $caller_gid
16:23:34 1783 ???expanding: $caller_gid
16:23:34 1783 ??????result: 0
16:23:34 1783 ?considering: ${extract{5}{:}{${lookup passwd{}{$value}}}}
16:23:34 1783 ?considering: 5}{:}{${lookup passwd{}{$value}}}}
16:23:34 1783 ???expanding: 5
16:23:34 1783 ??????result: 5
16:23:34 1783 ?considering: :}{${lookup passwd{}{$value}}}}
16:23:34 1783 ???expanding: :
16:23:34 1783 ??????result: :
16:23:34 1783 ?considering: ${lookup passwd{}{$value}}}}
16:23:34 1783 ??considering: }{$value}}}}
16:23:34 1783 ????expanding:
16:23:34 1783 ???????result:
16:23:34 1783 search_open: passwd "NULL"
16:23:34 1783 cached open
16:23:34 1783 search_find: file="NULL"
16:23:34 1783 key="" partial=-1 affix=NULL starflags=0 opts=NULL
16:23:34 1783 LRU list:
16:23:34 1783 7/etc/userdomains
16:23:34 1783 7/etc/localdomains
16:23:34 1783 6/etc/senderverifybypasshosts
16:23:34 1783 7/etc/outgoing_mail_suspended_users
16:23:34 1783 7/etc/domainusers
16:23:34 1783 =/etc/blocked_incoming_email_domains
16:23:34 1783 7/etc/secondarymx
16:23:34 1783 7/etc/demousers
16:23:34 1783 6/etc/spammeripblocks
16:23:34 1783 6/etc/trustedmailhosts
16:23:34 1783 6/etc/recent_authed_mail_ips
16:23:34 1783 6/etc/neighbor_netblocks
16:23:34 1783 6/etc/blocked_incoming_email_country_ips
16:23:34 1783 End
16:23:34 1783 internal_search_find: file="NULL"
16:23:34 1783 type=passwd key="" opts=NULL
16:23:34 1783 ?????scanning: $value}}}}
16:23:34 1783 ????expanding: $value
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ???expanding: ${lookup passwd{}{$value}}
16:23:34 1783 ??????result:
16:23:34 1783 ???expanding: ${extract{5}{:}{${lookup passwd{}{$value}}}}
16:23:34 1783 ??????result:
16:23:34 1783 ???expanding: ${perl{mailtrapheaders}}
16:23:34 1783 ??????result: X-AntiAbuse: This header was added to track abuse, please include it with any abuse report
16:23:34 1783 X-AntiAbuse: Primary Hostname - extra.felipe.dev
16:23:34 1783 X-AntiAbuse: Original Domain - cpanel.com
16:23:34 1783 X-AntiAbuse: Originator/Caller UID/GID - [47 12] / [0 0]
16:23:34 1783 X-AntiAbuse: Sender Address Domain - felipe.dev.cpanel.net
16:23:34 1783 X-Get-Message-Sender-Via: extra.felipe.dev: authenticated_id: mailer/only user confirmed/virtual account not confirmed
16:23:34 1783 X-Authenticated-Sender: extra.felipe.dev: mailer
16:23:34 1783 X-Source:
16:23:34 1783 X-Source-Args:
16:23:34 1783 X-Source-Dir:
16:23:34 1783 queued for remote_smtp transport: local_part = felipe
16:23:34 1783 domain = cpanel.com
16:23:34 1783 errors_to=NULL
16:23:34 1783 domain_data=NULL localpart_data=NULL
16:23:34 1783 routed by lookuphost router
16:23:34 1783 envelope to: felipe@cpanel.com
16:23:34 1783 transport: remote_smtp
16:23:34 1783 host mx2.cpanel.net [208.74.123.210] MX=0 dnssec=no
16:23:34 1783 host mx1.cpanel.net [208.74.121.68] MX=10 dnssec=no
16:23:34 1783 ----------- end verify ------------
16:23:34 1783 accept: condition test succeeded in ACL "acl_smtp_rcpt"
16:23:34 1783 end of ACL "acl_smtp_rcpt": ACCEPT
16:23:34 1783 SMTP>> 250 Accepted
16:23:34 1783 tls_write(0x14308a0, 14)
16:23:34 1783 SSL_write(0x147d9b0, 0x14308a0, 14)
16:23:34 1783 outbytes=14 error=0
16:23:34 1783 Calling SSL_read(0x147d9b0, 0x14816c0, 4096)
16:23:34 1783 SMTP<< DATA
16:23:34 1783 SMTP>> 354 Enter message, ending with "." on a line by itself
16:23:34 1783 tls_write(0x14308a0, 56)
16:23:34 1783 SSL_write(0x147d9b0, 0x14308a0, 56)
16:23:34 1783 outbytes=56 error=0
16:23:34 1783 search_tidyup called
16:23:34 1783 host in ignore_fromline_hosts? no (option unset)
16:23:34 1783 >>Headers received:
16:23:34 1783 From: canada@felipe.dev.cpanel.net
16:23:34 1783 Subject: Test sent from ./smtp_send_test.pl
16:23:34 1783
16:23:34 1783 rewrite_one_header: type=F:
16:23:34 1783 From: canada@felipe.dev.cpanel.net
16:23:34 1783 search_tidyup called
16:23:34 1783 >>Headers after rewriting and local additions:
16:23:34 1783 F From: canada@felipe.dev.cpanel.net
16:23:34 1783 Subject: Test sent from ./smtp_send_test.pl
16:23:34 1783
16:23:34 1783 Data file name: /var/spool/exim//input/m/1lRLpm-0000Sl-Fn-D
16:23:34 1783 Data file written for message 1lRLpm-0000Sl-Fn
16:23:34 1783 ?considering: ${tod_full}
16:23:34 1783 ???expanding: ${tod_full}
16:23:34 1783 ??????result: Tue, 30 Mar 2021 16:23:34 -0500
16:23:34 1783 ?considering: Received: ${if def:sender_rcvhost {from $sender_rcvhost
16:23:34 1783 }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
16:23:34 1783 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
16:23:34 1783 }}(Exim $version_number)
16:23:34 1783 ${if def:sender_address {(envelope-from <$sender_address>)
16:23:34 1783 }}id $message_exim_id${if def:received_for {
16:23:34 1783 for $received_for}}
16:23:34 1783 ???condition: def:sender_rcvhost
16:23:34 1783 ??????result: true
16:23:34 1783 ?considering: from $sender_rcvhost
16:23:34 1783 }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
16:23:34 1783 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
16:23:34 1783 }}(Exim $version_number)
16:23:34 1783 ${if def:sender_address {(envelope-from <$sender_address>)
16:23:34 1783 }}id $message_exim_id${if def:received_for {
16:23:34 1783 for $received_for}}
16:23:34 1783 ???expanding: from $sender_rcvhost
16:23:34 1783
16:23:34 1783 ??????result: from [10.1.34.231] (port=33586 helo=felipe.dev.cpanel.net)
16:23:34 1783
16:23:34 1783 ???(tainted)
16:23:34 1783 ????scanning: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
16:23:34 1783 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
16:23:34 1783 }}(Exim $version_number)
16:23:34 1783 ${if def:sender_address {(envelope-from <$sender_address>)
16:23:34 1783 }}id $message_exim_id${if def:received_for {
16:23:34 1783 for $received_for}}
16:23:34 1783 ???condition: def:sender_ident
16:23:34 1783 ??????result: false
16:23:34 1783 ????scanning: from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
16:23:34 1783 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
16:23:34 1783 }}(Exim $version_number)
16:23:34 1783 ${if def:sender_address {(envelope-from <$sender_address>)
16:23:34 1783 }}id $message_exim_id${if def:received_for {
16:23:34 1783 for $received_for}}
16:23:34 1783 ?????scanning: $sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
16:23:34 1783 ? }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
16:23:34 1783 ? }}(Exim $version_number)
16:23:34 1783 ? ${if def:sender_address {(envelope-from <$sender_address>)
16:23:34 1783 ? }}id $message_exim_id${if def:received_for {
16:23:34 1783 ? for $received_for}}
16:23:34 1783 ????expanding: $sender_ident
16:23:34 1783 ???????result:
16:23:34 1783 ?????skipping: result is not used
16:23:34 1783 ???expanding: from ${quote_local_part:$sender_ident}
16:23:34 1783 ??????result: from
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???condition: def:sender_helo_name
16:23:34 1783 ??????result: false
16:23:34 1783 ????scanning: (helo=$sender_helo_name)
16:23:34 1783 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
16:23:34 1783 }}(Exim $version_number)
16:23:34 1783 ${if def:sender_address {(envelope-from <$sender_address>)
16:23:34 1783 }}id $message_exim_id${if def:received_for {
16:23:34 1783 for $received_for}}
16:23:34 1783 ???expanding: (helo=$sender_helo_name)
16:23:34 1783
16:23:34 1783 ??????result: (helo=)
16:23:34 1783
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???expanding: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
16:23:34 1783 }}
16:23:34 1783 ??????result:
16:23:34 1783 ????skipping: result is not used
16:23:34 1783 ???condition: def:received_protocol
16:23:34 1783 ??????result: true
16:23:34 1783 ?considering: with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
16:23:34 1783 }}(Exim $version_number)
16:23:34 1783 ${if def:sender_address {(envelope-from <$sender_address>)
16:23:34 1783 }}id $message_exim_id${if def:received_for {
16:23:34 1783 for $received_for}}
16:23:34 1783 ???expanding: with $received_protocol
16:23:34 1783 ??????result: with esmtpsa
16:23:34 1783 ???condition: def:tls_in_ver
16:23:34 1783 ??????result: true
16:23:34 1783 ?considering: ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
16:23:34 1783 }}(Exim $version_number)
16:23:34 1783 ${if def:sender_address {(envelope-from <$sender_address>)
16:23:34 1783 }}id $message_exim_id${if def:received_for {
16:23:34 1783 for $received_for}}
16:23:34 1783 ???expanding: ($tls_in_ver)
16:23:34 1783 ??????result: (TLS1.2)
16:23:34 1783 ???condition: def:tls_in_cipher_std
16:23:34 1783 ??????result: true
16:23:34 1783 ?considering: tls $tls_in_cipher_std
16:23:34 1783 }}(Exim $version_number)
16:23:34 1783 ${if def:sender_address {(envelope-from <$sender_address>)
16:23:34 1783 }}id $message_exim_id${if def:received_for {
16:23:34 1783 for $received_for}}
16:23:34 1783 ???expanding: tls $tls_in_cipher_std
16:23:34 1783
16:23:34 1783 ??????result: tls TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256
16:23:34 1783
16:23:34 1783 ???condition: def:sender_address
16:23:34 1783 ??????result: true
16:23:34 1783 ?considering: (envelope-from <$sender_address>)
16:23:34 1783 }}id $message_exim_id${if def:received_for {
16:23:34 1783 for $received_for}}
16:23:34 1783 ???expanding: (envelope-from <$sender_address>)
16:23:34 1783
16:23:34 1783 ??????result: (envelope-from <canada@felipe.dev.cpanel.net>)
16:23:34 1783
16:23:34 1783 ???(tainted)
16:23:34 1783 ???condition: def:received_for
16:23:34 1783 ??????result: true
16:23:34 1783 ?considering:
16:23:34 1783 for $received_for}}
16:23:34 1783 ???expanding:
16:23:34 1783 for $received_for
16:23:34 1783 ??????result:
16:23:34 1783 for felipe@cpanel.com
16:23:34 1783 ???(tainted)
16:23:34 1783 ???expanding: Received: ${if def:sender_rcvhost {from $sender_rcvhost
16:23:34 1783 }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
16:23:34 1783 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
16:23:34 1783 }}(Exim $version_number)
16:23:34 1783 ${if def:sender_address {(envelope-from <$sender_address>)
16:23:34 1783 }}id $message_exim_id${if def:received_for {
16:23:34 1783 for $received_for}}
16:23:34 1783 ??????result: Received: from [10.1.34.231] (port=33586 helo=felipe.dev.cpanel.net)
16:23:34 1783 by extra.felipe.dev with esmtpsa (TLS1.2) tls TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256
16:23:34 1783 (Exim 4.94)
16:23:34 1783 (envelope-from <canada@felipe.dev.cpanel.net>)
16:23:34 1783 id 1lRLpm-0000Sl-Fn
16:23:34 1783 for felipe@cpanel.com
16:23:34 1783 ???(tainted)
16:23:34 1783 >>Generated Received: header line
16:23:34 1783 P Received: from [10.1.34.231] (port=33586 helo=felipe.dev.cpanel.net)
16:23:34 1783 by extra.felipe.dev with esmtpsa (TLS1.2) tls TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256
16:23:34 1783 (Exim 4.94)
16:23:34 1783 (envelope-from <canada@felipe.dev.cpanel.net>)
16:23:34 1783 id 1lRLpm-0000Sl-Fn
16:23:34 1783 for felipe@cpanel.com; Tue, 30 Mar 2021 16:23:34 -0500
16:23:34 1783 using ACL "acl_smtp_data"
16:23:34 1783 processing "accept" (/etc/exim.conf 517)
16:23:34 1783 check hosts = : +loopback : +recent_authed_mail_ips : +backupmx_hosts
16:23:34 1783 cached no match for +loopback
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 cached no match for +recent_authed_mail_ips
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 cached no match for +backupmx_hosts
16:23:34 1783 cached lookup data = NULL
16:23:34 1783 host in ": +loopback : +recent_authed_mail_ips : +backupmx_hosts"? no (end of list)
16:23:34 1783 accept: condition test failed in ACL "acl_smtp_data"
16:23:34 1783 processing "accept" (/etc/exim.conf 519)
16:23:34 1783 check authenticated = *
16:23:34 1783 dovecot_plain in "*"? yes (matched "*")
16:23:34 1783 check hosts = *
16:23:34 1783 host in "*"? yes (matched "*")
16:23:34 1783 accept: condition test succeeded in ACL "acl_smtp_data"
16:23:34 1783 end of ACL "acl_smtp_data": ACCEPT
16:23:34 1783 ?considering: ${tod_full}
16:23:34 1783 ???expanding: ${tod_full}
16:23:34 1783 ??????result: Tue, 30 Mar 2021 16:23:34 -0500
16:23:34 1783 Writing spool header file: /var/spool/exim//input/m/hdr.1lRLpm-0000Sl-Fn
16:23:34 1783 DSN: **** SPOOL_OUT - address: <felipe@cpanel.com> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
16:23:34 1783 Renaming spool header file: /var/spool/exim//input/m/1lRLpm-0000Sl-Fn-H
16:23:34 1783 Size of headers = 372
16:23:34 1783 ?considering: $h_subject:
16:23:34 1783 ???expanding: $h_subject:
16:23:34 1783 ??????result: Test sent from ./smtp_send_test.pl
16:23:34 1783 ???(tainted)
16:23:34 1783 LOG: MAIN
16:23:34 1783 <= canada@felipe.dev.cpanel.net H=(felipe.dev.cpanel.net) [10.1.34.231]:33586 P=esmtpsa X=TLS1.2:ECDHE-RSA-AES128-GCM-SHA256:128 CV=no A=dovecot_plain:mailer S=389 T="Test sent from ./smtp_send_test.pl"
16:23:34 1783 tick check: 1617139414.489500 1617132214.499000
16:23:34 1783 waiting 7199.991000 sec
------

-FG
--
## List details at https://lists.exim.org/mailman/listinfo/exim-users
## Exim details at http://www.exim.org/
## Please use the Wiki with this list - http://wiki.exim.org/
Re: time skew: SMTP delay [ In reply to ]
> On Mar 30, 2021, at 11:13 AM, Jeremy Harris <jgh@wizmail.org> wrote:
>
> On 30/03/2021 15:02, Felipe Gasper via Exim-users wrote:
>> [pid 25596] write(4</var/log/exim_mainlog>, "2021-03-30 10:55:28 1lRGiG-0006eq-QA <= canada@felipe.dev.cpanel.net H=(felipes-mbp.lan) [10.3.4.84]:49684 P=esmtpsa X=TLS1.2:ECDHE-RSA-AES128-GCM-SHA256:128 CV=no A=dovecot_plain:mailer S=381 T=\"Test sent from ./smtp_send_test.pl\" for felipe@felipe.com\n", 254) = 254
>
> I'd be interested in knowing whether Exim gave an SMTP response accepting the data
> phase of the message.
>
>> [pid 25596] rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0
>> [pid 25596] setitimer(ITIMER_REAL, {it_interval={tv_sec=0, tv_usec=0}, it_value={tv_sec=7198, tv_usec=625000}}, NULL) = 0
>> [pid 25596] rt_sigsuspend(~[ALRM RTMIN RT_1], 8
>> -----
>> … and there it hangs. The socket never closes, so the client eventually times out the connection.
>
> ... because if it did, your test script ought to record the message as having
> been properly sent. The closing of the TCP connection is a seperable issue.

No, I see no such response.

My test script sends: "DATA\r\nFrom: canada@felipe.dev.cpanel.net\r\nSubject: Test sent from ./smtp_send_test.pl\r\n\r\nThis is a test.\r\n.\r\nQUIT\r\n"

The server later sends "354 Enter message, ending with \".\" on a line by itself\r\n", 56354 Enter message, ending with "." on a line by itself", but I think that’s just in response to successful authentication?

-F
--
## List details at https://lists.exim.org/mailman/listinfo/exim-users
## Exim details at http://www.exim.org/
## Please use the Wiki with this list - http://wiki.exim.org/
Re: time skew: SMTP delay [ In reply to ]
On 30/03/2021 16:32, Felipe Gasper wrote:
> (Out of curiosity, can it also be done via exim.conf?)

Yes, in ACL. The docs have a concept-index, helpful
for finding such things.

> 16:23:34 1783 tick check: 1617139414.489500 1617132214.499000
> 16:23:34 1783 waiting 7199.991000 sec

Right; we're a routine affected by the aforementioned bugfix
(since 4.94). Presumably your distro does not track the +fixes
branch we maintain. You might want to build from source.
--
Cheers,
Jeremy

--
## List details at https://lists.exim.org/mailman/listinfo/exim-users
## Exim details at http://www.exim.org/
## Please use the Wiki with this list - http://wiki.exim.org/
Re: time skew: SMTP delay [ In reply to ]
> On Mar 30, 2021, at 10:48 AM, Jeremy Harris via Exim-users <exim-users@exim.org> wrote:
>
> On 30/03/2021 15:40, Felipe Gasper via Exim-users wrote:
>> Incidentally, I’m setting system clock via `date`.
>
> Are you deliberately skewing the system into the future?
> Before or after starting the daemon?

Context: this is happening in production because chrony apparently “jerks” the system time rather than adjusting it slowly.

-F
--
## List details at https://lists.exim.org/mailman/listinfo/exim-users
## Exim details at http://www.exim.org/
## Please use the Wiki with this list - http://wiki.exim.org/