= Nebulous Gory Details: neb-touch =

[[PageOutline]]

neb-touch has a call to two nebulous "primitive" functions: neb_stat and neb_create

== Command / Context ==
  neb-touch this/is/a/test/never/done/before
 
  * Key.pm was modified to have log messages/Key.pm and Nebulous.pm log level both set to $DEBUG
  * Tests were performed on ippc01 with NEB_SERVER set to http://ippc01.ifa.hawaii.edu:80/nebulous as ipp user

== Trace Analysis ==
  (see next section for full trace text file)

  The trace shows the operations in Nebulous on the server side. The trace is an excerpt of the nebulous.log file.

 * Two SOAP messages: stat_object and create_object.
   Comment: Nothing interesting here.

 * stat_object analysis:
   * 4 calls to _stringify_key with the same arguments

 * create_object analysis:
   * 16! calls to _stringify_key with the same arguments
   * 4 calls to Nebulous::Server::_db_index_for_key with the same arguments

(Another experiment with neb-touch neb://ippc01.0/that/command/has/never/been/run give 23 calls to _stringify_key and 5 to _db_index_for_key for create_object -- stat_object "call stack" is unchanged)

== SQL statements decomposition: Stat_object statement ==

{{{
        SELECT
            so.so_id,
            so.ext_id,
            attr.read_lock,
            attr.write_lock,
            attr.epoch,
            attr.mtime,
            SUM(available) as available,
            COUNT(instance.so_id) as instances
        FROM storage_object as so
        JOIN storage_object_attr as attr
            USING (so_id)
        JOIN instance
            USING (so_id)
        LEFT JOIN mountedvol
            USING(vol_id)
        WHERE ext_id = ?
        GROUP BY so.so_id
}}}

with ? = this/is/a/test/never/done/before

''Comment:'' Could be simplified since the only information which is used is (at the neb-touch script level): is there something called like that?! No need for so many joins!

''Suggestion'': 
* Test existence with
{{{
SELECT ext_id FROM storage_object WHERE ext_id = ? LIMIT 1
}}}

* Since looking for the string is costly, it would be interesting to DESIGN the tables in the database so that search can be efficient. For instance, 10-bits string hash partitioning of tables based on ext_id might be interesting. It makes 1024 partitions for one table (and that's unfortunately the maximum). For information, the storage_object table currently currently contains about 3.7e+8 entries.

''Implementation'': in modified ''Server.pm'' as ''exists_object''
== SQL statements decomposition: Create_object statements ==

   * Statement 1:
{{{
        INSERT INTO storage_object
        (so_id, ext_id, ext_id_basename, type, dir_id)
        VALUES (?, ?, ?, 'REG_FILE', ?)
}}}
with ? = ('NULL', 'this/is/a/test/never/done/before', 'before', 12257)

   ''Comment:'' Insterting 'NULL' while the value is auto-incremented leads to a warning.

   * Statement 2:

{{{
        SELECT LAST_INSERT_ID()
}}}

   ''Comment:'' Sets the ''so_id'' value (to 804337).

   * Statement 3

{{{
        INSERT INTO storage_object_attr
        (so_id, read_lock, write_lock)
        VALUES (?, 0, NULL)
}}}
    
with ? = 804337

   ''Comment:'' Replace so_id by LAST_INSERT_ID()?

   * Statement 4

{{{
        INSERT INTO instance 
        (so_id, vol_id, uri)
        VALUES (LAST_INSERT_ID(), ?, 'error')
}}}
with ? = 1

     ''Comment:'' See below

   * Statement 5

{{{
        SELECT LAST_INSERT_ID()
}}}
   ''Comment:'' Set the ''ins_id'' value

   * Statement 6

{{{
        UPDATE instance
        SET vol_id = ?, uri = ?
        WHERE ins_id = ?
}}}
where ? = (1, 'file:///export/ippc01.0/nebulous/96/9a/584332.this:is:a:test:never:done:before', 584332)

   ''Comment:'' Between SQL statements 4 and 6, the ''_create_empty_instance_file'' function is called. 
   Its purpose is to create a file and generate its URI, that is something like 
   file:///export/ippc01.0/nebulous/a9/56/584333.that:command:has:never:been:run
   No SQL statement is performed between them. Statements 4 to 6 could be replaced by a single one:

{{{
        INSERT INTO instance 
        (so_id, vol_id, uri)
        VALUES (LAST_INSERT_ID(), ?, ?)
}}}
   where ? = (1, 'file:///export/ippc01.0/nebulous/96/9a/584332.this:is:a:test:never:done:before')

== Full Trace ==
{{{
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::SOAP::AUTOLOAD - ################################
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::SOAP::AUTOLOAD - SOAP [stat_object]
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::SOAP::AUTOLOAD -   Arguments count: 1
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::SOAP::AUTOLOAD -     Argument [this/is/a/test/never/done/before]
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::SOAP::AUTOLOAD - ################################
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::stat_object - entered - this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_is_valid_object_key - entered - this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::parse_neb_key - entered - parse_neb_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::parse_neb_key -   parse_neb_key Argument 1/key: this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::parse_neb_key -   parse_neb_key Argument 2/volume: undefined
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::parse_neb_volume - entered - parse_neb_volume
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::parse_neb_key - leaving - parse_neb_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_is_valid_object_key - key neb:///this/is/a/test/never/done/before not found in cache
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::db - entered - neb:///this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - entered - neb:///this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - index is 0
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - leaving
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_for_index - entered - 0
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_for_index - db handle is still alive
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_is_valid_object_key - key not found in db
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_is_valid_object_key - leaving
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::SOAP::AUTOLOAD - ################################
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::SOAP::AUTOLOAD - SOAP [create_object]
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::SOAP::AUTOLOAD -   Arguments count: 2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::SOAP::AUTOLOAD -     Argument [this/is/a/test/never/done/before]
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::SOAP::AUTOLOAD -     Argument udnfiend
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::SOAP::AUTOLOAD - ################################
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - entered - this/is/a/test/never/done/before 
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::parse_neb_key - entered - parse_neb_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::parse_neb_key -   parse_neb_key Argument 1/key: this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::parse_neb_key -   parse_neb_key Argument 2/volume: undefined
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::parse_neb_volume - entered - parse_neb_volume
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::parse_neb_key - leaving - parse_neb_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::db - entered - neb:///this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - entered - neb:///this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - index is 0
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - leaving
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_for_index - entered - 0
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_for_index - db handle is still alive
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_get_storage_volume - entered - neb:///this/is/a/test/never/done/before  
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::db - entered - neb:///this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - entered - neb:///this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - index is 0
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - leaving
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_for_index - entered - 0
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_for_index - db handle is still alive
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_get_storage_volume - leaving
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_resolve_dir_parent_id - entered - key neb:///this/is/a/test/never/done/before create 1
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::db - entered - neb:///this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - entered - neb:///this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - index is 0
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - leaving
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_for_index - entered - 0
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_for_index - db handle is still alive
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_resolve_dir_parent_id - looking for dirs - this : is : a : test : never : done
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_resolve_dir_parent_id - resolved this to dir_id: 2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_resolve_dir_parent_id - resolved is to dir_id: 3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_resolve_dir_parent_id - resolved a to dir_id: 4
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_resolve_dir_parent_id - resolved test to dir_id: 12249
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_resolve_dir_parent_id - leaving
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - TRANS block
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - Eval 1:
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - 
        INSERT INTO storage_object
        (so_id, ext_id, ext_id_basename, type, dir_id)
        VALUES (?, ?, ?, 'REG_FILE', ?)
    
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - 12257
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - Eval 2:
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - 
        SELECT LAST_INSERT_ID()
    
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - Eval 3:
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - 
        INSERT INTO storage_object_attr
        (so_id, read_lock, write_lock)
        VALUES (?, 0, NULL)
    
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - 804337
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - Eval 4:
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - 
        INSERT INTO instance 
        (so_id, vol_id, uri)
        VALUES (LAST_INSERT_ID(), ?, 'error')
    
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - 1
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - Eval 5:
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - 
        SELECT LAST_INSERT_ID()
    
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_create_empty_instance_file - entered - neb:///this/is/a/test/never/done/before 804337 584332 /export/ippc01.0/nebulous 0
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::db - entered - neb:///this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - entered - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg1this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg2
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - _stringify_key: arg3
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Key::_stringify_key - leaving - _stringify_key
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - entered - neb:///this/is/a/test/never/done/before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - index is 0
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_index_for_key - leaving
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_for_index - entered - 0
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_db_for_index - db handle is still alive
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_retry - entered - CODE(0x14ffa20)
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_create_empty_instance_file - generated uri file:///export/ippc01.0/nebulous/96/9a/584332.this:is:a:test:never:done:before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_create_empty_file - entered - /export/ippc01.0/nebulous/96/9a/584332.this:is:a:test:never:done:before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_retry - entered - CODE(0x1b8cc30)
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_retry - entered - CODE(0x1b8cc30)
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_retry - entered - CODE(0x1b8cc30)
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::_retry - entered - CODE(0x1b8cc30)
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - created file:///export/ippc01.0/nebulous/96/9a/584332.this:is:a:test:never:done:before on volume ID: 1
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - Eval 6
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - 
        UPDATE instance
        SET vol_id = ?, uri = ?
        WHERE ins_id = ?
    
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - 1
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - file:///export/ippc01.0/nebulous/96/9a/584332.this:is:a:test:never:done:before
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - 584332
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - commit
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - key added to cache
2011-02-09 16:11:49 | ippc01 | DEBUG | Nebulous::Server::create_object - leaving

}}}