Page speed running on debian with mpm-worker + fcgi causes lots of errors written to apache log

[GoogleCodeExporter]

What steps will reproduce the problem?
1. Installed package, configured for with domain and turned on settings as 
below. 

What is the expected output? What do you see instead?
The /var/log/apache2/error.log filled up with this more than 11K lines per 
second.

[Thu Nov 04 09:28:50 2010] [error] [1104/092850:ERROR:net/instaweb/ 
apache/serf_url_async_fetcher.cc(674)] Poll success status 0 (111) 
[Thu Nov 04 09:28:50 2010] [error] [1104/092850:ERROR:net/instaweb/ 
apache/serf_url_async_fetcher.cc(674)] Poll success status 0 (111) 
[Thu Nov 04 09:28:50 2010] [error] [1104/092850:ERROR:net/instaweb/ 
apache/serf_url_async_fetcher.cc(674)] Poll success status 0 (111) 

What version of the product are you using? On what operating system?
1. Setup Lenny 32-bit + apache package 2.2.9 + MPM_worker + fcgid running php 
site 
2. Setup Pagespeed: ModPagespeedEnableFilters 
collapse_whitespace,rewrite_images,remove_comments 

Please provide any additional information below.

Original issue reported on code.google.com by john%pro...@gtempaccount.com on 3 Nov 2010 at 10:50

[GoogleCodeExporter]

Thanks for the report.

We have seen a few of these reports on mod-pagespeed-discuss and believe it is 
related to usage of the threaded MPM, which we have not yet tested.  Our 
initial offering is tested on the pre-fork MPM.

If you would like to help adapt mod_pagespeed to the Threaded MPM we'd love to 
work with you.

Original comment by jmara...@google.com on 4 Nov 2010 at 3:56

[GoogleCodeExporter]

I believe I have the same problem: I'm using Ubuntu 64 bit + MPM:

[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(35)] Could not 
create directories for file 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/templates/system/css/system.
css,
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/templates/system/css/system.
css,FEdVYr:0: open temp file (code=2 No such file or directory)
[Thu Nov 04 17:36:27 2010] [notice] 
[1104/173627:INFO:net/instaweb/apache/serf_url_async_fetcher.cc(684)] Fetch 
complete: http://www.zorgsaam.org/templates/system/css/system.css
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_time_duration_ms cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_bytes_count cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A:0: creating dir (code=13 Permission denied)
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(35)] Could not 
create directories for file 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/templates/system/css/general
.css,
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/templates/system/css/general
.css,OchlMV:0: open temp file (code=2 No such file or directory)
[Thu Nov 04 17:36:27 2010] [notice] 
[1104/173627:INFO:net/instaweb/apache/serf_url_async_fetcher.cc(684)] Fetch 
complete: http://www.zorgsaam.org/templates/system/css/general.css
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_time_duration_ms cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_bytes_count cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A:0: creating dir (code=13 Permission denied)
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(35)] Could not 
create directories for file 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/templates/zorgsaam_homepage/
css/template.css,
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/templates/zorgsaam_homepage/
css/template.css,gr1Szp:0: open temp file (code=2 No such file or directory)
[Thu Nov 04 17:36:27 2010] [notice] 
[1104/173627:INFO:net/instaweb/apache/serf_url_async_fetcher.cc(684)] Fetch 
complete: http://www.zorgsaam.org/templates/zorgsaam_homepage/css/template.css
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_time_duration_ms cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_bytes_count cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A:0: creating dir (code=13 Permission denied)
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(35)] Could not 
create directories for file 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/media/system/js/mootools.js,
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/media/system/js/mootools.js,
JJBInT:0: open temp file (code=2 No such file or directory)
[Thu Nov 04 17:36:27 2010] [notice] 
[1104/173627:INFO:net/instaweb/apache/serf_url_async_fetcher.cc(684)] Fetch 
complete: http://www.zorgsaam.org/media/system/js/mootools.js
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_time_duration_ms cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_bytes_count cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A:0: creating dir (code=13 Permission denied)
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(35)] Could not 
create directories for file 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/media/system/js/caption.js,
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/media/system/js/caption.js,S
GVFbn:0: open temp file (code=2 No such file or directory)
[Thu Nov 04 17:36:27 2010] [notice] 
[1104/173627:INFO:net/instaweb/apache/serf_url_async_fetcher.cc(684)] Fetch 
complete: http://www.zorgsaam.org/media/system/js/caption.js
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_time_duration_ms cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_bytes_count cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A:0: creating dir (code=13 Permission denied)
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(35)] Could not 
create directories for file 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/modules/mod_fhw_fontadjust/j
s/mod_fhw-fontadjust-px.js,
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/modules/mod_fhw_fontadjust/j
s/mod_fhw-fontadjust-px.js,7LhFZQ:0: open temp file (code=2 No such file or 
directory)
[Thu Nov 04 17:36:27 2010] [notice] 
[1104/173627:INFO:net/instaweb/apache/serf_url_async_fetcher.cc(684)] Fetch 
complete: 
http://www.zorgsaam.org/modules/mod_fhw_fontadjust/js/mod_fhw-fontadjust-px.js
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_time_duration_ms cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_bytes_count cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A:0: creating dir (code=13 Permission denied)
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(35)] Could not 
create directories for file 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/media/system/js/mootools.js,
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/media/system/js/mootools.js,
0uXYNk:0: open temp file (code=2 No such file or directory)
[Thu Nov 04 17:36:27 2010] [notice] 
[1104/173627:INFO:net/instaweb/apache/serf_url_async_fetcher.cc(684)] Fetch 
complete: http://www.zorgsaam.org/media/system/js/mootools.js
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_time_duration_ms cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_bytes_count cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A:0: creating dir (code=13 Permission denied)
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(35)] Could not 
create directories for file 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/media/system/js/caption.js,
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/media/system/js/caption.js,a
f2pCO:0: open temp file (code=2 No such file or directory)
[Thu Nov 04 17:36:27 2010] [notice] 
[1104/173627:INFO:net/instaweb/apache/serf_url_async_fetcher.cc(684)] Fetch 
complete: http://www.zorgsaam.org/media/system/js/caption.js
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_time_duration_ms cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_bytes_count cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A:0: creating dir (code=13 Permission denied)
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(35)] Could not 
create directories for file 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/templates/system/css/system.
css,
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(54)] 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/templates/system/css/system.
css,NBk1qi:0: open temp file (code=2 No such file or directory)
[Thu Nov 04 17:36:27 2010] [notice] 
[1104/173627:INFO:net/instaweb/apache/serf_url_async_fetcher.cc(684)] Fetch 
complete: http://www.zorgsaam.org/templates/system/css/system.css
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_time_duration_ms cannot lock mutex: Permission denied 
[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/apache/apr_statistics.cc(97)] Variable 
serf_fetch_bytes_count cannot lock mutex: Permission denied

