Re: Finding why two UPDATES are send
Re: Finding why two UPDATES are send
- Subject: Re: Finding why two UPDATES are send
- From: Pascal Robert <email@hidden>
- Date: Thu, 22 Apr 2010 15:53:45 -0400
Le 10-04-22 à 15:50, Chuck Hill a écrit :
On Apr 22, 2010, at 12:41 PM, Pascal Robert wrote:
Setting
er.extensions.ERXAdaptorChannelDelegate.enabled=true
er.extensions.ERXAdaptorChannelDelegate.trace.milliSeconds.info=1
er.extensions.ERXAdaptorChannelDelegate.trace.entityMatchPattern
= .*
Should give you a stack trace of where the second update
originates.
Based on the delay, I'd guess a refresh of some sort from the
browser. Check your Ajax usage. Also override
dispatchRequest() in Application and log out the request.uri()
so that you can see the traffic coming in.
Ah yes, the SQL Server calls does happen in a WOLongResponsePage.
So I will check that out.
The two updates runs in the same request (eg, the URI didn't
change) :-/ In fact, they are running in the same transaction.
So, it's part of the same transaction, and it's timed only one time :
2010-04-22 15:36:37,811 [ERXNSLogLog4jBridge.java:46] DEBUG - ===
Begin Internal Transaction
2010-04-22 15:36:37,812 [ERXNSLogLog4jBridge.java:46] DEBUG -
evaluateExpression:
<com.webobjects.jdbcadaptor.MicrosoftSQLServerPlugIn
$MicrosoftSQLServerExpression: "UPDATE dbo.CommandesEcom SET
CommandeComplet = ? WHERE NoCommandeOracle = ?" withBindings:
1:true(commandeComplet), 2:6091(noCommandeOracle)>
The gap between log messages here is 0.5 seconds and note the
missing log message
"2010-04-22 15:36:38,324 [ERXEOAccessUtilities.java:1213] INFO -
"CommandesEcom"@8796244 expression took X ms: "
Or did you delete that by accident?
Didn't delete anything.
2010-04-22 15:36:38,319 [ERXNSLogLog4jBridge.java:46] DEBUG -
evaluateExpression:
<com.webobjects.jdbcadaptor.MicrosoftSQLServerPlugIn
$MicrosoftSQLServerExpression: "UPDATE dbo.CommandesEcom SET
CommandeComplet = ? WHERE NoCommandeOracle = ?" withBindings:
1:true(commandeComplet), 2:6091(noCommandeOracle)>
2010-04-22 15:36:38,324 [ERXEOAccessUtilities.java:1213] INFO -
"CommandesEcom"@8796244 expression took 5 ms: UPDATE
dbo.CommandesEcom SET CommandeComplet = ? WHERE NoCommandeOracle
= ? withBindings: 1:NULL[commandeComplet], 2:6091[noCommandeOracle]
That one was 0.005 ms not 0.5 like the first. Are you hitting a
timeout of some sort? Database returning an error and EOF
retrying? Is inheritance involved?
No inheritance. I'm crazy, but that much :-) I will try Mark's
debugging trick, and check with the accounting software vendor to see
if they can trace something.
2010-04-22 15:36:38,335 [ERXNSLogLog4jBridge.java:46] DEBUG - ===
Commit Internal Transaction
--
Chuck Hill Senior Consultant / VP Development
Practical WebObjects - for developers who want to increase their
overall knowledge of WebObjects or who are trying to solve specific
problems.
http://www.global-village.net/products/practical_webobjects
_______________________________________________
Do not post admin requests to the list. They will be ignored.
Webobjects-dev mailing list (email@hidden)
Help/Unsubscribe/Update your Subscription:
This email sent to email@hidden