python3 ./tests/test_cli.py
DEBUG:passlib.utils.compat:loaded lazy attr 'SafeConfigParser': <class 'configparser.ConfigParser'>
DEBUG:passlib.utils.compat:loaded lazy attr 'NativeStringIO': <class '_io.StringIO'>
DEBUG:passlib.utils.compat:loaded lazy attr 'BytesIO': <class '_io.BytesIO'>
INFO:werkzeug: * Running on http://127.0.0.1:5555/
>>>  blender --background --factory-startup -noaudio --python ./tests/blendfile_templates.py -- /tmp/bam_test/blend_file_template/create_image_single.blend /tmp/bam_test/blend_file_template /tmp/bam_test/blend_template_deps.json create_image_single NONE 123
   stdout:  b'Error: Not freed memory blocks: 215'
   stderr:  b''
   return:  123
>>>  blender --background --factory-startup -noaudio --python ./tests/blendfile_templates.py -- /tmp/bam_test/blend_file_template/create_blank.blend /tmp/bam_test/blend_file_template /tmp/bam_test/blend_template_deps.json create_blank NONE 123
   stdout:  b'Error: Not freed memory blocks: 215'
   stderr:  b''
   return:  123
>>>  blender --background --factory-startup -noaudio --python ./tests/blendfile_templates.py -- /tmp/bam_test/blend_file_template/create_from_files.blend /tmp/bam_test/blend_file_template /tmp/bam_test/blend_template_deps.json create_from_files NONE 123
   stdout:  b'Error: Not freed memory blocks: 215'
   stderr:  b''
   return:  123
.>>>  blender --background --factory-startup -noaudio --python ./tests/blendfile_templates.py -- /tmp/bam_test/testfile.blend /tmp/bam_test /tmp/bam_test/blend_template_deps.json create_blank NONE 123
   stdout:  b'Error: Not freed memory blocks: 215'
   stderr:  b''
   return:  123
.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam create mysession
   stdout:  Session 'mysession' created
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'test message'
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/other_file.txt']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'test message', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:13] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22test+message%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22test+message%22%7D&command=commit HTTP/1.1" 200 138
   stdout:  Now make a zipfile
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/other_file.txt'
Adding         tmp/bam_test/remote_store/svn_checkout/other_file.txt
Transmitting file data .
Committed revision 2.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam checkout other_file.txt --output /tmp/bam_test/local_store/test_project/mysession
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:13] "GET /test_project/file?filepath=other_file.txt&command=checkout HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file?filepath=other_file.txt&command=checkout HTTP/1.1" 200 None
INFO:webservice:  Source path: '/tmp/bam_test/remote_store/svn_checkout/other_file.txt'
INFO:webservice:  Zip path: '/tmp/tmpei9y0kij.zip'
   stdout:  download: [100%]
written: '/tmp/bam_test/local_store/test_project/mysession'
   stderr:  
.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam create mysession
   stdout:  Session 'mysession' created
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'test message'
   stdout:  Nothing to commit!
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'test message'
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/testfile.txt']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'test message', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:13] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22test+message%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22test+message%22%7D&command=commit HTTP/1.1" 200 136
   stdout:  Now make a zipfile
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/testfile.txt'
Adding         tmp/bam_test/remote_store/svn_checkout/testfile.txt
Transmitting file data .
Committed revision 2.
   stderr:  
.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam create mysession
   stdout:  Session 'mysession' created
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'test 1'
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/a.data']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/b_dir']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/c_dir']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/d_dir']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'test 1', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:14] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22test+1%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22test+1%22%7D&command=commit HTTP/1.1" 200 766
   stdout:  Now make a zipfile
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/c_dir/c_subdir/c.data'
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/b_dir/b.data'
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/d_dir/d_subdir/d_nested/d.data'
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/a.data'
Adding         tmp/bam_test/remote_store/svn_checkout/a.data
Adding         tmp/bam_test/remote_store/svn_checkout/b_dir
Adding         tmp/bam_test/remote_store/svn_checkout/b_dir/b.data
Adding         tmp/bam_test/remote_store/svn_checkout/c_dir
Adding         tmp/bam_test/remote_store/svn_checkout/c_dir/c_subdir
Adding         tmp/bam_test/remote_store/svn_checkout/c_dir/c_subdir/c.data
Adding         tmp/bam_test/remote_store/svn_checkout/d_dir
Adding         tmp/bam_test/remote_store/svn_checkout/d_dir/d_subdir
Adding         tmp/bam_test/remote_store/svn_checkout/d_dir/d_subdir/d_nested
Adding         tmp/bam_test/remote_store/svn_checkout/d_dir/d_subdir/d_nested/d.data
Transmitting file data ....
Committed revision 2.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam status --json
   stdout:  []
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam status --json
   stdout:  [["M", "a.data"], ["M", "b_dir/b.data"], ["M", "c_dir/c_subdir/c.data"], ["M", "d_dir/d_subdir/d_nested/d.data"]]
   stderr:  
