poor progress indication during inter-format fetch
Affects | Status | Importance | Assigned to | Milestone | |
---|---|---|---|---|---|
Bazaar |
Confirmed
|
Medium
|
Unassigned |
Bug Description
During a merge I am currently attempting, the progress bar is not showing any progress (even though the Python process is at near 100% CPU).
This is what I'm doing:
~/Projects/
[| ] Transferring:
I'm including partial output of fs_usage below, showing that it seems to be accessing the same file over and over again.
I then interrupted the merge; the stack trace is shown below.
18:54:52 read 0.000008 Python
18:54:52 close 0.000014 Python
18:54:55 open nd/emacs-
18:54:55 fstat 0.000005 Python
18:54:55 lseek 0.000002 Python
18:54:55 lseek 0.000001 Python
18:54:55 read 0.000016 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000008 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000008 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000006 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000007 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000006 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000005 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000007 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000006 Python
18:54:55 close 0.000012 Python
18:54:55 open nd/emacs-
18:54:55 fstat 0.000006 Python
18:54:55 lseek 0.000002 Python
18:54:55 lseek 0.000001 Python
18:54:55 read 0.000022 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000008 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000008 Python
18:54:55 read 0.000007 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000008 Python
18:54:55 read 0.000007 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000007 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000009 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000009 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000008 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000007 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000006 Python
18:54:55 read 0.000008 Python
18:54:55 lseek 0.000002 Python
18:54:55 read 0.000007 Python
Sat 2009-03-21 18:46:52 -0400
0.039 bzr arguments: [u'merge', u'../..
0.062 looking for plugins in /Users/
0.192 looking for plugins in /Library/
0.192 Plugin name loom already loaded
0.192 Plugin name rebase already loaded
0.487 opening working tree '/Users/
1.137 Using fetch logic to copy between KnitPackReposit
1.138 fetch up to rev {cvs-1:
718.235 Traceback (most recent call last):
File "/Library/
return the_callable(*args, **kwargs)
File "/Library/
ret = run(*run_argv)
File "/Library/
return self.run(
File "/Library/
return bzrlib.
File "/Library/
location, revision, remember, possible_
File "/Library/
other_
File "/Library/
merger.
File "/Library/
self.
File "/Library/
target.
File "/Library/
result = unbound(self, *args, **kwargs)
File "/Library/
pb=nested_pb)
File "/Library/
find_
File "/Library/
pb=pb, find_ghosts=
File "/Library/
self.__fetch()
File "/Library/
self.
File "/Library/
stream, from_format, [])
File "/Library/
return self._locked_
File "/Library/
for substream_type, substream in stream:
File "/Library/
for _ in self._generate_
File "/Library/
self.
File "/Library/
revs, parent_map, graph)
File "/Library/
for tree in self.iter_
File "/Library/
for tree in self.source.
File "/Library/
for inv in inventories:
File "/Library/
yield self.deserialis
File "/Library/
entry_
File "/Library/
entry_
File "/Library/
ie = unpack_entry(e, entry_cache=
File "/Library/
return cached_ie.copy()
File "/Library/
other = InventoryFile(
File "/Library/
super(
KeyboardInterrupt
718.244 return code 3
summary: |
- merge: progress bar doesn't change for a long time + poor progress indication during inter-format fetch |
tags: | added: progress |
Changed in bzr: | |
importance: | Undecided → Medium |
status: | New → Confirmed |
tags: | added: fetch |
tags: | added: check-for-breezy |
I'd like to add another, probably related case.
I decided to merge just the latest revisions, that is, just 22 revisions:
~/Projects/ Bazaar- Playground/ import- from-cvs/ emacs$ bzr merge ../../emacs- dl/emacs/ trunk -r 96946.. -v Walking content. 96968/96968
[\ ] Transferring:
When it appears to be done "walking content", it basically appears to freeze. Again, it's working hard, but neither the progress bar nor anything else is changing.
The indication of completed revisions is misleading - obviously there is much more to "walking the content", because otherwise it'd be done by now.
(I also don't understand why merging 22 revisions is taking forever.)