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