.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam create mysession
   stdout:  Session 'mysession' created
   stderr:  
>>>  blender --background --factory-startup -noaudio --python ./tests/blendfile_templates.py -- /tmp/bam_test/local_store/test_project/mysession/testfile.blend /tmp/bam_test/local_store/test_project/mysession /tmp/bam_test/blend_template_deps.json create_blank NONE 123
   stdout:  b'Error: Not freed memory blocks: 215'
   stderr:  b''
   return:  123

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'tests message'
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/testfile.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/testfile.blend']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'tests message', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:15] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22tests+message%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22tests+message%22%7D&command=commit HTTP/1.1" 200 138
   stdout:  Now make a zipfile
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/testfile.blend'
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/testfile.blend
Transmitting file data .
Committed revision 2.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam checkout testfile.blend --output new_out
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:15] "GET /test_project/file?filepath=testfile.blend&command=checkout HTTP/1.1" 200 -
INFO:webservice:  Source path: '/tmp/bam_test/remote_store/svn_checkout/testfile.blend'
INFO:webservice:  Zip path: '/tmp/tmpgnnzezoj.zip'
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file?filepath=testfile.blend&command=checkout HTTP/1.1" 200 None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/remote_store/svn_checkout/testfile.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
   stdout:  
scanning deps: b'/tmp/bam_test/remote_store/svn_checkout/testfile.blend'...

archiving: 1 files
  copying: b'/tmp/__blendfile_temp__/testfile.blend@' -> <archive>
  written: b'/tmp/tmpgnnzezoj.zip'
download: [100%]
written: '/tmp/bam_test/local_store/test_project/new_out'
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/new_out' ; bam commit -m 'test deletion'
DEBUG:bam_cli:{'testfile.blend': 'D'}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:webservice:{'testfile.blend': 'D'}
DEBUG:svn:RUN: ['svn', 'rm', '/tmp/bam_test/remote_store/svn_checkout/testfile.blend']
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'test deletion', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:15] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22test+deletion%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22test+deletion%22%7D&command=commit HTTP/1.1" 200 114
   stdout:  Now make a zipfile
Deleting       tmp/bam_test/remote_store/svn_checkout/testfile.blend

Committed revision 3.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/new_out' ; bam ls --json
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:15] "GET /test_project/file_list?path=. HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file_list?path=. HTTP/1.1" 200 173
   stdout:  [[".svn", "dir"], ["test_project", "dir"]]
   stderr:  
.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam create mysession
   stdout:  Session 'mysession' created
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam status
   stdout:  
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'test message'
   stdout:  Nothing to commit!
   stderr:  
.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam create mysession
   stdout:  Session 'mysession' created
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam status

.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  
.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam ls --json
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:15] "GET /test_project/file_list?path=. HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file_list?path=. HTTP/1.1" 200 173
   stdout:  [[".svn", "dir"], ["test_project", "dir"]]
   stderr:  
.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam create mysession
   stdout:  Session 'mysession' created
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'test message'
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/subdir/rel/path/house_rel.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/abs/path/house_abs.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/subdir/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/abs']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/subdir']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'test message', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:16] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22test+message%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22test+message%22%7D&command=commit HTTP/1.1" 200 642
   stdout:  Now make a zipfile
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/subdir/rel/path/house_rel.blend'
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/abs/path/house_abs.blend'
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/subdir/house_lib_user.blend'
Adding         tmp/bam_test/remote_store/svn_checkout/abs
Adding         tmp/bam_test/remote_store/svn_checkout/abs/path
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/abs/path/house_abs.blend
Adding         tmp/bam_test/remote_store/svn_checkout/subdir
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend
Adding         tmp/bam_test/remote_store/svn_checkout/subdir/rel
Adding         tmp/bam_test/remote_store/svn_checkout/subdir/rel/path
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/subdir/rel/path/house_rel.blend
Transmitting file data ...
Committed revision 2.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam checkout subdir/house_lib_user.blend --output /tmp/bam_test/local_store/test_project/mysession
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:16] "GET /test_project/file?filepath=subdir%2Fhouse_lib_user.blend&command=checkout HTTP/1.1" 200 -
INFO:webservice:  Source path: '/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'
INFO:webservice:  Zip path: '/tmp/tmps2q1ko8g.zip'
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file?filepath=subdir%2Fhouse_lib_user.blend&command=checkout HTTP/1.1" 200 None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
  Library: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/subdir/rel/path/house_rel.blend'