Original comment by leonboga...@gmail.com on 4 Nov 2010 at 4:43

[GoogleCodeExporter]

Marking as Enhancement, to support previously unsupported threaded MPM.

Original comment by sligocki@google.com on 5 Nov 2010 at 3:02

• Added labels: Priority-High, Type-Enhancement
• Removed labels: Priority-Medium, Type-Defect

[GoogleCodeExporter]

I was not complete the last time: I'm using mpm-itk. It's based on the 
traditional prefork MPM: http://mpm-itk.sesse.net/

Original comment by leonboga...@gmail.com on 11 Nov 2010 at 8:53

[GoogleCodeExporter]

There are two unrelated issues here.

john.clegg.nz reports Poll success status 0 (111) which means "timeout 
requesting URL".  We've seen this elsewhere and need to track it down.

leonbogaert reports issues that look to me like the directories set up for 
mod_pagespeed to store cache files and other files are not writable.  Leon, can 
you investigate that?

So Leon, I think you have a setup issue.  The first error you reported was:

[Thu Nov 04 17:36:27 2010] [error] 
[1104/173627:ERROR:net/instaweb/util/google_message_handler.cc(35)] Could not 
create directories for file 
/var/mod_pagespeed/cache/http,3A/,/www.zorgsaam.org/templates/system/css/system.
css,

Can you take a look at whether your httpd server has write access to 
/var/mod_pagespeed/cache?  If not, you can edit pagespeed.conf to point to a 
location where you do have write access. 


And both of you: please be sure you are using our latest binary distribution, 
which was released Nov 8, 2010.

Could you verify by looking at the X-Mod-Pagespeed header in Firebug from your 
HTML file?

Original comment by jmara...@google.com on 11 Nov 2010 at 11:15

[GoogleCodeExporter]

Fixed in http://code.google.com/p/modpagespeed/source/detail?r=215

Original comment by jmara...@google.com on 17 Nov 2010 at 4:50

• Changed state: Fixed