Jul 19 21:45:30 sd1528 postfix/qmgr[11909]: dir_forest: 11BE9CDD48 -> 1/ Jul 19 21:45:30 sd1528 postfix/qmgr[11909]: wakeup 11BE9CDD48 after 4000 secs Jul 19 21:45:30 sd1528 postfix/qmgr[11909]: dir_forest: 11BE9CDD48 -> 1/ Jul 19 21:45:30 sd1528 last message repeated 2 times Jul 19 21:45:30 sd1528 postfix/qmgr[11909]: qmgr_active_defer: defer 11BE9CDD48 Jul 19 21:45:30 sd1528 postfix/qmgr[11909]: qmgr_job_free: 11BE9CDD48 smtp Jul 19 21:45:30 sd1528 postfix/qmgr[11909]: watchdog_start: 0x805e3a0 Jul 19 21:45:30 sd1528 postfix/master[5771]: warning: process /usr/lib/postfix/smtp pid 11938 exit status 127 Jul 19 21:46:08 sd1528 postfix/qmgr[11909]: rewrite stream disconnect Jul 19 21:46:08 sd1528 postfix/qmgr[11909]: watchdog_start: 0x805e3a0 Jul 19 21:47:51 sd1528 postfix/pickup[11908]: 0B43ACD5C4: uid=33 from= Jul 19 21:47:51 sd1528 postfix/cleanup[12039]: 0B43ACD5C4: message-id=<20060719194751.0B43ACD5C4@mail.raphaelharoche.net> Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: trigger_server_accept_fifo: trigger arrived Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: master_notify: status 0 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: request: 87 (W) Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_scan_start: start incoming queue scan Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: master_notify: status 1 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: watchdog_start: 0x805e3a0 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found A Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/A Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/A Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found 9 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/9 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/9 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found D Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/D Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/D Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found C Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/C Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/C Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found 1 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/1 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/1 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found 6 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/6 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/6 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found 2 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/2 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/2 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found 4 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/4 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/4 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found E Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/E Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/E Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found 8 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/8 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/8 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found 3 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/3 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/3 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found 5 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/5 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/5 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found 0 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/0 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found 0B43ACD5C4 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_active_feed: queue incoming Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: dir_forest: 0B43ACD5C4 -> 0/ Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_active_feed: incoming/0/0B43ACD5C4 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: dir_forest: 0B43ACD5C4 -> 0/ Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: dir_forest: 0B43ACD5C4 -> 0/ Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_message_alloc: active 0B43ACD5C4 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: dir_forest: 0B43ACD5C4 -> 0/ @@Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: record C 333 145 1 0 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: record T 1153338471 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: record A message_origin=local Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: record F www-data Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: record S www-data@mail.raphaelharoche.net Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: 0B43ACD5C4: from=, size=333, nrcpt=1 (queue active) Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: record M Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: record X Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: record O david.legros@gmail.com Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: record R david.legros@gmail.com Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: record E Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: dir_forest: 0B43ACD5C4 -> 0/ Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: start sorted recipient list Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_message_sort: david.legros@gmail.com Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: end sorted recipient list Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: connect to subsystem private/rewrite Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr request = resolve Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr address = david.legros@gmail.com Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: private/rewrite socket: wanted attribute: transport Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: input attribute name: transport Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: input attribute value: smtp Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: private/rewrite socket: wanted attribute: nexthop Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: input attribute name: nexthop Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: input attribute value: gmail.com Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: private/rewrite socket: wanted attribute: recipient Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: input attribute name: recipient Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: input attribute value: david.legros@gmail.com Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: private/rewrite socket: wanted attribute: flags Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: input attribute name: flags Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: input attribute value: 4096 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: private/rewrite socket: wanted attribute: (list terminator) Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: input attribute name: (end) Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: resolve_clnt: `david.legros@gmail.com' -> transp=`smtp' host=`gmail.com' rcpt=`david.legros@gmail.com' flags= class=default Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: start sorted recipient list Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_message_sort: david.legros@gmail.com Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: end sorted recipient list Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: mail_flow_put: 1 1 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: watchdog_start: 0x805e3a0 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_transport_select: smtp Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_active_drain: allocate smtp Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: connect to subsystem private/smtp Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/0 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found B Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/B Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/B Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found 7 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/7 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/7 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: found F Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_push: open incoming/F Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip . Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_next: skip .. Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming/F Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: scan_dir_pop: close incoming Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: done incoming queue scan Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: watchdog_start: 0x805e3a0 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: transport_event: smtp Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: private/smtp socket: wanted attribute: status Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: input attribute name: status Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: input attribute value: 0 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: private/smtp socket: wanted attribute: (list terminator) Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: input attribute name: (end) Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_peer_select: 0B43ACD5C4 smtp gmail.com (1 of 5) Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_job_retire: 0B43ACD5C4 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr flags = 3 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr queue_name = active Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr queue_id = 0B43ACD5C4 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr offset = 145 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr size = 333 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr nexthop = gmail.com Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr encoding = Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr sender = www-data@mail.raphaelharoche.net Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr errors-to = www-data@mail.raphaelharoche.net Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr return-receipt = Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr time = 1153338471 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr client_name = Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr client_address = Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr protocol_name = Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr helo_name = Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr offset = 504 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr original_recipient = david.legros@gmail.com Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr recipient = david.legros@gmail.com Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: send attr offset = 0 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_deliver: site `gmail.com' Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: watchdog_start: 0x805e3a0 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: private/smtp socket: wanted attribute: reason Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: warning: premature end-of-input on private/smtp socket while reading input attribute name Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: warning: private/smtp socket: malformed response Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_transport_throttle: transport smtp: reason: unknown mail transport error Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: defer transport smtp: unknown mail transport error Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: defer site gmail.com: unknown mail transport error Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_transport_unthrottle: transport smtp Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_queue_unthrottle: queue gmail.com Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_active_done: 0B43ACD5C4 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: dir_forest: 0B43ACD5C4 -> 0/ Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: dir_forest: 0B43ACD5C4 -> 0/ Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: wakeup 0B43ACD5C4 after 1000 secs Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: dir_forest: 0B43ACD5C4 -> 0/ Jul 19 21:47:51 sd1528 last message repeated 2 times Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_active_defer: defer 0B43ACD5C4 Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: qmgr_job_free: 0B43ACD5C4 smtp Jul 19 21:47:51 sd1528 postfix/qmgr[11909]: watchdog_start: 0x805e3a0 Jul 19 21:47:51 sd1528 postfix/master[5771]: warning: process /usr/lib/postfix/smtp pid 12044 exit status 127 Jul 19 21:47:51 sd1528 postfix/master[5771]: warning: /usr/lib/postfix/smtp: bad command startup -- throttling