INFO:path_walker:~
INFO:path_walker:  /tmp/bam_test/remote_store/svn_checkout/subdir/rel/path/house_rel.blend
INFO:path_walker:  GRhouse_rel
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:  GRhouse_rel, MAMaterial, MECube, OBCube, TETex
  Library: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/abs/path/house_abs.blend'
INFO:path_walker:~
INFO:path_walker:  /tmp/bam_test/remote_store/svn_checkout/abs/path/house_abs.blend
INFO:path_walker:  GRhouse_abs
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:  GRhouse_abs, MAMaterial, MECube, OBCube, TETex
   stdout:  
scanning deps: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'...
  blend:       b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'

archiving: 1 files
  copying: b'/tmp/__blendfile_temp__/_abs/path/house_abs.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/house_lib_user.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/rel/path/house_rel.blend@' -> <archive>
  written: b'/tmp/tmps2q1ko8g.zip'
download: [100%]
written: '/tmp/bam_test/local_store/test_project/mysession'
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam deps /tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend --json
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
   stdout:  [
["/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend", "//_abs/path/house_abs.blend", "/tmp/bam_test/local_store/test_project/mysession/_abs/path/house_abs.blend", "OK"],
["/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend", "//rel/path/house_rel.blend", "/tmp/bam_test/local_store/test_project/mysession/rel/path/house_rel.blend", "OK"]]
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'just touched'
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/rel/path/house_rel.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/_abs/path/house_abs.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:None
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'just touched', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:16] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22just+touched%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22just+touched%22%7D&command=commit HTTP/1.1" 200 151
   stdout:  Remapping: b'//_abs/path/house_abs.blend' -> b'//../abs/path/house_abs.blend'
Now make a zipfile
  packing (M): '/tmp/bam_test/local_store/test_project/.bam/tmp/house_lib_user.blend'
  packing (M): '/tmp/bam_test/local_store/test_project/.bam/tmp/rel/path/house_rel.blend'
  packing (M): '/tmp/bam_test/local_store/test_project/.bam/tmp/_abs/path/house_abs.blend'
Sending        tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend
Transmitting file data .
Committed revision 3.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam checkout subdir/house_lib_user.blend --output /tmp/bam_test/local_store/test_project/mysession
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:16] "GET /test_project/file?filepath=subdir%2Fhouse_lib_user.blend&command=checkout HTTP/1.1" 200 -
INFO:webservice:  Source path: '/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'
INFO:webservice:  Zip path: '/tmp/tmp4vdr21gv.zip'
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file?filepath=subdir%2Fhouse_lib_user.blend&command=checkout HTTP/1.1" 200 None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
  Library: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/subdir/rel/path/house_rel.blend'
INFO:path_walker:~
INFO:path_walker:  /tmp/bam_test/remote_store/svn_checkout/subdir/rel/path/house_rel.blend
INFO:path_walker:  GRhouse_rel
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:  GRhouse_rel, MAMaterial, MECube, OBCube, TETex
  Library: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/abs/path/house_abs.blend'
INFO:path_walker:~
INFO:path_walker:  /tmp/bam_test/remote_store/svn_checkout/abs/path/house_abs.blend
INFO:path_walker:  GRhouse_abs
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:  GRhouse_abs, MAMaterial, MECube, OBCube, TETex
   stdout:  
scanning deps: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'...
  blend:       b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'

archiving: 1 files
  copying: b'/tmp/__blendfile_temp__/_abs/path/house_abs.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/house_lib_user.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/rel/path/house_rel.blend@' -> <archive>
  written: b'/tmp/tmp4vdr21gv.zip'
download: [100%]
written: '/tmp/bam_test/local_store/test_project/mysession'
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam deps /tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend --json
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
   stdout:  [
["/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend", "//_abs/path/house_abs.blend", "/tmp/bam_test/local_store/test_project/mysession/_abs/path/house_abs.blend", "OK"],
["/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend", "//rel/path/house_rel.blend", "/tmp/bam_test/local_store/test_project/mysession/rel/path/house_rel.blend", "OK"]]
   stderr:  
.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam create mysession
   stdout:  Session 'mysession' created
   stderr:  
