#91 Unable to open handle for document revision

[Adam Tauno Williams]

When a file is modified via a Windows mount it appears to cause a document versioning issue

DEBUG:context:duration of document::get was 0.006
ERROR:http:Unable to open handle for document revision objectId#95120 revision#None
Traceback (most recent call last):
  File "/home/awilliam/projects/coils/coils/net/handler.py", line 163, in process_request
    getattr(handler, 'do_{0}'.format(self.command))()
  File "/home/awilliam/projects/coils/coils/protocol/dav/groupware/documentobject.py", line 143, in do_GET
    handle = self.context.run_command('document::get-handle', id=self.entity.object_id)
  File "/home/awilliam/projects/coils/coils/core/context.py", line 293, in run_command
    command.run()
  File "/home/awilliam/projects/coils/coils/logic/blob/get_handle.py", line 54, in run
    encoding=self._encoding)
  File "/home/awilliam/projects/coils/coils/logic/blob/command.py", line 61, in get_handle
    format(document.object_id, version))
CoilsException: Unable to open handle for document revision objectId#95120     revision#None
DEBUG:context:Closing context 0f4d9c2575c546c9b3f426fa9ad118f8
DEBUG:coils.http.24480:Incremented request count

[Adam Tauno Williams]

No, it looks like it works now. Scratches head.

[Adam Tauno Williams]

There is some ORM whining

/home/awilliam/projects/coils/lib/python2.7/site-packages/sqlalchemy/orm/attributes.py:453: SAWarning: Multiple rows returned with uselist=False for lazily-loaded attribute 'Document._version'

value = self.callable_(state, passive)
localhost - - [25/Nov/2012 00:06:00] "PUT /dav/Projects/test123/Documents/84CF40A47CE1B33A8B850F3F31952.jpg HTTP/1.0" 204 -
/home/awilliam/projects/coils/lib/python2.7/site-packages/sqlalchemy/orm/attributes.py:453: SAWarning: Multiple rows returned with uselist=False for lazily-loaded attribute 'Document.version'
value = self.callable(state, passive)

[Adam Tauno Williams]

Okay, got it again after saving a document from wordpad and they trying to open it.

DEBUG:context:duration of document::get was 0.006
ERROR:http:Unable to open handle for document revision objectId#96330 revision#None
Traceback (most recent call last):
File "/home/awilliam/projects/coils/coils/net/handler.py", line 163, in process_request
 getattr(handler, 'do_{0}'.format(self.command))()
File "/home/awilliam/projects/coils/coils/protocol/dav/groupware/documentobject.py", line 143, in do_GET
 handle = self.context.run_command('document::get-handle', id=self.entity.object_id)
File "/home/awilliam/projects/coils/coils/core/context.py", line 293, in run_command
 command.run()
File "/home/awilliam/projects/coils/coils/logic/blob/get_handle.py", line 54, in run
 encoding=self._encoding)
File "/home/awilliam/projects/coils/coils/logic/blob/command.py", line 61, in get_handle
 format(document.object_id, version))
CoilsException: Unable to open handle for document revision objectId#96330 revision#None
DEBUG:context:Closing context 9d891635f2d649bd929dd30d1dd25694

[Adam Tauno Williams]

Now that the log records the expected path...

CoilsException: Unable to open handle for document revision objectId#96330 revision#None, expected path was "documents/93920/96000/96330.odt"

Interesting, that blob exists but has a different file extension.

awilliam@workstation:~/projects/coils/root> ls -l documents/93920/96000/
total 540
-rw-r--r-- 1 awilliam users 20607 Nov 24 19:06 96010.jpg
-rw-r--r-- 1 awilliam users     0 Nov 24 19:04 96020.jpg
-rw-r--r-- 1 awilliam users 14170 Nov 24 19:04 96060.jpg
-rw-r--r-- 1 awilliam users 20607 Nov 24 19:05 96110.jpg
-rw-r--r-- 1 awilliam users 20607 Nov 24 19:06 96140.jpg
-rw-r--r-- 1 awilliam users 57361 Nov 24 20:23 96200.odt
-rw-r--r-- 1 awilliam users 57302 Nov 24 20:23 96210.odt
-rw-r--r-- 1 awilliam users 57368 Nov 24 20:23 96220.odt
-rw-r--r-- 1 awilliam users 57307 Nov 24 20:25 96260.odt
-rw-r--r-- 1 awilliam users 57371 Nov 24 20:25 96270.odt
-rw-r--r-- 1 awilliam users 80789 Nov 25 14:40 96330.tmp
-rw-r--r-- 1 awilliam users     0 Nov 25 14:40 96340.tmp
-rw-r--r-- 1 awilliam users 80789 Nov 25 14:40 96380.tmp

Perhaps this is the tmp-file-rename-thing. Does that create a new document or a new version? I think we need to trace the entire conversation.