| Author |
|
djangodude Newbie

Joined: 08 November 2011 Location: United States
Online Status: Offline Posts: 7
|
| Posted: 24 January 2012 at 10:34am | IP Logged
|
|
|
I'm getting a lot of these and I don't understand why. I'm using the WPI with the
latest available Zoo, with default web.config, etc. as generated by the
"BlankDjangoProject" WPI running through IIS 7.5/Windows Server 2008 R2
I like that the newer Zoo error files contain much more info than before, but it
doesn't help me to understand what is going wrong, and what I might do to fix
the situation.
In most cases, upon encountering one of these, a simple refresh will set things
right, but they occur pretty frequently...not really suitable for release.
Here's an example:
Code:
500. Error in Helicon Zoo module or application configuration
ZooApplication Asynchronous IO Error.
To enable logging please specify ERROR LOG variable read more
Application Configuration is:
appName django.project.x64
appFullPath C:\python27\python.exe
appDirectory C:\djangoapps
appArguments -O C:\Zoo\Workers\python\zoofcgi.py
transportName NamedPipe
protocol fastcgi
scriptProcessor python.2.7.pipe
errorLog
postCacheDir
watchFileChangesMask
host 127.0.0.1
port %PORT%
minInstances 1
maxInstances 1
minRequestsPerWorker 3
maxRequestsPerWorker 15
portLower 49152
portUpper 65535
workerRequestTimeout 30
timeLimit 0
memoryLimit 0
gracefulShutdownTimeout 0
Enviroment Variables
APPL_PHYSICAL_PATH C:\djangoapps
APPL_VIRTUAL_PATH
DEPLOY_FILE deploy.py
DEPLOY_LOG log\deploy.log
DJANGO_SETTINGS_MODULE settings
HOST 127.0.0.1
PORT %PORT%
PYTHONPATH
C:\djangoapps;C:\djangoapps\python_modules\Lib\site-
packages;%PYTHONPATH%
django.root \
JobFastCgi:[/myapp/test/] _requestId:16, BACKEND_READ_COMPLETE,
POST_MODE_BUFFER
|
|
|
|
| Back to Top |
|
| |
jhowson Newbie

Joined: 06 February 2012 Location: United States
Online Status: Offline Posts: 7
|
| Posted: 06 February 2012 at 4:58pm | IP Logged
|
|
|
Hi,
I am also receiving this exact error on two different
test machines. One is running Windows 2008\IIS 7.0 and
the other is running Windows 2008 R2\IIS 7.5. I would
like to get this problem worked out before moving to the
more permanent and production site.
I have noticed that this problem occurs much more
frequently after the website has been idling for an
extended period of time (e.g. 4+ hours).
I can provide the full error if necessary but it is
identical to djangodude's except obviously the Path
values.
|
| Back to Top |
|
| |
Anton Admin Group

Joined: 30 January 2007 Location: Ukraine
Online Status: Offline Posts: 10520
|
| Posted: 07 February 2012 at 3:24am | IP Logged
|
|
|
Hello, guys
First, please upgrade to the latest build available - it shows more verbose logs which is helpful.
And then please provide the logs.
__________________ Regards,
Anton
|
| Back to Top |
|
| |
jhowson Newbie