>>>  blender --background --factory-startup -noaudio --python ./tests/blendfile_templates.py -- /tmp/bam_test/local_store/test_project/mysession/subdir/house_lib_user.blend /tmp/bam_test/local_store/test_project/mysession /tmp/bam_test/blend_template_deps.json create_blank NONE 123
   stdout:  b'Error: Not freed memory blocks: 215'
   stderr:  b''
   return:  123

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'test message'
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/subdir/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/subdir']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'test message', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:18] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22test+message%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22test+message%22%7D&command=commit HTTP/1.1" 200 213
   stdout:  Now make a zipfile
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/subdir/house_lib_user.blend'
Adding         tmp/bam_test/remote_store/svn_checkout/subdir
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend
Transmitting file data .
Committed revision 2.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam checkout subdir/house_lib_user.blend --output /tmp/bam_test/local_store/test_project/mysession
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:18] "GET /test_project/file?filepath=subdir%2Fhouse_lib_user.blend&command=checkout HTTP/1.1" 200 -
INFO:webservice:  Source path: '/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'
INFO:webservice:  Zip path: '/tmp/tmpsszig0lm.zip'
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file?filepath=subdir%2Fhouse_lib_user.blend&command=checkout HTTP/1.1" 200 None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
   stdout:  
scanning deps: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'...

archiving: 1 files
  copying: b'/tmp/__blendfile_temp__/house_lib_user.blend@' -> <archive>
  written: b'/tmp/tmpsszig0lm.zip'
download: [100%]
written: '/tmp/bam_test/local_store/test_project/mysession'
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'new house to remap'
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/rel/path/house_rel.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/_abs/path/house_abs.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:None
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/abs']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/subdir/rel']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'new house to remap', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:18] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22new+house+to+remap%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22new+house+to+remap%22%7D&command=commit HTTP/1.1" 200 580
   stdout:  Remapping: b'//_abs/path/house_abs.blend' -> b'//../abs/path/house_abs.blend'
Now make a zipfile
  packing (M): '/tmp/bam_test/local_store/test_project/.bam/tmp/house_lib_user.blend'
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/rel/path/house_rel.blend'
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/_abs/path/house_abs.blend'
Adding         tmp/bam_test/remote_store/svn_checkout/abs
Adding         tmp/bam_test/remote_store/svn_checkout/abs/path
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/abs/path/house_abs.blend
Sending        tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend
Adding         tmp/bam_test/remote_store/svn_checkout/subdir/rel
Adding         tmp/bam_test/remote_store/svn_checkout/subdir/rel/path
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/subdir/rel/path/house_rel.blend
Transmitting file data ...
Committed revision 3.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam checkout subdir/house_lib_user.blend --output /tmp/bam_test/local_store/test_project/mysession
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:18] "GET /test_project/file?filepath=subdir%2Fhouse_lib_user.blend&command=checkout HTTP/1.1" 200 -
INFO:webservice:  Source path: '/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'
INFO:webservice:  Zip path: '/tmp/tmpanobdf1n.zip'
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file?filepath=subdir%2Fhouse_lib_user.blend&command=checkout HTTP/1.1" 200 None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
  Library: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/subdir/rel/path/house_rel.blend'
INFO:path_walker:~
INFO:path_walker:  /tmp/bam_test/remote_store/svn_checkout/subdir/rel/path/house_rel.blend
INFO:path_walker:  GRhouse_rel
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:  GRhouse_rel, MAMaterial, MECube, OBCube, TETex
  Library: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/abs/path/house_abs.blend'
INFO:path_walker:~
INFO:path_walker:  /tmp/bam_test/remote_store/svn_checkout/abs/path/house_abs.blend
INFO:path_walker:  GRhouse_abs
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:  GRhouse_abs, MAMaterial, MECube, OBCube, TETex
   stdout:  
scanning deps: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'...
  blend:       b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'

archiving: 1 files
  copying: b'/tmp/__blendfile_temp__/_abs/path/house_abs.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/house_lib_user.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/rel/path/house_rel.blend@' -> <archive>
  written: b'/tmp/tmpanobdf1n.zip'
download: [100%]
written: '/tmp/bam_test/local_store/test_project/mysession'
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam deps /tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend --json
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
   stdout:  [
["/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend", "//_abs/path/house_abs.blend", "/tmp/bam_test/local_store/test_project/mysession/_abs/path/house_abs.blend", "OK"],
["/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend", "//rel/path/house_rel.blend", "/tmp/bam_test/local_store/test_project/mysession/rel/path/house_rel.blend", "OK"]]
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'just touched'
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/rel/path/house_rel.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/_abs/path/house_abs.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:None
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'just touched', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:19] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22just+touched%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22just+touched%22%7D&command=commit HTTP/1.1" 200 151
   stdout:  Remapping: b'//_abs/path/house_abs.blend' -> b'//../abs/path/house_abs.blend'
