test/stderr/0374

   1 Exim version x.yz ....
   2 configuration file is TESTSUITE/test-config
   3 admin user
   4 LOG: MAIN
   5   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
   6 created log directory TESTSUITE/spool/log
   7 Exim version x.yz ....
   8 configuration file is TESTSUITE/test-config
   9 trusted user
  10 admin user
  11 locking TESTSUITE/spool/db/retry.lockfile
  12 no retry data available
  13 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  14 Considering: a1@myhost.test.ex
  15 unique = a1@myhost.test.ex
  16 a1@myhost.test.ex: queued for routing
  17 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  18 Considering: b1@myhost.test.ex
  19 unique = b1@myhost.test.ex
  20 b1@myhost.test.ex: queued for routing
  21 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  22 Considering: c1@myhost.test.ex
  23 unique = c1@myhost.test.ex
  24 c1@myhost.test.ex: queued for routing
  25 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  26 Considering: d1@myhost.test.ex
  27 unique = d1@myhost.test.ex
  28 d1@myhost.test.ex: queued for routing
  29 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  30 Considering: d2@myhost.test.ex
  31 unique = d2@myhost.test.ex
  32 d2@myhost.test.ex: queued for routing
  33 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  34 Considering: d3@myhost.test.ex
  35 unique = d3@myhost.test.ex
  36 d3@myhost.test.ex: queued for routing
  37 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  38 routing d3@myhost.test.ex
  39 --------> u1 router <--------
  40 local_part=d3 domain=myhost.test.ex
  41 checking local_parts
  42 u1 router skipped: local_parts mismatch
  43 --------> ut2 router <--------
  44 local_part=d3 domain=myhost.test.ex
  45 checking local_parts
  46 ut2 router skipped: local_parts mismatch
  47 --------> ut3 router <--------
  48 local_part=d3 domain=myhost.test.ex
  49 checking local_parts
  50 ut3 router skipped: local_parts mismatch
  51 --------> ut4 router <--------
  52 local_part=d3 domain=myhost.test.ex
  53 checking local_parts
  54 calling ut4 router
  55 ut4 router called for d3@myhost.test.ex
  56   domain = myhost.test.ex
  57 set transport ut4
  58 queued for ut4 transport: local_part = d3
  59 domain = myhost.test.ex
  60   errors_to=NULL
  61   domain_data=NULL localpart_data=NULL
  62 routed by ut4 router (unseen)
  63   envelope to: d3@myhost.test.ex
  64   transport: ut4
  65 "unseen" set: replicated d3@myhost.test.ex
  66 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  67 routing d2@myhost.test.ex
  68 --------> u1 router <--------
  69 local_part=d2 domain=myhost.test.ex
  70 checking local_parts
  71 u1 router skipped: local_parts mismatch
  72 --------> ut2 router <--------
  73 local_part=d2 domain=myhost.test.ex
  74 checking local_parts
  75 ut2 router skipped: local_parts mismatch
  76 --------> ut3 router <--------
  77 local_part=d2 domain=myhost.test.ex
  78 checking local_parts
  79 ut3 router skipped: local_parts mismatch
  80 --------> ut4 router <--------
  81 local_part=d2 domain=myhost.test.ex
  82 checking local_parts
  83 calling ut4 router
  84 ut4 router called for d2@myhost.test.ex
  85   domain = myhost.test.ex
  86 queued for ut4 transport: local_part = d2
  87 domain = myhost.test.ex
  88   errors_to=NULL
  89   domain_data=NULL localpart_data=NULL
  90 routed by ut4 router (unseen)
  91   envelope to: d2@myhost.test.ex
  92   transport: ut4
  93 "unseen" set: replicated d2@myhost.test.ex
  94 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  95 routing d1@myhost.test.ex
  96 --------> u1 router <--------
  97 local_part=d1 domain=myhost.test.ex
  98 checking local_parts
  99 u1 router skipped: local_parts mismatch
 100 --------> ut2 router <--------
 101 local_part=d1 domain=myhost.test.ex
 102 checking local_parts
 103 ut2 router skipped: local_parts mismatch
 104 --------> ut3 router <--------
 105 local_part=d1 domain=myhost.test.ex
 106 checking local_parts
 107 ut3 router skipped: local_parts mismatch
 108 --------> ut4 router <--------
 109 local_part=d1 domain=myhost.test.ex
 110 checking local_parts
 111 calling ut4 router
 112 ut4 router called for d1@myhost.test.ex
 113   domain = myhost.test.ex
 114 queued for ut4 transport: local_part = d1
 115 domain = myhost.test.ex
 116   errors_to=NULL
 117   domain_data=NULL localpart_data=NULL
 118 routed by ut4 router (unseen)
 119   envelope to: d1@myhost.test.ex
 120   transport: ut4
 121 "unseen" set: replicated d1@myhost.test.ex
 122 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 123 routing c1@myhost.test.ex
 124 --------> u1 router <--------
 125 local_part=c1 domain=myhost.test.ex
 126 checking local_parts
 127 u1 router skipped: local_parts mismatch
 128 --------> ut2 router <--------
 129 local_part=c1 domain=myhost.test.ex
 130 checking local_parts
 131 ut2 router skipped: local_parts mismatch
 132 --------> ut3 router <--------
 133 local_part=c1 domain=myhost.test.ex
 134 checking local_parts
 135 calling ut3 router
 136 ut3 router called for c1@myhost.test.ex
 137   domain = myhost.test.ex
 138 set transport ut3
 139 queued for ut3 transport: local_part = c1
 140 domain = myhost.test.ex
 141   errors_to=NULL
 142   domain_data=NULL localpart_data=NULL
 143 routed by ut3 router (unseen)
 144   envelope to: c1@myhost.test.ex
 145   transport: ut3
 146 "unseen" set: replicated c1@myhost.test.ex
 147 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 148 routing b1@myhost.test.ex
 149 --------> u1 router <--------
 150 local_part=b1 domain=myhost.test.ex
 151 checking local_parts
 152 u1 router skipped: local_parts mismatch
 153 --------> ut2 router <--------
 154 local_part=b1 domain=myhost.test.ex
 155 checking local_parts
 156 calling ut2 router
 157 ut2 router called for b1@myhost.test.ex
 158   domain = myhost.test.ex
 159 set transport ut2
 160 queued for ut2 transport: local_part = b1
 161 domain = myhost.test.ex
 162   errors_to=NULL
 163   domain_data=NULL localpart_data=NULL
 164 routed by ut2 router (unseen)
 165   envelope to: b1@myhost.test.ex
 166   transport: ut2
 167 "unseen" set: replicated b1@myhost.test.ex
 168 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 169 routing a1@myhost.test.ex
 170 --------> u1 router <--------
 171 local_part=a1 domain=myhost.test.ex
 172 checking local_parts
 173 calling u1 router
 174 u1 router called for a1@myhost.test.ex
 175   domain = myhost.test.ex
 176 set transport ut1
 177 queued for ut1 transport: local_part = a1
 178 domain = myhost.test.ex
 179   errors_to=NULL
 180   domain_data=NULL localpart_data=NULL
 181 routed by u1 router (unseen)
 182   envelope to: a1@myhost.test.ex
 183   transport: ut1
 184 "unseen" set: replicated a1@myhost.test.ex
 185 locking TESTSUITE/spool/db/retry.lockfile
 186 no retry data available
 187 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 188 Considering: a1@myhost.test.ex
 189 unique = \0\a1@myhost.test.ex
 190 a1@myhost.test.ex: queued for routing
 191 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 192 Considering: b1@myhost.test.ex
 193 unique = \0\b1@myhost.test.ex
 194 b1@myhost.test.ex: queued for routing
 195 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 196 Considering: c1@myhost.test.ex
 197 unique = \0\c1@myhost.test.ex
 198 c1@myhost.test.ex: queued for routing
 199 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 200 Considering: d1@myhost.test.ex
 201 unique = \0\d1@myhost.test.ex
 202 d1@myhost.test.ex: queued for routing
 203 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 204 Considering: d2@myhost.test.ex
 205 unique = \0\d2@myhost.test.ex
 206 d2@myhost.test.ex: queued for routing
 207 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 208 Considering: d3@myhost.test.ex
 209 unique = \0\d3@myhost.test.ex
 210 d3@myhost.test.ex: queued for routing
 211 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 212 routing d3@myhost.test.ex
 213 --------> real router <--------
 214 local_part=d3 domain=myhost.test.ex
 215 calling real router
 216 real router called for d3@myhost.test.ex
 217   domain = myhost.test.ex
 218 set transport real
 219 queued for real transport: local_part = d3
 220 domain = myhost.test.ex
 221   errors_to=NULL
 222   domain_data=NULL localpart_data=NULL
 223 routed by real router
 224   envelope to: d3@myhost.test.ex
 225   transport: real
 226 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 227 routing d2@myhost.test.ex
 228 --------> real router <--------
 229 local_part=d2 domain=myhost.test.ex
 230 calling real router
 231 real router called for d2@myhost.test.ex
 232   domain = myhost.test.ex
 233 queued for real transport: local_part = d2
 234 domain = myhost.test.ex
 235   errors_to=NULL
 236   domain_data=NULL localpart_data=NULL
 237 routed by real router
 238   envelope to: d2@myhost.test.ex
 239   transport: real
 240 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 241 routing d1@myhost.test.ex
 242 --------> real router <--------
 243 local_part=d1 domain=myhost.test.ex
 244 calling real router
 245 real router called for d1@myhost.test.ex
 246   domain = myhost.test.ex
 247 queued for real transport: local_part = d1
 248 domain = myhost.test.ex
 249   errors_to=NULL
 250   domain_data=NULL localpart_data=NULL
 251 routed by real router
 252   envelope to: d1@myhost.test.ex
 253   transport: real
 254 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 255 routing c1@myhost.test.ex
 256 --------> ut4 router <--------
 257 local_part=c1 domain=myhost.test.ex
 258 checking local_parts
 259 ut4 router skipped: local_parts mismatch
 260 --------> real router <--------
 261 local_part=c1 domain=myhost.test.ex
 262 calling real router
 263 real router called for c1@myhost.test.ex
 264   domain = myhost.test.ex
 265 queued for real transport: local_part = c1
 266 domain = myhost.test.ex
 267   errors_to=NULL
 268   domain_data=NULL localpart_data=NULL
 269 routed by real router
 270   envelope to: c1@myhost.test.ex
 271   transport: real
 272 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 273 routing b1@myhost.test.ex
 274 --------> ut3 router <--------
 275 local_part=b1 domain=myhost.test.ex
 276 checking local_parts
 277 ut3 router skipped: local_parts mismatch
 278 --------> ut4 router <--------
 279 local_part=b1 domain=myhost.test.ex
 280 checking local_parts
 281 ut4 router skipped: local_parts mismatch
 282 --------> real router <--------
 283 local_part=b1 domain=myhost.test.ex
 284 calling real router
 285 real router called for b1@myhost.test.ex
 286   domain = myhost.test.ex
 287 queued for real transport: local_part = b1
 288 domain = myhost.test.ex
 289   errors_to=NULL
 290   domain_data=NULL localpart_data=NULL
 291 routed by real router
 292   envelope to: b1@myhost.test.ex
 293   transport: real
 294 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 295 routing a1@myhost.test.ex
 296 --------> ut2 router <--------
 297 local_part=a1 domain=myhost.test.ex
 298 checking local_parts
 299 ut2 router skipped: local_parts mismatch
 300 --------> ut3 router <--------
 301 local_part=a1 domain=myhost.test.ex
 302 checking local_parts
 303 ut3 router skipped: local_parts mismatch
 304 --------> ut4 router <--------
 305 local_part=a1 domain=myhost.test.ex
 306 checking local_parts
 307 ut4 router skipped: local_parts mismatch
 308 --------> real router <--------
 309 local_part=a1 domain=myhost.test.ex
 310 calling real router
 311 real router called for a1@myhost.test.ex
 312   domain = myhost.test.ex
 313 queued for real transport: local_part = a1
 314 domain = myhost.test.ex
 315   errors_to=NULL
 316   domain_data=NULL localpart_data=NULL
 317 routed by real router
 318   envelope to: a1@myhost.test.ex
 319   transport: real
 320 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 321 After routing:
 322   Local deliveries:
 323     a1@myhost.test.ex
 324     b1@myhost.test.ex
 325     c1@myhost.test.ex
 326     d1@myhost.test.ex
 327     d2@myhost.test.ex
 328     d3@myhost.test.ex
 329     a1@myhost.test.ex
 330     b1@myhost.test.ex
 331     c1@myhost.test.ex
 332   Remote deliveries:
 333     d1@myhost.test.ex
 334     d2@myhost.test.ex
 335     d3@myhost.test.ex
 336   Failed addresses:
 337   Deferred addresses:
 338 locking TESTSUITE/spool/db/retry.lockfile
 339 LOG: MAIN
 340   => a1 <a1@myhost.test.ex> R=real T=real
 341 locking TESTSUITE/spool/db/retry.lockfile
 342 LOG: MAIN
 343   => b1 <b1@myhost.test.ex> R=real T=real
 344 locking TESTSUITE/spool/db/retry.lockfile
 345 LOG: MAIN
 346   => c1 <c1@myhost.test.ex> R=real T=real
 347 locking TESTSUITE/spool/db/retry.lockfile
 348 LOG: MAIN
 349   => d1 <d1@myhost.test.ex> R=real T=real
 350 locking TESTSUITE/spool/db/retry.lockfile
 351 LOG: MAIN
 352   => d2 <d2@myhost.test.ex> R=real T=real
 353 locking TESTSUITE/spool/db/retry.lockfile
 354 LOG: MAIN
 355   => d3 <d3@myhost.test.ex> R=real T=real
 356 locking TESTSUITE/spool/db/retry.lockfile
 357 LOG: MAIN
 358   => a1 <a1@myhost.test.ex> R=u1 T=ut1
 359 locking TESTSUITE/spool/db/retry.lockfile
 360 transport error EPIPE ignored
 361 LOG: MAIN
 362   ** b1@myhost.test.ex R=ut2 T=ut2: Child process of ut2 transport returned 127 (could mean unable to exec or command does not exist) from command: /non/existent/file
 363 locking TESTSUITE/spool/db/retry.lockfile
 364 transport error EPIPE ignored
 365 LOG: MAIN
 366   == c1@myhost.test.ex R=ut3 T=ut3 defer (0): Child process of ut3 transport returned 127 (could mean unable to exec or command does not exist) from command: /non/existent/file
 367 locking TESTSUITE/spool/db/retry.lockfile
 368 locking TESTSUITE/spool/db/wait-ut4.lockfile
 369 LOG: MAIN
 370   => d1@myhost.test.ex R=ut4 T=ut4 H=127.0.0.1 [127.0.0.1]
 371 locking TESTSUITE/spool/db/retry.lockfile
 372 LOG: MAIN
 373   == d2@myhost.test.ex R=ut4 T=ut4 defer (-44): SMTP error from remote mail server after RCPT TO:<d2@myhost.test.ex>: host 127.0.0.1 [127.0.0.1]: 450 soft error
 374 locking TESTSUITE/spool/db/retry.lockfile
 375 locking TESTSUITE/spool/db/wait-ut4.lockfile
 376 LOG: MAIN
 377   ** d3@myhost.test.ex R=ut4 T=ut4: SMTP error from remote mail server after RCPT TO:<d3@myhost.test.ex>: host 127.0.0.1 [127.0.0.1]: 550 hard error
 378 locking TESTSUITE/spool/db/retry.lockfile
 379 Exim version x.yz ....
 380 configuration file is TESTSUITE/test-config
 381 trusted user
 382 admin user
 383 LOG: MAIN
 384   <= <> R=10HmaX-0005vi-00 U=EXIMUSER P=local S=sss
 385 Exim version x.yz ....
 386 configuration file is TESTSUITE/test-config
 387 trusted user
 388 admin user
 389 locking TESTSUITE/spool/db/retry.lockfile
 390 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 391 Considering: CALLER@myhost.test.ex
 392 unique = CALLER@myhost.test.ex
 393 CALLER@myhost.test.ex: queued for routing
 394 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 395 routing CALLER@myhost.test.ex
 396 --------> u1 router <--------
 397 local_part=CALLER domain=myhost.test.ex
 398 checking local_parts
 399 u1 router skipped: local_parts mismatch
 400 --------> ut2 router <--------
 401 local_part=CALLER domain=myhost.test.ex
 402 checking local_parts
 403 ut2 router skipped: local_parts mismatch
 404 --------> ut3 router <--------
 405 local_part=CALLER domain=myhost.test.ex
 406 checking local_parts
 407 ut3 router skipped: local_parts mismatch
 408 --------> ut4 router <--------
 409 local_part=CALLER domain=myhost.test.ex
 410 checking local_parts
 411 ut4 router skipped: local_parts mismatch
 412 --------> real router <--------
 413 local_part=CALLER domain=myhost.test.ex
 414 calling real router
 415 real router called for CALLER@myhost.test.ex
 416   domain = myhost.test.ex
 417 set transport real
 418 queued for real transport: local_part = CALLER
 419 domain = myhost.test.ex
 420   errors_to=NULL
 421   domain_data=NULL localpart_data=NULL
 422 routed by real router
 423   envelope to: CALLER@myhost.test.ex
 424   transport: real
 425 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 426 After routing:
 427   Local deliveries:
 428     CALLER@myhost.test.ex
 429   Remote deliveries:
 430   Failed addresses:
 431   Deferred addresses:
 432 locking TESTSUITE/spool/db/retry.lockfile
 433 LOG: MAIN
 434   => CALLER <CALLER@myhost.test.ex> R=real T=real
 435 LOG: MAIN
 436   Completed
 437 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
 438 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
 439 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
 440 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>