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