Now make a zipfile
  packing (M): '/tmp/bam_test/local_store/test_project/.bam/tmp/house_lib_user.blend'
  packing (M): '/tmp/bam_test/local_store/test_project/.bam/tmp/rel/path/house_rel.blend'
  packing (M): '/tmp/bam_test/local_store/test_project/.bam/tmp/_abs/path/house_abs.blend'
Sending        tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend
Transmitting file data .
Committed revision 4.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam checkout subdir/house_lib_user.blend --output /tmp/bam_test/local_store/test_project/mysession
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:19] "GET /test_project/file?filepath=subdir%2Fhouse_lib_user.blend&command=checkout HTTP/1.1" 200 -
INFO:webservice:  Source path: '/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'
INFO:webservice:  Zip path: '/tmp/tmp4t8ynf2n.zip'
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file?filepath=subdir%2Fhouse_lib_user.blend&command=checkout HTTP/1.1" 200 None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
  Library: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/subdir/rel/path/house_rel.blend'
INFO:path_walker:~
INFO:path_walker:  /tmp/bam_test/remote_store/svn_checkout/subdir/rel/path/house_rel.blend
INFO:path_walker:  GRhouse_rel
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:  GRhouse_rel, MAMaterial, MECube, OBCube, TETex
  Library: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/abs/path/house_abs.blend'
INFO:path_walker:~
INFO:path_walker:  /tmp/bam_test/remote_store/svn_checkout/abs/path/house_abs.blend
INFO:path_walker:  GRhouse_abs
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3950 names
DEBUG:blendfile:building #644 types
DEBUG:blendfile:building #644 type-lengths
DEBUG:blendfile:building #568 structures
INFO:path_walker:  GRhouse_abs, MAMaterial, MECube, OBCube, TETex
   stdout:  
scanning deps: b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'...
  blend:       b'/tmp/bam_test/remote_store/svn_checkout/subdir/house_lib_user.blend'

archiving: 1 files
  copying: b'/tmp/__blendfile_temp__/_abs/path/house_abs.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/house_lib_user.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/rel/path/house_rel.blend@' -> <archive>
  written: b'/tmp/tmp4t8ynf2n.zip'
download: [100%]
written: '/tmp/bam_test/local_store/test_project/mysession'
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam deps /tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend --json
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
   stdout:  [
["/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend", "//_abs/path/house_abs.blend", "/tmp/bam_test/local_store/test_project/mysession/_abs/path/house_abs.blend", "OK"],
["/tmp/bam_test/local_store/test_project/mysession/house_lib_user.blend", "//rel/path/house_rel.blend", "/tmp/bam_test/local_store/test_project/mysession/rel/path/house_rel.blend", "OK"]]
   stderr:  
.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam create mysession
   stdout:  Session 'mysession' created
   stderr:  
>>>  blender --background --factory-startup -noaudio --python ./tests/blendfile_templates.py -- /tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend /tmp/bam_test/local_store/test_project/mysession /tmp/bam_test/blend_template_deps.json create_from_file_liblinks /tmp/bam_test/blendfile_create_data.json 123
   stdout:  b"Info: Read library:  '/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend', '//../../../maps/generic.blend'\n[['/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend', 'Scene10', ['/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend']], ['/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend', 'MySpecial', ['/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend']], ['/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend', 'MyGeneric', []]]\n['/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend']\n['/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend']\n[]\n['/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend']\n['/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend']\n['/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend']\nError: Not freed memory blocks: 215"
   stderr:  b''
   return:  123

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam deps /tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend --json
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
   stdout:  [
["/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend", "//maps/special.blend", "/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend", "OK"]]
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'commit shot_01'
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/maps']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/shots']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'commit shot_01', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:21] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22commit+shot_01%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22commit+shot_01%22%7D&command=commit HTTP/1.1" 200 800
   stdout:  Now make a zipfile
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/maps/generic.blend'
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/shots/01/shot_01.blend'
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend1'
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend1'
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/shots/01/maps/special.blend'
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend1'
Adding         tmp/bam_test/remote_store/svn_checkout/maps
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/maps/generic.blend
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/maps/generic.blend1
Adding         tmp/bam_test/remote_store/svn_checkout/shots
Adding         tmp/bam_test/remote_store/svn_checkout/shots/01
Adding         tmp/bam_test/remote_store/svn_checkout/shots/01/maps
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend1
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend1
Transmitting file data ......
Committed revision 2.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam checkout shots/01/shot_01.blend --output new_out
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:21] "GET /test_project/file?filepath=shots%2F01%2Fshot_01.blend&command=checkout HTTP/1.1" 200 -
INFO:webservice:  Source path: '/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend'
INFO:webservice:  Zip path: '/tmp/tmpua5c90iy.zip'
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file?filepath=shots%2F01%2Fshot_01.blend&command=checkout HTTP/1.1" 200 None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
  Library: b'/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend'
