Loading User Data Snafu

[David T.]

I am reinvigorating this thread because I am encountering the same problem under 2.4.0 sporadically. The log file has several megabytes of the following sort of entry in it:

* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x115247b0 set date to 1041321600.000000000 Tue Dec 31 00:00:00 2002
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x115247b0 set date to 1157752805.000000000 Fri Sep  8 15:00:05 2006
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x11524838
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x115249d0
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x11524a58 set date to 1049097600.000000000 Mon Mar 31 00:00:00 2003
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x11524a58 set date to 1157752805.000000000 Fri Sep  8 15:00:05 2006
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x115249d0
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x11524948
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x11524948 set date to 1049097600.000000000 Mon Mar 31 00:00:00 2003
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x11524948 set date to 1157752805.000000000 Fri Sep  8 15:00:05 2006
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x115249d0
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x115249d0
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x11524838 set date to 1049097600.000000000 Mon Mar 31 00:00:00 2003
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x11524838 set date to 1157752805.000000000 Fri Sep  8 15:00:05 2006
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x115249d0
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x11524b68
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x11524bf0 set date to 1049097600.000000000 Mon Mar 31 00:00:00 2003
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x11524bf0 set date to 1157752805.000000000 Fri Sep  8 15:00:05 2006
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x11524c78
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x11524d88
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x11524d88 set date to 1050908400.000000000 Mon Apr 21 00:00:00 2003
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x11524d88 set date to 1157752805.000000000 Fri Sep  8 15:00:05 2006
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x11524c78
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x11524b68
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x115249d0 set date to 1051772400.000000000 Thu May  1 00:00:00 2003
* 23:57:32  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x115249d0 set date to 1157752805.000000000 Fri Sep  8 15:00:05 2006
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x11524ae0
* 23:57:32  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x11524c78

The last one is:

* 00:01:05  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x143870a0 set date to 1128322800.000000000 Mon Oct  3 00:00:00 2005
* 00:01:05  INFO <gnc.engine> [xaccTransSetDateInternal] addr=0x143870a0 set date to 1157752806.000000000 Fri Sep  8 15:00:06 2006
* 00:01:05  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x14380f40
* 00:01:05  INFO <gnc.engine> [trans_cleanup_commit] get rid of rollback trans=0x14380e30

The timestamps show that these hold up the start by three and a half minutes. I'm not certain, but this seems like a lot of activity that is slowing things down. Does anyone know whether these are supposed to be here? Why are they running? 

I am using an XML data file.

David

--- On Tue, 12/7/10, John Ralls <jralls at ceridwen.us> wrote:

> From: John Ralls <jralls at ceridwen.us>
> Subject: Re: Loading User Data Snafu
> To: "David T." <sunfish62 at yahoo.com>
> Cc: "devel gnucash" <gnucash-devel at gnucash.org>
> Date: Tuesday, December 7, 2010, 10:09 PM
> 
> On Dec 7, 2010, at 9:32 PM, David T. wrote:
> 
> > Hi. I am running both 2.2.9 and 2.3.17 on my Mac (OS X
> 10.5.8) using the bundles from Sourceforge, and suddenly
> today I have found that the start up repeatedly gets bogged
> down at the Loading User Data prompt. This part is now
> taking several minutes, whereas before (yesterday), it was
> only taking 30 seconds or so. I have encountered this
> before, and Check & Repair tended to fix it, but not
> now. 
> > 
> > While I do have one report that opens on start up,
> this slow down is happening before the report is getting
> loaded. I have no idea why this is happening all of a
> sudden, and don't even know where to start looking for a
> fix.
> > 
> 
> Is that with both versions, and with XML?
> 
> (Assuming XML) if you load a backup file from a couple of
> days ago, does it load quickly? If so, you might be able to
> triage it by finding which is the first backup which loads
> slowly and then examining the corresponding log file to see
> what changed in your database. 
> 
> Regards,
> John Ralls
> 
> 
>