Joined: 06 February 2012 Location: United States
Online Status: Offline Posts: 7
|
| Posted: 07 February 2012 at 3:50pm | IP Logged
|
|
|
Anton,
I updated the Helicon Zoo module on our server. We are
now running 1.0.0031. The updated version does seem to
be preforming better as the pages are loading quicker.
However, I did receive the 500 error again. Here is the
information
Code:
500. Error in Helicon Zoo module or
application configuration
TransportError: ZooApplication Asynchronous IO
Error. GetLastError = The I/O operation has been aborted
because of either a thread exit or an application
request. dwError=995
You can find more debug information in C:\TEMP\
Application Configuration
appName django.project.x86
appFullPath C:\python27\python.exe
appDirectory D:\OSQA_SITE
appArguments -O
C:\Zoo\Workers\python\zoofcgi.py
transportName NamedPipe
protocol fastcgi
scriptProcessor python.2.7.pipe
errorLogDir C:\TEMP\
postBufferDir
watchFileChangesMask
host 127.0.0.1
port %PORT%
minInstances 1
maxInstances 1
minRequestsPerWorker 3
maxRequestsPerWorker 15
portLower 49152
portUpper 65535
workerRequestTimeout 30
timeLimit 0
postBuffer 1024
memoryLimit 0
gracefulShutdownTimeout 0
Enviroment Variables
APPL_PHYSICAL_PATH D:\OSQA_SITE
APPL_VIRTUAL_PATH
DEPLOY_FILE deploy.py
DEPLOY_LOG log\deploy.log
DJANGO_SETTINGS_MODULE settings
HOST 127.0.0.1
PORT %PORT%
PYTHONPATH
D:\OSQA_SITE;D:\OSQA_SITE\python_modules\Lib\site-
packages;%PYTHONPATH%
django.root \
JobFastCgi:[/questions/] _requestId:2,
BACKEND_READ_COMPLETE, POST_MODE_PARTIAL
Internal info
file: ZooApplication.cpp
line: 907
To get additional help please visit
http://support.helicontech.com/ |
|
|
I looked in the C:\Temp location for any additional logs
but the files there were empty.
Let me know if you want any additional information or if
you want me to change any settings.
UPDATE: Just wanted to note that I have been running this
OSQA site with 2 worker processes for the App Pool and
that this error *seems* to occur more frequently when
running more then one WP.
Regards,
Jason
Edited by jhowson - 07 February 2012 at 4:02pm
|
| Back to Top |
|
| |
Anton Admin Group

Joined: 30 January 2007 Location: Ukraine
Online Status: Offline Posts: 10520
|
| Posted: 08 February 2012 at 3:48am | IP Logged
|
|
|
Hello, Jason
Please add the following piece of code into the web.config for the site:
<environmentVariables>
<add name="ERROR_LOG_DIR" value="logs" />
</environmentVariables>
After that the /logs/ folder under the site will catch the logs. After the error occurs, please send us all the logs you have there.
__________________ Regards,
Anton
|
| Back to Top |
|
| |
jhowson Newbie

Joined: 06 February 2012 Location: United States
Online Status: Offline Posts: 7
|
| Posted: 08 February 2012 at 2:31pm | IP Logged
|
|
|
Hi Anton,
I added the "ERROR_LOG_DIR" environment variable and
created the "logs" folder in my site area. Several files
were created in this folder with the names like
"django.project.x86 [2012_02_08 14_15_59] worker-0.log".
However, these files are empty (0 KB) and there are no
other logs here even after the error occurs.
Do I need to do anything else to enable more logging? Do
I need to switch to the "python.2.7.debug" script
processor to enable more logging?
Regards,
Jason
|
| Back to Top |
|
| |
Sergey Admin Group

Joined: 11 March 2005
Online Status: Offline Posts: 750
|
| Posted: 09 February 2012 at 11:10am | IP Logged
|
|
|
Hello, Jason
with empty log files we can't guess what happened
1. please check windows application error log, and send it to us
2. switch to python.2.7.debug engine and check C:\Zoo\Workers\python\__error.log
3. if possible provide us with RDP or send zipped copy of your site. (http://support.helicontech.com/helpdesk/)
|
| Back to Top |
|
| |
djangodude Newbie

Joined: 08 November 2011 Location: United States
Online Status: Offline Posts: 7
|
| Posted: 14 February 2012 at 2:54pm | IP Logged
|
|
|
Hey guys,
Sorry I have not responded. I've just enabled the ERROR_LOG_DIR variable
and am waiting for something to happen :-)
After Jason mentioned "this problem occurs much more frequently after
the website has been idling for an extended period of time (e.g. 4+
hours)", it made me think a little bit about my setup, and for me it seems
like it happens more frequently after IIS Application Pool
recycling...unfortunately I don't have any data to back that up but will see
if I can gather anything on that, in addition to the logs.
Also, Sergey, you mentioned, "switch to python.2.7.debug engine"; how is
this accomplished?
|
| Back to Top |
|
| |
jhowson Newbie