INFO:path_walker:~
INFO:path_walker:  /tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend
INFO:path_walker:  SCMySpecial
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:  SCMySpecial, WOWorld
    Library: b'/tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/maps/generic.blend'
INFO:path_walker:~
INFO:path_walker:    /tmp/bam_test/remote_store/svn_checkout/maps/generic.blend
INFO:path_walker:    SCMyGeneric
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:    SCMyGeneric, WOWorld
   stdout:  
scanning deps: b'/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend'...
  blend:       b'/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend'
  blend:       b'/tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend'

archiving: 1 files
  copying: b'/tmp/__blendfile_temp__/shot_01.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/maps/special.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/_maps/generic.blend@' -> <archive>
  written: b'/tmp/tmpua5c90iy.zip'
download: [100%]
written: '/tmp/bam_test/local_store/test_project/new_out'
   stderr:  
Exists? /tmp/bam_test/local_store/test_project/new_out/shot_01.blend
Exists? /tmp/bam_test/local_store/test_project/new_out/maps/special.blend
Exists? /tmp/bam_test/local_store/test_project/new_out/_maps/generic.blend

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam checkout shots/01/shot_01.blend --output new_out
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:21] "GET /test_project/file?filepath=shots%2F01%2Fshot_01.blend&command=checkout HTTP/1.1" 200 -
INFO:webservice:  Source path: '/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend'
INFO:webservice:  Zip path: '/tmp/tmpmarkjus_.zip'
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file?filepath=shots%2F01%2Fshot_01.blend&command=checkout HTTP/1.1" 200 None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
  Library: b'/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend'
INFO:path_walker:~
INFO:path_walker:  /tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend
INFO:path_walker:  SCMySpecial
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:  SCMySpecial, WOWorld
    Library: b'/tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/maps/generic.blend'
INFO:path_walker:~
INFO:path_walker:    /tmp/bam_test/remote_store/svn_checkout/maps/generic.blend
INFO:path_walker:    SCMyGeneric
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:    SCMyGeneric, WOWorld
   stdout:  
scanning deps: b'/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend'...
  blend:       b'/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend'
  blend:       b'/tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend'

archiving: 1 files
  copying: b'/tmp/__blendfile_temp__/shot_01.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/maps/special.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/_maps/generic.blend@' -> <archive>
  written: b'/tmp/tmpmarkjus_.zip'
download: [100%]
written: '/tmp/bam_test/local_store/test_project/new_out'
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/new_out' ; bam commit -m 'new abs and rel files'
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/maps_more']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/shots/01/maps_more']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'new abs and rel files', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:21] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22new+abs+and+rel+files%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22new+abs+and+rel+files%22%7D&command=commit HTTP/1.1" 200 363
   stdout:  Now make a zipfile
  packing (A): '/tmp/bam_test/local_store/test_project/new_out/maps_more/rel.txt'
  packing (A): '/tmp/bam_test/local_store/test_project/new_out/_maps_more/abs.txt'
Adding         tmp/bam_test/remote_store/svn_checkout/maps_more
Adding         tmp/bam_test/remote_store/svn_checkout/maps_more/abs.txt
Adding         tmp/bam_test/remote_store/svn_checkout/shots/01/maps_more
Adding         tmp/bam_test/remote_store/svn_checkout/shots/01/maps_more/rel.txt
Transmitting file data ..
Committed revision 3.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam ls --json
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:21] "GET /test_project/file_list?path=. HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file_list?path=. HTTP/1.1" 200 350
   stdout:  [[".svn", "dir"], ["maps", "dir"], ["maps_more", "dir"], ["shots", "dir"], ["test_project", "dir"]]
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam ls shots/01/maps_more --json
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:21] "GET /test_project/file_list?path=shots%2F01%2Fmaps_more HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file_list?path=shots%2F01%2Fmaps_more HTTP/1.1" 200 126
   stdout:  [["rel.txt", "file"]]
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam ls maps_more --json
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:21] "GET /test_project/file_list?path=maps_more HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file_list?path=maps_more HTTP/1.1" 200 117
   stdout:  [["abs.txt", "file"]]
   stderr:  
