test/stderr/0022

   1 Exim version x.yz ....
   2 changed uid/gid: forcing real = effective
   3   uid=uuuu gid=CALLER_GID pid=pppp
   4 configuration file is TESTSUITE/test-config
   5 admin user
   6 changed uid/gid: privilege not needed
   7   uid=EXIM_UID gid=EXIM_GID pid=pppp
   8 seeking password data for user "CALLER": cache not available
   9 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  10 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
  11 sender address = CALLER@test.ex
  12 sender_fullhost = [V4NET.9.8.7]
  13 sender_rcvhost = [V4NET.9.8.7]
  14 host in hosts_connection_nolog? no (end of list)
  15 LOG: smtp_connection MAIN
  16   SMTP connection from [V4NET.9.8.7]
  17 host in host_lookup? no (option unset)
  18 set_process_info: pppp handling incoming connection from [V4NET.9.8.7]
  19 host in host_reject_connection? no (option unset)
  20 host in sender_unqualified_hosts? no (option unset)
  21 host in recipient_unqualified_hosts? no (option unset)
  22 host in helo_verify_hosts? no (option unset)
  23 host in helo_try_verify_hosts? no (option unset)
  24 host in helo_accept_junk_hosts? no (option unset)
  25 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
  26 smtp_setup_msg entered
  27 SMTP<< mail from:<x@y>
  28 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
  29 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
  30 SMTP>> 250 OK
  31 SMTP<< rcpt to:<warn_empty@test.ex>
  32 using ACL "warn_empty"
  33 processing "warn"
  34 warn: condition test succeeded in ACL "warn_empty"
  35 processing "accept"
  36 accept: condition test succeeded in ACL "warn_empty"
  37 end of ACL "warn_empty": ACCEPT
  38 SMTP>> 250 Accepted
  39 DSN: orcpt: NULL  flags: 0
  40 SMTP<< data
  41 SMTP>> 354 Enter message, ending with "." on a line by itself
  42 search_tidyup called
  43 >>Headers received:
  44
  45 search_tidyup called
  46 >>Headers after rewriting and local additions:
  47
  48 Data file name: TESTSUITE/spool//input//10HmbF-0005vi-00-D
  49 Data file written for message 10HmbF-0005vi-00
  50 >>Generated Received: header line
  51 P Received: from [V4NET.9.8.7]
  52         by myhost.test.ex with smtp (Exim x.yz)
  53         (envelope-from <x@y>)
  54         id 10HmbF-0005vi-00
  55         for warn_empty@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
  56 PDKIM: no signatures
  57 calling local_scan(); timeout=300
  58 local_scan() returned 0 NULL
  59 LOG: MAIN
  60   <= x@y H=[V4NET.9.8.7] P=smtp S=sss
  61 SMTP>> 250 OK id=10HmbF-0005vi-00
  62 smtp_setup_msg entered
  63 SMTP<< quit
  64 SMTP>> 221 myhost.test.ex closing connection
  65 LOG: smtp_connection MAIN
  66   SMTP connection from [V4NET.9.8.7] closed by QUIT
  67 search_tidyup called
  68 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
  69 Exim version x.yz ....
  70 changed uid/gid: forcing real = effective
  71   uid=uuuu gid=CALLER_GID pid=pppp
  72 configuration file is TESTSUITE/test-config
  73 admin user
  74 changed uid/gid: privilege not needed
  75   uid=EXIM_UID gid=EXIM_GID pid=pppp
  76 seeking password data for user "CALLER": cache not available
  77 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  78 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
  79 sender address = CALLER@test.ex
  80 sender_fullhost = [V4NET.9.8.7]
  81 sender_rcvhost = [V4NET.9.8.7]
  82 host in hosts_connection_nolog? no (end of list)
  83 LOG: smtp_connection MAIN
  84   SMTP connection from [V4NET.9.8.7]
  85 host in host_lookup? no (option unset)
  86 set_process_info: pppp handling incoming connection from [V4NET.9.8.7]
  87 host in host_reject_connection? no (option unset)
  88 host in sender_unqualified_hosts? no (option unset)
  89 host in recipient_unqualified_hosts? no (option unset)
  90 host in helo_verify_hosts? no (option unset)
  91 host in helo_try_verify_hosts? no (option unset)
  92 host in helo_accept_junk_hosts? no (option unset)
  93 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
  94 smtp_setup_msg entered
  95 SMTP<< mail from:<x@y>
  96 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
  97 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
  98 SMTP>> 250 OK
  99 SMTP<< rcpt to:<warn_log@test.ex>
 100 using ACL "warn_log"
 101 processing "warn"
 102 l_message: warn log message
 103 warn: condition test succeeded in ACL "warn_log"
 104 LOG: MAIN
 105   H=[V4NET.9.8.7] Warning: warn log message
 106 processing "accept"
 107 accept: condition test succeeded in ACL "warn_log"
 108 end of ACL "warn_log": ACCEPT
 109 SMTP>> 250 Accepted
 110 DSN: orcpt: NULL  flags: 0
 111 SMTP<< data
 112 SMTP>> 354 Enter message, ending with "." on a line by itself
 113 search_tidyup called
 114 >>Headers received:
 115
 116 search_tidyup called
 117 >>Headers after rewriting and local additions:
 118
 119 Data file name: TESTSUITE/spool//input//10HmbG-0005vi-00-D
 120 Data file written for message 10HmbG-0005vi-00
 121 >>Generated Received: header line
 122 P Received: from [V4NET.9.8.7]
 123         by myhost.test.ex with smtp (Exim x.yz)
 124         (envelope-from <x@y>)
 125         id 10HmbG-0005vi-00
 126         for warn_log@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
 127 PDKIM: no signatures
 128 calling local_scan(); timeout=300
 129 local_scan() returned 0 NULL
 130 LOG: MAIN
 131   <= x@y H=[V4NET.9.8.7] P=smtp S=sss
 132 SMTP>> 250 OK id=10HmbG-0005vi-00
 133 smtp_setup_msg entered
 134 SMTP<< quit
 135 SMTP>> 221 myhost.test.ex closing connection
 136 LOG: smtp_connection MAIN
 137   SMTP connection from [V4NET.9.8.7] closed by QUIT
 138 search_tidyup called
 139 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
 140 Exim version x.yz ....
 141 changed uid/gid: forcing real = effective
 142   uid=uuuu gid=CALLER_GID pid=pppp
 143 configuration file is TESTSUITE/test-config
 144 admin user
 145 changed uid/gid: privilege not needed
 146   uid=EXIM_UID gid=EXIM_GID pid=pppp
 147 seeking password data for user "CALLER": cache not available
 148 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 149 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 150 sender address = CALLER@test.ex
 151 sender_fullhost = [V4NET.9.8.7]
 152 sender_rcvhost = [V4NET.9.8.7]
 153 host in hosts_connection_nolog? no (end of list)
 154 LOG: smtp_connection MAIN
 155   SMTP connection from [V4NET.9.8.7]
 156 host in host_lookup? no (option unset)
 157 set_process_info: pppp handling incoming connection from [V4NET.9.8.7]
 158 host in host_reject_connection? no (option unset)
 159 host in sender_unqualified_hosts? no (option unset)
 160 host in recipient_unqualified_hosts? no (option unset)
 161 host in helo_verify_hosts? no (option unset)
 162 host in helo_try_verify_hosts? no (option unset)
 163 host in helo_accept_junk_hosts? no (option unset)
 164 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 165 smtp_setup_msg entered
 166 SMTP<< mail from:<x@y>
 167 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 168 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 169 SMTP>> 250 OK
 170 SMTP<< rcpt to:<warn_user@test.ex>
 171 using ACL "warn_user"
 172 processing "warn"
 173   message: warn user message
 174 warn: condition test succeeded in ACL "warn_user"
 175 processing "accept"
 176 accept: condition test succeeded in ACL "warn_user"
 177 end of ACL "warn_user": ACCEPT
 178 SMTP>> 250 Accepted
 179 DSN: orcpt: NULL  flags: 0
 180 SMTP<< data
 181 SMTP>> 354 Enter message, ending with "." on a line by itself
 182 search_tidyup called
 183 >>Headers received:
 184
 185 search_tidyup called
 186 >>Headers after rewriting and local additions:
 187
 188 Data file name: TESTSUITE/spool//input//10HmbH-0005vi-00-D
 189 Data file written for message 10HmbH-0005vi-00
 190 >>Generated Received: header line
 191 P Received: from [V4NET.9.8.7]
 192         by myhost.test.ex with smtp (Exim x.yz)
 193         (envelope-from <x@y>)
 194         id 10HmbH-0005vi-00
 195         for warn_user@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
 196 >>Headers added by MAIL or RCPT ACL:
 197   X-ACL-Warn: warn user message
 198 >>
 199 PDKIM: no signatures
 200 calling local_scan(); timeout=300
 201 local_scan() returned 0 NULL
 202 LOG: MAIN
 203   <= x@y H=[V4NET.9.8.7] P=smtp S=sss
 204 SMTP>> 250 OK id=10HmbH-0005vi-00
 205 smtp_setup_msg entered
 206 SMTP<< quit
 207 SMTP>> 221 myhost.test.ex closing connection
 208 LOG: smtp_connection MAIN
 209   SMTP connection from [V4NET.9.8.7] closed by QUIT
 210 search_tidyup called
 211 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
 212 >>> host in hosts_connection_nolog? no (end of list)
 213 LOG: SMTP connection from [V4NET.9.8.7]
 214 >>> host in host_lookup? no (option unset)
 215 >>> host in host_reject_connection? no (option unset)
 216 >>> host in sender_unqualified_hosts? no (option unset)
 217 >>> host in recipient_unqualified_hosts? no (option unset)
 218 >>> host in helo_verify_hosts? no (option unset)
 219 >>> host in helo_try_verify_hosts? no (option unset)
 220 >>> host in helo_accept_junk_hosts? no (option unset)
 221 >>> using ACL "defer"
 222 >>> processing "defer"
 223 >>>   message: forcibly deferred
 224 >>> defer: condition test succeeded in ACL "defer"
 225 >>> end of ACL "defer": DEFER
 226 LOG: H=[V4NET.9.8.7] F=<x@y> temporarily rejected RCPT <defer@y>: forcibly deferred
 227 >>> using ACL "accept"
 228 >>> processing "accept"
 229 >>> accept: condition test succeeded in ACL "accept"
 230 >>> end of ACL "accept": ACCEPT
 231 >>> using ACL "drop"
 232 >>> processing "drop"
 233 >>>   message: forcibly dropped
 234 >>> drop: condition test succeeded in ACL "drop"
 235 >>> end of ACL "drop": DROP
 236 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <drop@y>: forcibly dropped
 237 LOG: SMTP connection from [V4NET.9.8.7] closed by DROP in ACL
 238 >>> host in hosts_connection_nolog? no (end of list)
 239 LOG: SMTP connection from [V4NET.9.8.7]
 240 >>> host in host_lookup? no (option unset)
 241 >>> host in host_reject_connection? no (option unset)
 242 >>> host in sender_unqualified_hosts? no (option unset)
 243 >>> host in recipient_unqualified_hosts? no (option unset)
 244 >>> host in helo_verify_hosts? no (option unset)
 245 >>> host in helo_try_verify_hosts? no (option unset)
 246 >>> host in helo_accept_junk_hosts? no (option unset)
 247 >>> using ACL "defer_senders"
 248 >>> processing "defer"
 249 >>> check senders = :
 250 >>>  in ":"? yes (matched "")
 251 >>> defer: condition test succeeded in ACL "defer_senders"
 252 >>> end of ACL "defer_senders": DEFER
 253 LOG: H=[V4NET.9.8.7] F=<> temporarily rejected RCPT <defer_senders@y>
 254 LOG: SMTP connection from [V4NET.9.8.7] closed by QUIT
 255 >>> host in hosts_connection_nolog? no (end of list)
 256 LOG: SMTP connection from [V4NET.9.8.7]
 257 >>> host in host_lookup? no (option unset)
 258 >>> host in host_reject_connection? no (option unset)
 259 >>> host in sender_unqualified_hosts? no (option unset)
 260 >>> host in recipient_unqualified_hosts? no (option unset)
 261 >>> host in helo_verify_hosts? no (option unset)
 262 >>> host in helo_try_verify_hosts? no (option unset)
 263 >>> host in helo_accept_junk_hosts? no (option unset)
 264 >>> using ACL "delay_accept"
 265 >>> processing "accept"
 266 >>> check delay = 1s
 267 >>> delay modifier requests 1-second delay
 268 >>> delay skipped in -bh checking mode
 269 >>> accept: condition test succeeded in ACL "delay_accept"
 270 >>> end of ACL "delay_accept": ACCEPT
 271 >>> using ACL "delay_warn"
 272 >>> processing "warn"
 273 >>> check delay = 1s
 274 >>> delay modifier requests 1-second delay
 275 >>> delay skipped in -bh checking mode
 276 >>> warn: condition test succeeded in ACL "delay_warn"
 277 >>> processing "accept"
 278 >>> accept: condition test succeeded in ACL "delay_warn"
 279 >>> end of ACL "delay_warn": ACCEPT
 280 LOG: SMTP connection from [V4NET.9.8.7] closed by QUIT
 281 >>> host in hosts_connection_nolog? no (end of list)
 282 LOG: SMTP connection from [V4NET.9.8.7]
 283 >>> host in host_lookup? no (option unset)
 284 >>> host in host_reject_connection? no (option unset)
 285 >>> host in sender_unqualified_hosts? no (option unset)
 286 >>> host in recipient_unqualified_hosts? no (option unset)
 287 >>> host in helo_verify_hosts? no (option unset)
 288 >>> host in helo_try_verify_hosts? no (option unset)
 289 >>> host in helo_accept_junk_hosts? no (option unset)
 290 >>> using ACL "host_check"
 291 >>> processing "deny"
 292 >>> check hosts = net-lsearch;TESTSUITE/aux-var/0022.hosts
 293 >>> host in "net-lsearch;TESTSUITE/aux-var/0022.hosts"? yes (matched "net-lsearch;TESTSUITE/aux-var/0022.hosts")
 294 >>>   message: host data >$host_data<
 295 >>> deny: condition test succeeded in ACL "host_check"
 296 >>> end of ACL "host_check": DENY
 297 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <host_check@y>: host data >A host-specific message<
 298 >>> using ACL "host_check"
 299 >>> processing "deny"
 300 >>> check hosts = net-lsearch;TESTSUITE/aux-var/0022.hosts
 301 >>> host in "net-lsearch;TESTSUITE/aux-var/0022.hosts"? yes (matched "net-lsearch;TESTSUITE/aux-var/0022.hosts")
 302 >>>   message: host data >$host_data<
 303 >>> deny: condition test succeeded in ACL "host_check"
 304 >>> end of ACL "host_check": DENY
 305 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <host_check@y>: host data >A host-specific message<
 306 >>> using ACL "host_check2"
 307 >>> processing "deny"
 308 >>>   message: host data >$host_data<
 309 >>> check hosts = +some_hosts
 310 >>> host in "net-lsearch;TESTSUITE/aux-var/0022.hosts"? yes (matched "net-lsearch;TESTSUITE/aux-var/0022.hosts")
 311 >>> host in "+some_hosts"? yes (matched "+some_hosts")
 312 >>> deny: condition test succeeded in ACL "host_check2"
 313 >>> end of ACL "host_check2": DENY
 314 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <host_check2@y>: host data >A host-specific message<
 315 >>> using ACL "host_check2"
 316 >>> processing "deny"
 317 >>>   message: host data >$host_data<
 318 >>> check hosts = +some_hosts
 319 >>> host in "+some_hosts"? yes (matched "+some_hosts" - cached)
 320 >>> deny: condition test succeeded in ACL "host_check2"
 321 >>> end of ACL "host_check2": DENY
 322 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <host_check2@y>: host data >A host-specific message<
 323 LOG: SMTP connection from [V4NET.9.8.7] closed by QUIT
 324 LOG: smtp_connection MAIN
 325   SMTP connection from CALLER
 326 LOG: MAIN
 327   Connections=0
 328 LOG: MAIN
 329   <= CALLER@test.ex U=CALLER P=local-smtp S=sss
 330 LOG: MAIN
 331   frozen by ACL
 332 LOG: MAIN
 333   <= CALLER@test.ex U=CALLER P=local-smtp S=sss
 334 delivering 10HmaY-0005vi-00
 335 LOG: MAIN
 336   *> accept <accept@y> R=accept T=appendfile
 337 LOG: MAIN
 338   Completed
 339 LOG: MAIN
 340   <= CALLER@test.ex U=CALLER P=local-smtp S=sss
 341 LOG: delay_delivery MAIN
 342   no immediate delivery: queued by ACL
 343 LOG: MAIN
 344   <= CALLER@test.ex U=CALLER P=local-smtp S=sss
 345 delivering 10HmbA-0005vi-00
 346 LOG: MAIN
 347   *> accept <accept@y> R=accept T=appendfile
 348 LOG: MAIN
 349   Completed
 350 LOG: smtp_connection MAIN
 351   SMTP connection from CALLER closed by QUIT
 352 >>> host in hosts_connection_nolog? no (end of list)
 353 LOG: SMTP connection from [V4NET.9.8.7]
 354 >>> host in host_lookup? no (option unset)
 355 >>> host in host_reject_connection? no (option unset)
 356 >>> host in sender_unqualified_hosts? no (option unset)
 357 >>> host in recipient_unqualified_hosts? no (option unset)
 358 >>> host in helo_verify_hosts? no (option unset)
 359 >>> host in helo_try_verify_hosts? no (option unset)
 360 >>> host in helo_accept_junk_hosts? no (option unset)
 361 >>> using ACL "nested_drop"
 362 >>> processing "accept"
 363 >>> check acl = drop
 364 >>>  using ACL "drop"
 365 >>>  processing "drop"
 366 >>>    message: forcibly dropped
 367 >>>  drop: condition test succeeded in ACL "drop"
 368 >>>  end of ACL "drop": DROP
 369 >>> accept: condition test yielded "drop" in ACL "nested_drop"
 370 >>> accept: endpass encountered - denying access
 371 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <nested_drop@y>: forcibly dropped
 372 LOG: SMTP connection from [V4NET.9.8.7] closed by DROP in ACL
 373 >>> host in hosts_connection_nolog? no (end of list)
 374 LOG: SMTP connection from [V4NET.9.8.7]
 375 >>> host in host_lookup? no (option unset)
 376 >>> host in host_reject_connection? no (option unset)
 377 >>> host in sender_unqualified_hosts? no (option unset)
 378 >>> host in recipient_unqualified_hosts? no (option unset)
 379 >>> host in helo_verify_hosts? no (option unset)
 380 >>> host in helo_try_verify_hosts? no (option unset)
 381 >>> host in helo_accept_junk_hosts? no (option unset)
 382 >>> using ACL "nested_drop_require"
 383 >>> processing "require"
 384 >>> check acl = drop
 385 >>>  using ACL "drop"
 386 >>>  processing "drop"
 387 >>>    message: forcibly dropped
 388 >>>  drop: condition test succeeded in ACL "drop"
 389 >>>  end of ACL "drop": DROP
 390 >>> require: condition test yielded "drop" in ACL "nested_drop_require"
 391 >>> end of ACL "nested_drop_require": not OK
 392 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <nested_drop_require@y>: forcibly dropped
 393 LOG: SMTP connection from [V4NET.9.8.7] closed by DROP in ACL
 394
 395 ******** SERVER ********