Joined: 06 February 2012 Location: United States
Online Status: Offline Posts: 7
|
| Posted: 14 February 2012 at 3:46pm | IP Logged
|
|
|
I hope you have better results then me with the ERROR_LOG_DIR variable.
As for the "python.2.7.debug engine", this is set in the web.config file in the root folder for your website
code. In the file you should have two handlers (django.project.x86 and django.project.x64) and each as a
scriptProcessor attribute. That is what you need to set to python.2.7.debug.
Regards,
Jason
Edited by jhowson - 14 February 2012 at 3:47pm
|
| Back to Top |
|
| |
djangodude Newbie

Joined: 08 November 2011 Location: United States
Online Status: Offline Posts: 7
|
| Posted: 14 February 2012 at 4:37pm | IP Logged
|
|
|
Cool, thanks for the info. I'll see how it goes and give that a whack.
|
| Back to Top |
|
| |
jhowson Newbie

Joined: 06 February 2012 Location: United States
Online Status: Offline Posts: 7
|
| Posted: 22 February 2012 at 4:34pm | IP Logged
|
|
|
Hello again,
Sorry for the long silence but some other things came up. I have not had any luck getting the files in the ERROR_LOG_DIR working. I did
some digging through all the files that had changes during an error and found this in a file called "ZooError.log" in my website's root
directory.
Here is the error from it at the time of one of the 500 Errors:
Code:
[2/22/2012 10:35:07] ForceShutdown
[2/22/2012 10:35:07] ZooApplication Asynchronous IO Error:
JobFastCgi:[/] _requestId:2, BACKEND_READ_COMPLETE, POST_MODE_PARTIAL
[2/22/2012 10:35:07] Can't create transport. <h2>WinApiError: Can't create
log file D:\OSQA_SITE\logs\django.project.x86 [2012_02_22 10_35_07] worker-0.log</h2>
The process cannot access the file because it is being used by another process.
dwError=32
<h3>Internal info</h3>
<pre>file: LogFile.cpp
line: 33
</pre>
|
|
|
I am not sure what other process would be accessing these files since they are generated by the Helicon Zoo module but there are several of
these errors in this file. I figured that since this was related to the empty error logs that I would provide this. Not sure if it helps
or not.
As for the other items you asked for, here are the two entries from the Application Event Log when the error occurred. The first is the
error and the second appears to be an accompanying information message.
Error
Code:
Log Name: Application
Source: Application Error
Date: 2/22/2012 4:20:51 PM
Event ID: 1000
Task Category: (100)
Level: Error
Keywords: Classic
User: N/A
Computer: US1153DEV08
Description:
Faulting application name: python.exe, version: 0.0.0.0, time stamp: 0x4df4ba7c
Faulting module name: ole32.dll, version: 6.1.7601.17514, time stamp: 0x4ce7b96f
Exception code: 0xc0000005
Fault offset: 0x00013d5d
Faulting process id: 0xf48
Faulting application start time: 0x01ccf1b0144aaf06
Faulting application path: C:\python27\python.exe
Faulting module path: C:\Windows\syswow64\ole32.dll
Report Id: 7a136b22-5da3-11e1-8ad9-005056980031
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
<System>
<Provider Name="Application Error" />
<EventID Qualifiers="0">1000</EventID>
<Level>2</Level>
<Task>100</Task>
<Keywords>0x80000000000000</Keywords>
<TimeCreated SystemTime="2012-02-22T22:20:51.000000000Z" />
<EventRecordID>1470929</EventRecordID>
<Channel>Application</Channel>
<Computer>US1153DEV08</Computer>
<Security />
</System>
<EventData>
<Data>python.exe</Data>
<Data>0.0.0.0</Data>
<Data>4df4ba7c</Data>
<Data>ole32.dll</Data>
<Data>6.1.7601.17514</Data>
<Data>4ce7b96f</Data>
<Data>c0000005</Data>
<Data>00013d5d</Data>
<Data>f48</Data>
<Data>01ccf1b0144aaf06</Data>
<Data>C:\python27\python.exe</Data>
<Data>C:\Windows\syswow64\ole32.dll</Data>
<Data>7a136b22-5da3-11e1-8ad9-005056980031</Data>
</EventData>
</Event>
|
|
|
Information
Code:
Log Name: Application
Source: Windows Error Reporting
Date: 2/22/2012 4:21:03 PM
Event ID: 1001
Task Category: None
Level: Information
Keywords: Classic
User: N/A
Computer: US1153DEV08
Description:
Fault bucket , type 0
Event Name: APPCRASH
Response: Not available
Cab Id: 0
Problem signature:
P1: python.exe
P2: 0.0.0.0
P3: 4df4ba7c
P4: ole32.dll
P5: 6.1.7601.17514
P6: 4ce7b96f
P7: c0000005
P8: 00013d5d
P9:
P10:
Attached files:
These files may be available here:
C:\ProgramData\Microsoft\Windows\WER\ReportQueue\AppCrash_python.exe_1f096ccd741413a61e2be0a4a316f058294dd5_16ec2041
Analysis symbol:
Rechecking for solution: 0
Report Id: 7a136b22-5da3-11e1-8ad9-005056980031
Report Status: 4
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
<System>
<Provider Name="Windows Error Reporting" />
<EventID Qualifiers="0">1001</EventID>
<Level>4</Level>
<Task>0</Task>
<Keywords>0x80000000000000</Keywords>
<TimeCreated SystemTime="2012-02-22T22:21:03.000000000Z" />
<EventRecordID>1470930</EventRecordID>
<Channel>Application</Channel>
<Computer>US1153DEV08</Computer>
<Security />
</System>
<EventData>
<Data>
</Data>
<Data>0</Data>
<Data>APPCRASH</Data>
<Data>Not available</Data>
<Data>0</Data>
<Data>python.exe</Data>
<Data>0.0.0.0</Data>
<Data>4df4ba7c</Data>
<Data>ole32.dll</Data>
<Data>6.1.7601.17514</Data>
<Data>4ce7b96f</Data>
<Data>c0000005</Data>
<Data>00013d5d</Data>
<Data>
</Data>
<Data>
</Data>
<Data>
</Data>
<Data>C:\ProgramData\Microsoft\Windows\WER\ReportQueue\AppCrash_python.exe_1f096ccd741413a61e2be0a4a316f058294dd5_16ec2041</Data>
<Data>
</Data>
<Data>0</Data>
<Data>7a136b22-5da3-11e1-8ad9-005056980031</Data>
<Data>4</Data>
</EventData>
</Event>
|
|
|
I also enabled the python.2.7.debug engine but the C:\Zoo\Workers\python\__error.log is also empty(0kB).
Regards,
Jason
|
| Back to Top |
|
| |
Anton Admin Group