.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam create mysession
   stdout:  Session 'mysession' created
   stderr:  
>>>  blender --background --factory-startup -noaudio --python ./tests/blendfile_templates.py -- /tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend /tmp/bam_test/local_store/test_project/mysession /tmp/bam_test/blend_template_deps.json create_from_files NONE 123
   stdout:  b'Error: Not freed memory blocks: 215'
   stderr:  b''
   return:  123

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam deps /tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend --json
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
   stdout:  [
["/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend", "//../../maps/generic.png", "/tmp/bam_test/local_store/test_project/mysession/shots/01/../../maps/generic.png", "OK"],
["/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend", "//maps/special.png", "/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.png", "OK"]]
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'commit shot_01'
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/maps']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/shots']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'commit shot_01', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:23] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22commit+shot_01%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22commit+shot_01%22%7D&command=commit HTTP/1.1" 200 555
   stdout:  Now make a zipfile
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/maps/generic.png'
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/shots/01/shot_01.blend'
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.png'
Adding         tmp/bam_test/remote_store/svn_checkout/maps
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/maps/generic.png
Adding         tmp/bam_test/remote_store/svn_checkout/shots
Adding         tmp/bam_test/remote_store/svn_checkout/shots/01
Adding         tmp/bam_test/remote_store/svn_checkout/shots/01/maps
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.png
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend
Transmitting file data ...
Committed revision 2.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam checkout shots/01/shot_01.blend --output new_out
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:23] "GET /test_project/file?filepath=shots%2F01%2Fshot_01.blend&command=checkout HTTP/1.1" 200 -
INFO:webservice:  Source path: '/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend'
INFO:webservice:  Zip path: '/tmp/tmpn3o4oaoo.zip'
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file?filepath=shots%2F01%2Fshot_01.blend&command=checkout HTTP/1.1" 200 None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
   stdout:  
scanning deps: b'/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend'...
  blend:       b'/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend'

archiving: 3 files
  copying: b'/tmp/__blendfile_temp__/shot_01.blend@' -> <archive>
  copying: b'/tmp/bam_test/remote_store/svn_checkout/shots/01/../../maps/generic.png' -> <archive>
  copying: b'/tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.png' -> <archive>
  written: b'/tmp/tmpn3o4oaoo.zip'
download: [100%]
written: '/tmp/bam_test/local_store/test_project/new_out'
   stderr:  
Exists? /tmp/bam_test/local_store/test_project/new_out/maps/special.png
Exists? /tmp/bam_test/local_store/test_project/new_out/_maps/generic.png
.>>>  svnadmin create test_project
   stdout:  b''
   stderr:  b''
   return:  0
>>>  svn checkout file:///tmp/bam_test/remote_store/svn_repo/test_project /tmp/bam_test/remote_store/svn_checkout
   stdout:  b'Checked out revision 0.'
   stderr:  b''
   return:  0
>>>  svn add /tmp/bam_test/remote_store/svn_checkout/test_project
   stdout:  b'A         /tmp/bam_test/remote_store/svn_checkout/test_project\nA         /tmp/bam_test/remote_store/svn_checkout/test_project/file1'
   stderr:  b''
   return:  0
>>>  svn commit -m 'First commit'
   stdout:  b'Adding         .\nAdding         file1\nTransmitting file data .\nCommitted revision 1.'
   stderr:  b''
   return:  0

  running:  cd '/tmp/bam_test/local_store' ; bam init user@http://localhost:5555/test_project
   stdout:  Project 'test_project' initialized
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam create mysession
   stdout:  Session 'mysession' created
   stderr:  
