2012-02-26 14:18:49,559 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:49] "POST /web/dataset/create HTTP/1.1" 200 - 2012-02-26 14:18:49,580 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:49] "POST /web/dataset/search_read HTTP/1.1" 200 - 2012-02-26 14:18:49,652 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:49] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:49,705 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for ""The quotation '%s' has been converted to a sales order."" 2012-02-26 14:18:49,735 2917 DEBUG ? openerp.addons.base.ir.ir_sequence: ir_sequence.get() and ir_sequence.get_id() are deprecated. Please use ir_sequence.next_by_code() or ir_sequence.next_by_id(). 2012-02-26 14:18:49,857 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for "'Delivery Order'" 2012-02-26 14:18:49,857 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for "'Reception'" 2012-02-26 14:18:49,857 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for "'Internal picking'" 2012-02-26 14:18:49,858 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for "'Document'" 2012-02-26 14:18:49,858 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for "' for the '" 2012-02-26 14:18:49,858 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for "'is scheduled %s.'" 2012-02-26 14:18:49,858 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for "'is ready to process.'" 2012-02-26 14:18:49,858 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for "'is cancelled.'" 2012-02-26 14:18:49,859 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for "'is done.'" 2012-02-26 14:18:49,859 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for "'is waiting.'" 2012-02-26 14:18:49,859 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for "'is in draft state.'" 2012-02-26 14:18:49,910 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:49] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:49,960 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:49] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:50,107 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:50] "POST /web/dataset/search_read HTTP/1.1" 200 - 2012-02-26 14:18:50,117 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:50] "POST /web/dataset/exec_workflow HTTP/1.1" 200 - 2012-02-26 14:18:50,239 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:50] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:50,251 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:50] "POST /web/dataset/call HTTP/1.1" 200 - 2012-02-26 14:18:50,393 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:50] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:50,405 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:50] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:50,432 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:50] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:50,588 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:50] "POST /web/dataset/search_read HTTP/1.1" 200 - 2012-02-26 14:18:52,954 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for "'Free Reference'" 2012-02-26 14:18:53,125 2917 DEBUG ? openerp.report.render.rml2pdf.trml2pdf: Image is 453x107 2012-02-26 14:18:53,140 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for ""Invoice '%s' is waiting for validation."" 2012-02-26 14:18:53,384 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:53] "POST /web/dataset/call_button HTTP/1.1" 200 - 2012-02-26 14:18:53,492 2917 ERROR ? addons.document.content_index: Could not index file SO012.pdf (None) Traceback (most recent call last): File "/opt/openerp/server/openerp/addons/document/content_index.py", line 182, in doIndex res = (mime, fobj.indexContent(content,filename,fname or realfname) ) File "/opt/openerp/server/openerp/addons/document/content_index.py", line 90, in indexContent res = self._doIndexFile(rfname) File "/opt/openerp/server/openerp/addons/document/std_index.py", line 168, in _doIndexFile pop = Popen(['pdftotext', '-enc', 'UTF-8', '-nopgbrk', fname, '-'], shell=False, stdout=PIPE) File "/usr/lib/python2.6/subprocess.py", line 633, in __init__ errread, errwrite) File "/usr/lib/python2.6/subprocess.py", line 1139, in _execute_child raise child_exception OSError: [Errno 2] No such file or directory 2012-02-26 14:18:53,508 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:53] "POST /web/session/eval_domain_and_context HTTP/1.1" 200 - 2012-02-26 14:18:54,064 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:54] "POST /web/searchview/load HTTP/1.1" 200 - 2012-02-26 14:18:54,201 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:54] "POST /web/searchview/get_filters HTTP/1.1" 200 - 2012-02-26 14:18:54,282 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:54] "POST /web/searchview/fields_get HTTP/1.1" 200 - 2012-02-26 14:18:54,359 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:54] "POST /web/view/load HTTP/1.1" 200 - 2012-02-26 14:18:54,367 2917 INFO ? edi: EDI export successful for sale.order #12, email notification sent. 2012-02-26 14:18:54,568 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:54] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:54,683 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:54] "POST /web/listview/load HTTP/1.1" 200 - 2012-02-26 14:18:54,727 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:54] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:54,788 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:54] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:54,819 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:54] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:54,980 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:54] "POST /web/dataset/search_read HTTP/1.1" 200 - 2012-02-26 14:18:56,796 2917 DEBUG ? openerp.tools.translate: no translation language detected, skipping translation for ""Invoice '%s' is validated."" 2012-02-26 14:18:56,823 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:56] "POST /web/dataset/exec_workflow HTTP/1.1" 200 - 2012-02-26 14:18:56,897 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:56] "POST /web/dataset/call HTTP/1.1" 200 - 2012-02-26 14:18:56,916 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:56] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:57,047 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:57] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:57,065 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:57] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:57,075 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:57] "POST /web/dataset/get HTTP/1.1" 200 - 2012-02-26 14:18:57,204 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:57] "POST /web/dataset/search_read HTTP/1.1" 200 - 2012-02-26 14:18:57,254 2917 INFO ? werkzeug: 192.168.2.100 - - [26/Feb/2012 14:18:57] "POST /web/listview/load HTTP/1.1" 200 - 2012-02-26 14:18:59,997 2917 DEBUG ? openerp.report.render.rml2pdf.trml2pdf: Image is 453x107 2012-02-26 14:19:00,040 2917 DEBUG ? openerp.cron: Database 'start' wake-up! Firing multi-threaded cron job processing 2012-02-26 14:19:00,052 2917 DEBUG ? openerp.addons.base.ir.ir_cron: Cron execution thread for job `Email Queue Manager` spawned 2012-02-26 14:19:00,054 2917 DEBUG ? openerp.cron: Wake-up scheduled for database 'start' @ 1330265951 2012-02-26 14:19:00,057 2917 DEBUG ? openerp.addons.base.ir.ir_cron: cron.object.execute('start', 1, '*', u'mail.message', u'process_email_queue') 2012-02-26 14:19:00,058 2917 DEBUG ? openerp.cron: Going to sleep for 10.9415268898s 2012-02-26 14:19:00,091 2917 ERROR ? mail: failed sending mail.message 27 Traceback (most recent call last): File "/opt/openerp/server/openerp/addons/mail/mail_message.py", line 511, in send headers=message.headers and ast.literal_eval(message.headers)) File "/opt/openerp/server/openerp/addons/base/ir/ir_mail_server.py", line 270, in build_email assert email_from, "You must either provide a sender address explicitly or configure "\ AssertionError: You must either provide a sender address explicitly or configure a global sender address in the server configuration or with the --email-from startup parameter. 2012-02-26 14:19:00,129 2917 DEBUG ? openerp.addons.base.ir.ir_cron: 0.059s (mail.message, process_email_queue) 2012-02-26 14:19:00,233 2917 ERROR ? addons.document.content_index: Could not index file INVVK20120012.pdf.pdf (None) Traceback (most recent call last): File "/opt/openerp/server/openerp/addons/document/content_index.py", line 182, in doIndex res = (mime, fobj.indexContent(content,filename,fname or realfname) ) File "/opt/openerp/server/openerp/addons/document/content_index.py", line 90, in indexContent res = self._doIndexFile(rfname) File "/opt/openerp/server/openerp/addons/document/std_index.py", line 168, in _doIndexFile pop = Popen(['pdftotext', '-enc', 'UTF-8', '-nopgbrk', fname, '-'], shell=False, stdout=PIPE) File "/usr/lib/python2.6/subprocess.py", line 633, in __init__ errread, errwrite) File "/usr/lib/python2.6/subprocess.py", line 1139, in _execute_child raise child_exception OSError: [Errno 2] No such file or directory 2012-02-26 14:19:00,729 2917 DEBUG ? openerp.report.render.rml2pdf.trml2pdf: Image is 453x107 2012-02-26 14:19:00,880 2917 ERROR ? addons.document.content_index: Could not index file INVVK20120012.pdf.pdf (None) Traceback (most recent call last): File "/opt/openerp/server/openerp/addons/document/content_index.py", line 182, in doIndex res = (mime, fobj.indexContent(content,filename,fname or realfname) ) File "/opt/openerp/server/openerp/addons/document/content_index.py", line 90, in indexContent res = self._doIndexFile(rfname) File "/opt/openerp/server/openerp/addons/document/std_index.py", line 168, in _doIndexFile pop = Popen(['pdftotext', '-enc', 'UTF-8', '-nopgbrk', fname, '-'], shell=False, stdout=PIPE) File "/usr/lib/python2.6/subprocess.py", line 633, in __init__ errread, errwrite) File "/usr/lib/python2.6/subprocess.py", line 1139, in _execute_child raise child_exception OSError: [Errno 2] No such file or directory 2012-02-26 14:19:01,045 2917 ERROR ? addons.document.content_index: Could not index file Invoice.pdf (None) Traceback (most recent call last): File "/opt/openerp/server/openerp/addons/document/content_index.py", line 182, in doIndex res = (mime, fobj.indexContent(content,filename,fname or realfname) ) File "/opt/openerp/server/openerp/addons/document/content_index.py", line 90, in indexContent res = self._doIndexFile(rfname) File "/opt/openerp/server/openerp/addons/document/std_index.py", line 168, in _doIndexFile pop = Popen(['pdftotext', '-enc', 'UTF-8', '-nopgbrk', fname, '-'], shell=False, stdout=PIPE) File "/usr/lib/python2.6/subprocess.py", line 633, in __init__ errread, errwrite) File "/usr/lib/python2.6/subprocess.py", line 1139, in _execute_child raise child_exception OSError: [Errno 2] No such file or directory 2012-02-26 14:19:01,072 2917 INFO ? edi: EDI export successful for account.invoice #13, email notification sent. 2012-02-26 14:19:11,024 2917 DEBUG ? openerp.cron: Database 'start' wake-up! Firing multi-threaded cron job processing 2012-02-26 14:19:11,054 2917 DEBUG ? openerp.addons.base.ir.ir_cron: Cron execution thread for job `Run Event Reminder` spawned 2012-02-26 14:19:11,058 2917 DEBUG ? openerp.cron: Wake-up scheduled for database 'start' @ 1330266000 2012-02-26 14:19:11,063 2917 DEBUG ? openerp.cron: Going to sleep for 48.9360840321s 2012-02-26 14:19:11,065 2917 DEBUG ? openerp.addons.base.ir.ir_cron: cron.object.execute('start', 1, '*', u'calendar.alarm', u'do_run_scheduler', False) 2012-02-26 14:19:11,069 2917 DEBUG ? openerp.addons.base.ir.ir_cron: 0.004s (calendar.alarm, do_run_scheduler) 2012-02-26 14:20:00,040 2917 DEBUG ? openerp.cron: Database 'start' wake-up! Firing multi-threaded cron job processing 2012-02-26 14:20:00,054 2917 DEBUG ? openerp.addons.base.ir.ir_cron: Cron execution thread for job `Email Queue Manager` spawned 2012-02-26 14:20:00,056 2917 DEBUG ? openerp.cron: Wake-up scheduled for database 'start' @ 1330266011 2012-02-26 14:20:00,057 2917 DEBUG ? openerp.cron: Going to sleep for 10.9422619343s 2012-02-26 14:20:00,059 2917 DEBUG ? openerp.addons.base.ir.ir_cron: cron.object.execute('start', 1, '*', u'mail.message', u'process_email_queue') 2012-02-26 14:20:02,131 2917 DEBUG ? openerp.addons.base.ir.ir_cron: 2.072s (mail.message, process_email_queue) 2012-02-26 14:20:11,017 2917 DEBUG ? openerp.cron: Database 'start' wake-up! Firing multi-threaded cron job processing 2012-02-26 14:20:11,041 2917 DEBUG ? openerp.addons.base.ir.ir_cron: Cron execution thread for job `Run Event Reminder` spawned 2012-02-26 14:20:11,047 2917 DEBUG ? openerp.cron: Wake-up scheduled for database 'start' @ 1330266060 2012-02-26 14:20:11,049 2917 DEBUG ? openerp.addons.base.ir.ir_cron: cron.object.execute('start', 1, '*', u'calendar.alarm', u'do_run_scheduler', False) 2012-02-26 14:20:11,056 2917 DEBUG ? openerp.cron: Going to sleep for 48.943969965s 2012-02-26 14:20:11,057 2917 DEBUG ? openerp.addons.base.ir.ir_cron: 0.008s (calendar.alarm, do_run_scheduler)