Joined: 30 January 2007 Location: Ukraine
Online Status: Offline Posts: 10520
|
| Posted: 24 February 2012 at 2:47am | IP Logged
|
|
|
Please update to the latest version of Zoo, your first issue
"The process cannot access the file because it is being used by another process."
was fixed there
As for python process crash
"Faulting application name: python.exe, version: 0.0.0.0, time stamp: 0x4df4ba7c
Faulting module name: ole32.dll, version: 6.1.7601.17514, time stamp: 0x4ce7b96f"
please explain what python modules you use and why you need ole32.dll.
If possible, remote access would be perfect.
__________________ Regards,
Anton
|
| Back to Top |
|
| |
jhowson Newbie

Joined: 06 February 2012 Location: United States
Online Status: Offline Posts: 7
|
| Posted: 02 March 2012 at 5:23pm | IP Logged
|
|
|
Sorry about the delay.
I updated all our machines to use the latest Helicon Zoo module. The crash/error still happens but is harder to reproduce on demand now
which is definitely a good thing. I am still getting the "The process cannot access the file because it is being used by another
process." error and am still not getting any logs with data in the ERROR_LOG_DIR folder. I am quite certain that the setup hates me and
refuses to given me any data.
As for the Python modules, here is the list of modules that we employ for our setup.
Django==1.3
django-mssql==1.0.1
flup==1.0.2
python-ldap==2.4.6
python-memcached==1.48
pywin32==216
setuptools==0.6.11
I do not know which one is using the "ole32.dll" or why.
On the remote access front, I do not think I can you guys direct access. All the servers that I have this running on are inside my
clients network which is heavily access controlled. It took me a couple years and a two factor auth VPN client to get access. That
said, if you want to try to do a web meeting and use a screen sharing app like WebEx or LiveMeeting to take a look at the machine I
would be willing.
|
| Back to Top |
|
| |
Anton Admin Group

