[Monotone-devel] monotone 0.30 hangs when performing sync on certain databases

[Markus Meyer]

Hi everyone,

I have three databases, two of them working fine, but with one database,
monotone hangs while trying to sync using a file. This is with 0.30, but
also happened with 0.29. See the attachment for the full output when run
with --debug. I can open the remote database file just fine, so my
network is ok. Any ideas?


Markus

Y:\>mtn --debug -d cutcontrol.db sync 
file:/\\markus\markus\daten\Repositories\cutcontrol.db de.mesw.cutcontrol.init
mtn: started up on Windows NT/2000/XP/2003 (5.0, build 2195, none) on ia32 
(level 6, rev 2309)
mtn: command line: 'mtn', '--debug', '-d', 'cutcontrol.db', 'sync', 
'file:/\\markus\markus\daten\Repositories\cutcontrol.db', 
'de.mesw.cutcontrol.init'
mtn: set locale: LC_ALL=German_Germany.1252
mtn: initial abs path is: Y:
mtn: converting 7 bytes from ASCII to UTF-8
mtn: converting 2 bytes from ASCII to UTF-8
mtn: converting 13 bytes from ASCII to UTF-8
mtn: converting 4 bytes from ASCII to UTF-8
mtn: converting 54 bytes from ASCII to UTF-8
mtn: converting 23 bytes from ASCII to UTF-8
mtn: converting 3 bytes from ASCII to UTF-8
mtn: initializing from directory Y:/
mtn: searching for '_MTN' directory with root 'Y:/'
mtn: search for '_MTN' ended at 'Y:/' with '' removed
mtn: 'Y:/_MTN' does not exist
mtn: searching for 'MT' directory with root 'Y:/'
mtn: search for 'MT' ended at 'Y:/' with '' removed
mtn: 'Y:/MT' does not exist
mtn: opening rcfile 'C:/Dokumente und 
Einstellungen/Name/Anwendungsdaten/monotone/monotonerc'
mtn: 'C:/Dokumente und Einstellungen/Name/Anwendungsdaten/monotone/monotonerc' 
is ok
mtn: skipping nonexistent rcfile '_MTN/monotonerc'
mtn: executing command 'sync'
mtn: executing SQL 'SELECT sql FROM sqlite_master WHERE (type = 'table' OR type 
= 'index') AND sql IS NOT NULL AND name not like 'sqlite_stat%' ORDER BY name'
mtn: result: 0 (not an error)
mtn: prepared statement SELECT 1 FROM revisions LIMIT 1
mtn: binding 0 parameters for SELECT 1 FROM revisions LIMIT 1
mtn: prepared statement SELECT 1 FROM manifests LIMIT 1
mtn: binding 0 parameters for SELECT 1 FROM manifests LIMIT 1
mtn: prepared statement SELECT 1 FROM rosters LIMIT 1
mtn: binding 0 parameters for SELECT 1 FROM rosters LIMIT 1
mtn: prepared statement SELECT domain, name, value FROM db_vars
mtn: binding 0 parameters for SELECT domain, name, value FROM db_vars
mtn: checked_globish_to_regex: input = 'de.mesw.cutcontrol.init'
mtn: checked_globish_to_regex: output = 'de\.mesw\.cutcontrol\.init'
mtn: binding 0 parameters for SELECT domain, name, value FROM db_vars
mtn: binding 0 parameters for SELECT domain, name, value FROM db_vars
mtn: reading key dir 'C:/Dokumente und 
Einstellungen/Name/Anwendungsdaten/monotone/keys'
mtn: reading keys from file 'address@hidden'
mtn: read keypair data packet
mtn: reading key pair 'address@hidden' from key store
mtn: successfully read key pair 'address@hidden' from key store
mtn: prepared statement BEGIN EXCLUSIVE
mtn: binding 0 parameters for BEGIN EXCLUSIVE
mtn: connecting to file:/\\markus\markus\daten\Repositories\cutcontrol.db
mtn: matched URI scheme: 'file'
mtn: matched URI path: '/\\markus\markus\daten\Repositories\cutcontrol.db'
mtn: matched URI query: ''
mtn: matched URI fragment: ''
mtn: loading lua hook get_netsync_connect_command
mtn: lua: extracted string = mtn
mtn: lua: extracted string = --debug
mtn: lua: extracted string = --db
mtn: lua: extracted string = /\\markus\markus\daten\Repositories\cutcontrol.db
mtn: lua: extracted string = serve
mtn: lua: extracted string = --stdio
mtn: lua: extracted string = --no-transport-auth
mtn: lua: extracted string = de.mesw.cutcontrol.init
mtn: loading lua hook use_transport_auth
mtn: lua: extracted bool = 0
mtn: Subprocess command line: 'mtn --debug --db 
/\\markus\markus\daten\Repositories\cutcontrol.db serve --stdio 
--no-transport-auth de.mesw.cutcontrol.init '
mtn: checked_globish_to_regex: input = 'de.mesw.cutcontrol.init'
mtn: checked_globish_to_regex: output = 'de\.mesw\.cutcontrol\.init'
mtn: checked_globish_to_regex: input = ''
mtn: checked_globish_to_regex: output = '$.^'
mtn: prepared statement SELECT parent,child FROM revision_ancestry
mtn: binding 0 parameters for SELECT parent,child FROM revision_ancestry
mtn: WaitForSingleObject(,21600000)
mtn: started up on Windows NT/2000/XP/2003 (5.0, build 2195, none) on ia32 
(level 6, rev 2309)
mtn: command line: 'mtn', '--debug', '--db', 
'/\\markus\markus\daten\Repositories\cutcontrol.db', 'serve', '--stdio', 
'--no-transport-auth', 'de.mesw.cutcontrol
.init'
mtn: set locale: LC_ALL=German_Germany.1252
mtn: initial abs path is: Y:
mtn: converting 7 bytes from ASCII to UTF-8
mtn: converting 4 bytes from ASCII to UTF-8
mtn: converting 49 bytes from ASCII to UTF-8
mtn: converting 5 bytes from ASCII to UTF-8
mtn: converting 7 bytes from ASCII to UTF-8
mtn: converting 19 bytes from ASCII to UTF-8
mtn: converting 23 bytes from ASCII to UTF-8
mtn: converting 3 bytes from ASCII to UTF-8
mtn: initializing from directory Y:/
mtn: searching for '_MTN' directory with root 'Y:/'
mtn: search for '_MTN' ended at 'Y:/' with '' removed
mtn: 'Y:/_MTN' does not exist
mtn: searching for 'MT' directory with root 'Y:/'
mtn: search for 'MT' ended at 'Y:/' with '' removed
mtn: 'Y:/MT' does not exist
mtn: opening rcfile 'C:/Dokumente und 
Einstellungen/Name/Anwendungsdaten/monotone/monotonerc'
mtn: 'C:/Dokumente und Einstellungen/Name/Anwendungsdaten/monotone/monotonerc' 
is ok
mtn: skipping nonexistent rcfile '_MTN/monotonerc'
mtn: executing command 'serve'
mtn: executing SQL 'SELECT sql FROM sqlite_master WHERE (type = 'table' OR type 
= 'index') AND sql IS NOT NULL AND name not like 'sqlite_stat%' ORDER BY name'
mtn: result: 0 (not an error)
mtn: prepared statement SELECT 1 FROM revisions LIMIT 1
mtn: binding 0 parameters for SELECT 1 FROM revisions LIMIT 1
mtn: prepared statement SELECT 1 FROM manifests LIMIT 1
mtn: binding 0 parameters for SELECT 1 FROM manifests LIMIT 1
mtn: prepared statement SELECT 1 FROM rosters LIMIT 1
mtn: binding 0 parameters for SELECT 1 FROM rosters LIMIT 1
mtn: checked_globish_to_regex: input = 'de.mesw.cutcontrol.init'
mtn: checked_globish_to_regex: output = 'de\.mesw\.cutcontrol\.init'
mtn: checked_globish_to_regex: input = 'de.mesw.cutcontrol.init'
mtn: checked_globish_to_regex: output = 'de\.mesw\.cutcontrol\.init'
mtn: checked_globish_to_regex: input = ''
mtn: checked_globish_to_regex: output = '$.^'
mtn: prepared statement SELECT parent,child FROM revision_ancestry
mtn: binding 0 parameters for SELECT parent,child FROM revision_ancestry
mtn: beginning service on stdio
mtn: prepared statement BEGIN EXCLUSIVE
mtn: binding 0 parameters for BEGIN EXCLUSIVE
mtn: i/o probe with 0 armed
mtn: read 25 bytes from fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: processing 0 byte input buffer from peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db
mtn: prepared statement SELECT DISTINCT value FROM revision_certs WHERE name = ?
mtn: binding 1 parameters for SELECT DISTINCT value FROM revision_certs WHERE 
name = ?
mtn: binding 1 with value 'branch'
mtn: matching 'de.mesw.cutcontrol.init' against 'de\.mesw\.cutcontrol\.init' 
excluding '$.^': included, not excluded: matches
mtn: finding items to synchronize:
mtn: including branch de.mesw.cutcontrol.init
mtn: binding 1 parameters for SELECT DISTINCT value FROM revision_certs WHERE 
name = ?
mtn: wrote 25 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: WaitForSingleObject(,21600000)
mtn: binding 1 with value 'branch'
mtn: prepared statement SELECT id, name, value, keypair, signature FROM 
revision_certs WHERE name = ? AND value = ?
mtn: binding 2 parameters for SELECT id, name, value, keypair, signature FROM 
revision_certs WHERE name = ? AND value = ?
mtn: binding 1 with value 'branch'
mtn: binding 2 with value 'de.mesw.cutcontrol.init'
mtn: prepared statement SELECT branch, epoch FROM branch_epochs
mtn: binding 0 parameters for SELECT branch, epoch FROM branch_epochs
mtn: prepared statement SELECT hash, id, keypair FROM 'revision_certs' WHERE 
name != 'branch'
mtn: binding 0 parameters for SELECT hash, id, keypair FROM 'revision_certs' 
WHERE name != 'branch'
mtn: prepared statement SELECT id FROM public_keys WHERE id = ?
mtn: binding 1 parameters for SELECT id FROM public_keys WHERE id = ?
mtn: binding 1 with value 'address@hidden'
mtn: prepared statement SELECT keydata FROM public_keys WHERE id = ?
mtn: binding 1 parameters for SELECT keydata FROM public_keys WHERE id = ?
mtn: binding 1 with value 'address@hidden'
mtn: noting key 'address@hidden' = 'f75d66b58488653f5ec706463c962994d60727b2' 
to send
mtn: certificates | keys | revisions
mtn:           81 |    1 |        27
mtn: read 30 bytes from fd 184 (peer stdio)
mtn: processing 0 byte input buffer from peer stdio
mtn: received 'anonymous' netcmd from client for pattern 
'de.mesw.cutcontrol.init' excluding '' in source and sink mode
mtn: prepared statement SELECT DISTINCT value FROM revision_certs WHERE name = ?
mtn: binding 1 parameters for SELECT DISTINCT value FROM revision_certs WHERE 
name = ?
mtn: binding 1 with value 'branch'
mtn: wrote 30 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: WaitForSingleObject(,21600000)
mtn: checked_globish_to_regex: input = 'de.mesw.cutcontrol.init'
mtn: checked_globish_to_regex: output = 'de\.mesw\.cutcontrol\.init'
mtn: checked_globish_to_regex: input = ''
mtn: checked_globish_to_regex: output = '$.^'
mtn: matching 'de.mesw.cutcontrol.init' against 'de\.mesw\.cutcontrol\.init' 
excluding '$.^': included, not excluded: matches
mtn: matching 'de.mesw.cutcontrol.init' against 'de\.mesw\.cutcontrol\.init' 
excluding '$.^': included, not excluded: matches
mtn: matching 'de.mesw.cutcontrol.vc7' against 'de\.mesw\.cutcontrol\.init' 
excluding '$.^': not included, not excluded: does not match
mtn: allowed anonymous read/write permission for 'de.mesw.cutcontrol.init' 
excluding ''
mtn: finding items to synchronize:
mtn: including branch de.mesw.cutcontrol.init
mtn: binding 1 parameters for SELECT DISTINCT value FROM revision_certs WHERE 
name = ?
mtn: binding 1 with value 'branch'
mtn: prepared statement SELECT id, name, value, keypair, signature FROM 
revision_certs WHERE name = ? AND value = ?
mtn: binding 2 parameters for SELECT id, name, value, keypair, signature FROM 
revision_certs WHERE name = ? AND value = ?
mtn: binding 1 with value 'branch'
mtn: binding 2 with value 'de.mesw.cutcontrol.init'
mtn: prepared statement SELECT branch, epoch FROM branch_epochs
mtn: binding 0 parameters for SELECT branch, epoch FROM branch_epochs
mtn: prepared statement SELECT hash, id, keypair FROM 'revision_certs' WHERE 
name != 'branch'
mtn: binding 0 parameters for SELECT hash, id, keypair FROM 'revision_certs' 
WHERE name != 'branch'
mtn: prepared statement SELECT id FROM public_keys WHERE id = ?
mtn: binding 1 parameters for SELECT id FROM public_keys WHERE id = ?
mtn: binding 1 with value 'address@hidden'
mtn: prepared statement SELECT keydata FROM public_keys WHERE id = ?
mtn: binding 1 parameters for SELECT keydata FROM public_keys WHERE id = ?
mtn: binding 1 with value 'address@hidden'
mtn: noting key 'address@hidden' = 'f75d66b58488653f5ec706463c962994d60727b2' 
to send
mtn: certificates | keys | revisions
mtn:           72 |    1 |        24
mtn: i/o probe with 0 armed
mtn: read 3 bytes from fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: processing 0 byte input buffer from peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db
mtn: queueing refinement query of epoch node '', level 0
mtn: Beginning epoch refinement on client.
mtn: wrote 51 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: WaitForSingleObject(,21600000)
mtn: wrote 3 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: WaitForSingleObject(,21600000)
mtn: read 51 bytes from fd 184 (peer stdio)
mtn: processing 0 byte input buffer from peer stdio
mtn: processing refine cmd for epoch node at level 0
mtn: queueing refinement response of epoch node '', level 0
mtn: i/o probe with 0 armed
mtn: read 51 bytes from fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: processing 0 byte input buffer from peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db
mtn: processing refine cmd for epoch node at level 0
mtn: queueing 'done' command for epoch (0 items)
mtn: wrote 5 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: WaitForSingleObject(,21600000)
mtn: wrote 51 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: WaitForSingleObject(,21600000)
mtn: read 5 bytes from fd 184 (peer stdio)
mtn: processing 0 byte input buffer from peer stdio
mtn: received 'done' command for epoch (0 items)
mtn: server finished epoch refinement: 0 to send, 0 to receive
mtn: queueing 'done' command for epoch (0 items)
mtn: epoch refinement finished
mtn: i/o probe with 0 armed
mtn: read 5 bytes from fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: processing 0 byte input buffer from peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db
mtn: received 'done' command for epoch (0 items)
mtn: client finished epoch refinement: 0 to send, 0 to receive
mtn: epoch refinement finished; beginning other refinements
mtn: queueing refinement query of key node '', level 0
mtn: Beginning key refinement on client.
mtn: queueing refinement query of cert node '', level 0
mtn: Beginning cert refinement on client.
mtn: queueing refinement query of revision node '', level 0
mtn: Beginning revision refinement on client.
mtn: wrote 51 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: wrote 352 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: wrote 292 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: WaitForSingleObject(,21600000)
mtn: wrote 5 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: WaitForSingleObject(,21600000)
mtn: read 695 bytes from fd 184 (peer stdio)
mtn: processing 644 byte input buffer from peer stdio
mtn: processing refine cmd for key node at level 0
mtn: queueing refinement response of key node '', level 0
mtn: fd 184 is armed
mtn: i/o probe with 1 armed
mtn: read 51 bytes from fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: processing 0 byte input buffer from peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db
mtn: processing refine cmd for key node at level 0
mtn: queueing 'done' command for key (0 items)
mtn: wrote 5 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: WaitForSingleObject(,21600000)
mtn: wrote 51 bytes to fd 184 (peer stdio)
mtn: processing 292 byte input buffer from peer stdio
mtn: processing refine cmd for cert node at level 0
mtn: queueing refinement query of cert node '00', level 1
mtn: queueing refinement query of cert node '01', level 1
mtn: queueing refinement query of cert node '04', level 1
mtn: queueing refinement query of cert node '05', level 1
mtn: queueing refinement query of cert node '08', level 1
mtn: queueing refinement query of cert node '09', level 1
mtn: queueing refinement query of cert node '0a', level 1
mtn: queueing refinement query of cert node '0b', level 1
mtn: queueing refinement query of cert node '0c', level 1
mtn: queueing refinement query of cert node '0e', level 1
mtn: queueing refinement query of cert node '0f', level 1
mtn: queueing refinement response of cert node '', level 0
mtn: fd 184 is armed
mtn: i/o probe with 1 armed
mtn: read 213 bytes from fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: processing 0 byte input buffer from peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db
mtn: processing refine cmd for cert node at level 1
mtn: queueing refinement response of cert node '00', level 1
mtn: wrote 233 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: WaitForSingleObject(,21600000)
mtn: wrote 213 bytes to fd 184 (peer stdio)
mtn: processing 0 byte input buffer from peer stdio
mtn: processing refine cmd for revision node at level 0
mtn: queueing refinement query of revision node '00', level 1
mtn: queueing refinement query of revision node '07', level 1
mtn: queueing refinement query of revision node '0a', level 1
mtn: queueing refinement response of revision node '', level 0
mtn: i/o probe with 0 armed
mtn: read 112 bytes from fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: processing 0 byte input buffer from peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db
mtn: processing refine cmd for cert node at level 1
mtn: queueing refinement response of cert node '01', level 1
mtn: wrote 133 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: bytes in | bytes out | revs in | revs out
mtn:      435 |     1.1 k |       0 |        0
mtn: WaitForSingleObject(,21600000)
mtn: wrote 112 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: read 72 bytes from fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: bytes in | bytes out | revs in | revs out
mtn:      507 |     1.1 k |       0 |        0
mtn: processing 0 byte input buffer from peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db
mtn: processing refine cmd for cert node at level 1
mtn: queueing refinement response of cert node '04', level 1
mtn: wrote 92 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: bytes in | bytes out | revs in | revs out
mtn:      507 |     1.2 k |       0 |        0
mtn: WaitForSingleObject(,21600000)
mtn: wrote 72 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: read 92 bytes from fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: bytes in | bytes out | revs in | revs out
mtn:      599 |     1.2 k |       0 |        0
mtn: processing 0 byte input buffer from peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db
mtn: processing refine cmd for cert node at level 1
mtn: queueing refinement query of cert node '35', level 2
mtn: queueing refinement response of cert node '05', level 1
mtn: wrote 72 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: wrote 92 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: bytes in | bytes out | revs in | revs out
mtn:      599 |     1.4 k |       0 |        0
mtn: WaitForSingleObject(,21600000)
mtn: wrote 92 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: read 72 bytes from fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: bytes in | bytes out | revs in | revs out
mtn:      671 |     1.4 k |       0 |        0
mtn: processing 0 byte input buffer from peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db
mtn: processing refine cmd for cert node at level 1
mtn: queueing refinement response of cert node '08', level 1
mtn: wrote 92 bytes to fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: bytes in | bytes out | revs in | revs out
mtn:      671 |     1.5 k |       0 |        0
mtn: WaitForSingleObject(,21600000)
mtn: wrote 72 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: read 133 bytes from fd 176 (peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db)
mtn: bytes in | bytes out | revs in | revs out
mtn:      804 |     1.5 k |       0 |        0
mtn: processing 0 byte input buffer from peer 
file:/\\markus\markus\daten\Repositories\cutcontrol.db
mtn: processing refine cmd for cert node at level 1
mtn: queueing refinement response of cert node '09', level 1
mtn: wrote 133 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: wrote 133 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: wrote 173 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: wrote 233 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: wrote 153 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
mtn: wrote 153 bytes to fd 184 (peer stdio)
mtn: i/o probe with 0 armed
^C
Y:\>