>>>  blender --background --factory-startup -noaudio --python ./tests/blendfile_templates.py -- /tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend /tmp/bam_test/local_store/test_project/mysession /tmp/bam_test/blend_template_deps.json create_from_file_liblinks /tmp/bam_test/blendfile_create_data.json 123
   stdout:  b"Info: Read library:  '/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend', '//../../../maps/generic.blend'\n[['/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend', 'Scene10', ['/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend']], ['/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend', 'MySpecial', ['/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend']], ['/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend', 'MyGeneric', []]]\n['/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend']\n['/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend']\n[]\n['/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend']\n['/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend']\n['/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend']\nError: Not freed memory blocks: 215"
   stderr:  b''
   return:  123

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam deps /tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend --json
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
   stdout:  [
["/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend", "//maps/special.blend", "/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend", "OK"]]
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project/mysession' ; bam commit -m 'commit shot_01'
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
DEBUG:bam_cli:{}
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'status', '/tmp/bam_test/remote_store/svn_checkout', '--xml']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/maps']
DEBUG:svn:RUN: ['svn', 'add', '/tmp/bam_test/remote_store/svn_checkout/shots']
DEBUG:webservice:{}
DEBUG:svn:RUN: ['svn', 'info', '--xml', '/tmp/bam_test/remote_store/svn_checkout']
DEBUG:svn:RUN: ['svn', 'commit', '/tmp/bam_test/remote_store/svn_checkout', '--no-auth-cache', '--message', 'commit shot_01', '--username', 'user', '--password', 'my_password']
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:24] "PUT /test_project/file?arguments=%7B%22message%22%3A+%22commit+shot_01%22%7D&command=commit HTTP/1.1" 200 -
DEBUG:requests.packages.urllib3.connectionpool:"PUT /test_project/file?arguments=%7B%22message%22%3A+%22commit+shot_01%22%7D&command=commit HTTP/1.1" 200 800
   stdout:  Now make a zipfile
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/maps/generic.blend'
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/shots/01/shot_01.blend'
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/shots/01/maps/special.blend1'
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/shots/01/shot_01.blend1'
  packing (A): '/tmp/bam_test/local_store/test_project/.bam/tmp/shots/01/maps/special.blend'
  packing (A): '/tmp/bam_test/local_store/test_project/mysession/maps/generic.blend1'
Adding         tmp/bam_test/remote_store/svn_checkout/maps
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/maps/generic.blend
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/maps/generic.blend1
Adding         tmp/bam_test/remote_store/svn_checkout/shots
Adding         tmp/bam_test/remote_store/svn_checkout/shots/01
Adding         tmp/bam_test/remote_store/svn_checkout/shots/01/maps
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend1
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend
Adding  (bin)  tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend1
Transmitting file data ......
Committed revision 2.
   stderr:  

  running:  cd '/tmp/bam_test/local_store/test_project' ; bam checkout shots/01/shot_01.blend --output new_out
INFO:requests.packages.urllib3.connectionpool:Starting new HTTP connection (1): localhost
INFO:werkzeug:127.0.0.1 - - [03/Dec/2014 23:16:24] "GET /test_project/file?filepath=shots%2F01%2Fshot_01.blend&command=checkout HTTP/1.1" 200 -
INFO:webservice:  Source path: '/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend'
INFO:webservice:  Zip path: '/tmp/tmpt3bzjuba.zip'
DEBUG:requests.packages.urllib3.connectionpool:"GET /test_project/file?filepath=shots%2F01%2Fshot_01.blend&command=checkout HTTP/1.1" 200 None
INFO:path_walker:~
INFO:path_walker:/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend
INFO:path_walker:None
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:None
  Library: b'/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend'
INFO:path_walker:~
INFO:path_walker:  /tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend
INFO:path_walker:  SCMySpecial
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:  SCMySpecial, WOWorld
    Library: b'/tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend' -> b'/tmp/bam_test/remote_store/svn_checkout/maps/generic.blend'
INFO:path_walker:~
INFO:path_walker:    /tmp/bam_test/remote_store/svn_checkout/maps/generic.blend
INFO:path_walker:    SCMyGeneric
DEBUG:blendfile:normal blendfile detected
DEBUG:blendfile:initializing reading blend-file
DEBUG:blendfile:reading blend-file-header
DEBUG:blendfile:building DNA catalog
DEBUG:blendfile:building #3932 names
DEBUG:blendfile:building #643 types
DEBUG:blendfile:building #643 type-lengths
DEBUG:blendfile:building #567 structures
INFO:path_walker:    SCMyGeneric, WOWorld
   stdout:  
scanning deps: b'/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend'...
  blend:       b'/tmp/bam_test/remote_store/svn_checkout/shots/01/shot_01.blend'
  blend:       b'/tmp/bam_test/remote_store/svn_checkout/shots/01/maps/special.blend'

archiving: 1 files
  copying: b'/tmp/__blendfile_temp__/shot_01.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/maps/special.blend@' -> <archive>
  copying: b'/tmp/__blendfile_temp__/_maps/generic.blend@' -> <archive>
  written: b'/tmp/tmpt3bzjuba.zip'
download: [100%]
written: '/tmp/bam_test/local_store/test_project/new_out'
   stderr:  
Exists? /tmp/bam_test/local_store/test_project/new_out/shot_01.blend
Exists? /tmp/bam_test/local_store/test_project/new_out/maps/special.blend
Exists? /tmp/bam_test/local_store/test_project/new_out/_maps/generic.blend
.
----------------------------------------------------------------------
Ran 15 tests in 13.869s

OK