Joined: 30 January 2007 Location: Ukraine
Online Status: Offline Posts: 10520
|
| Posted: 03 March 2012 at 4:11am | IP Logged
|
|
|
Thanks for your details.
Will it be possible for you to install DebugDiag tool on the machine where Zoo crashes?
If yes, we'll tell you how to adjust it properly to get the info we need.
The idea is that this tool must be running all the time before w3wp crash. Generally it won't hinder the performance, but at the moment of
crash it will create the dump of the process that might be a little slow and the site may be unavailable for about 5 minutes.
BTW, just to be sure, please check what version of this dll C:\Zoo\NativeModule\HeliconZoo_x64.dll you have.
__________________ Regards,
Anton
|
| Back to Top |
|
| |
jhowson Newbie

Joined: 06 February 2012 Location: United States
Online Status: Offline Posts: 7
|
| Posted: 06 March 2012 at 3:56pm | IP Logged
|
|
|
There shouldn't be any problem with installing DebugDiag. I assume v1.2? Please let me know what and how to adjust it to gather the information.
I am running version 1.0.0.34 on the machine. I updated the Zoo module yesterday.
|
| Back to Top |
|
| |
Anton Admin Group

Joined: 30 January 2007 Location: Ukraine
Online Status: Offline Posts: 10520
|
| Posted: 07 March 2012 at 3:31am | IP Logged
|
|
|
@jhowson, @djangodude:
Communicating on the forum becomes slightly inconvenient as it doesn't support attachments, so please go to our helpdesk
(http://support.helicontech.com/helpdesk/) and write a message with reference to this forum thread. I'll send you a new version and
instructions in response.
__________________ Regards,
Anton
|
| Back to Top |
|
| |
mitch Newbie

Joined: 13 February 2012 Location: Japan
Online Status: Offline Posts: 3
|
| Posted: 09 March 2012 at 10:06am | IP Logged
|
|
|
Hi,
I had the same problem when I was running my Django application with
Helicon Zoo and MS SQL Server.
But I don't think the error is due to Helicon Zoo because I also received the
same error when I was running my application not on Helicon Zoo but on Django's runserver.
I guess the error comes from a component of pywin32 to connect to MS SQL
Server. I'm not sure about the exact reason of the error, but the error is just
like the case in the following URL.
http://pastebin.com/Hgz7Pq48
Actually, the error is gone entirely from my application since I replaced the
MS SQL driver from django-mssql and pywin32 to django-pyodbc and
pyodbc.
Hope this helps.
Regards,
Mitch
|
| Back to Top |
|
| |
djangodude Newbie

Joined: 08 November 2011 Location: United States
Online Status: Offline Posts: 7
|
| Posted: 16 March 2012 at 1:42pm | IP Logged
|
|
|
mitch, thanks for this info...I'm using django-mssql/pywin32 so I suppose
that is suspicious. I'm not sure it's feasible to switch to pyodbc but if I get an
opening I will look into that.
I've got the debug stuff (thanks, Anton) but have not had the opportunity to
get it sorted out yet, will try to do that soon, too.
|
| Back to Top |
|
| |
djangodude Newbie

Joined: 08 November 2011 Location: United States
Online Status: Offline Posts: 7
|
| Posted: 13 June 2012 at 8:28am | IP Logged
|
|
|
At long last, I *think* it's safe to report that we have solved this problem,
thanks to Mitch's suggestion about django-mssql versus django-pyodbc. We
recently switched to pyodbc/django-pyodbc and have not had a single
ZooApplication Asynchronous I/O Error since. If anyone reading this is still
experiencing the problem, and you're using django-mssql, try switching to
django-pyodbc. You'll have to do some *minor* changes to your settings
file/DATABASES section, but it's really quite trivial. We've actually noticed
what appears to be a speed improvement after the switch.
Thanks again to Mitch!!
|
| Back to Top |
|
| |
immujeeb Newbie

Joined: 17 August 2012 Location: Pakistan
Online Status: Offline Posts: 1
|
| Posted: 17 August 2012 at 7:17am | IP Logged
|
|
|
i'm trying to install Redmine on my local machine.
Using Ms Web Platform, i installed
Helicon Zoo Module
Ruby 1.9 Development Kit
Ruby 1.9
Ruby 1.8
Thin
Ruby on Rail 3
Gave all the rights to Redmine and Ruby 18 and Ruby 19
folder. But when i try to access the url it give me
error.
Here's the log:
config.gem: Unpacked gem i18n-0.4.2 in vendor/gems has no
specification file. Run 'rake gems:refresh_specs' to fix
this.
config.gem: Unpacked gem rack-1.1.2 in vendor/gems has no
specification file. Run 'rake gems:refresh_specs' to fix
this.
config.gem: Unpacked gem rake-0.8.7 in vendor/gems has no
specification file. Run 'rake gems:refresh_specs' to fix
this.
config.gem: Unpacked gem sqlite3-1.3.4 in vendor/gems has
no specification file. Run 'rake gems:refresh_specs' to
fix this.
[tid-1681296] A key is required to write a cookie
containing the session data. Use
config.action_controller.session = { :key =>
"_myapp_session", :secret => "some secret phrase" } in
config/environment.rb (ArgumentError)
D:/CurrentProjects/Redmine/vendor/rails/actionpac
k/lib/action_controller/session/cookie_store.rb:178:in
`ensure_session_key'
D:/CurrentProjects/Redmine/vendor/rails/actionpac
k/lib/action_controller/session/cookie_store.rb:81:in
`initialize'
D:/CurrentProjects/Redmine/vendor/rails/actionpac
k/lib/action_controller/middleware_stack.rb:72:in `new'
D:/CurrentProjects/Redmine/vendor/rails/actionpac
k/lib/action_controller/middleware_stack.rb:72:in `build'
D:/CurrentProjects/Redmine/vendor/rails/actionpac
k/lib/action_controller/middleware_stack.rb:116:in
`build'
D:/CurrentProjects/Redmine/vendor/rails/activesup
port/lib/active_support/inflector.rb:364:in `inject'
D:/CurrentProjects/Redmine/vendor/rails/actionpac
k/lib/action_controller/middleware_stack.rb:116:in `each'
D:/CurrentProjects/Redmine/vendor/rails/actionpac
k/lib/action_controller/middleware_stack.rb:116:in
`inject'
D:/CurrentProjects/Redmine/vendor/rails/actionpac
k/lib/action_controller/middleware_stack.rb:116:in
`build'
D:/CurrentProjects/Redmine/vendor/rails/actionpac
k/lib/action_controller/dispatcher.rb:130:in
`build_middleware_stack'
D:/CurrentProjects/Redmine/vendor/rails/actionpac
k/lib/action_controller/dispatcher.rb:81:in `initialize'
D:/CurrentProjects/Redmine/vendor/rails/actionpac
k/lib/action_controller/dispatcher.rb:51:in `new'
D:/CurrentProjects/Redmine/vendor/rails/actionpac
k/lib/action_controller/dispatcher.rb:51:in
`run_prepare_callbacks'
D:\CurrentProjects\Redmine/config/../vendor/rails
/railties/lib/initializer.rb:631:in `prepare_dispatcher'
D:\CurrentProjects\Redmine/config/../vendor/rails
/railties/lib/initializer.rb:185:in `process'
D:\CurrentProjects\Redmine/config/../vendor/rails
/railties/lib/initializer.rb:113:in `send'
D:\CurrentProjects\Redmine/config/../vendor/rails
/railties/lib/initializer.rb:113:in `run'
D:\CurrentProjects\Redmine/config/environment.rb:
20
C:/Ruby18/lib/ruby/site_ruby/1.8/rubygems/custom_
require.rb:29:in `gem_original_require'
C:/Ruby18/lib/ruby/site_ruby/1.8/rubygems/custom_
require.rb:29:in `require'
C:/Zoo/Workers/ruby/lib/app.rb:28:in `build_app'
C:/Zoo/Workers/ruby/lib/app.rb:16:in `initialize'
C:/Zoo/Workers/ruby/lib/worker.rb:4:in `new'
C:/Zoo/Workers/ruby/lib/worker.rb:4:in
`initialize'
C:/Zoo/Workers/ruby/zoorack.rb:30:in `new'
C:/Zoo/Workers/ruby/zoorack.rb:30
Please help me in this..
thanks,
Mujeeb.
|
| Back to Top |
|
| |