test/stderr/0374

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