| Version 26 (modified by , 15 years ago) ( diff ) |
|---|
Nebulous Gory Details
neb-touch
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)
- Two SOAP messages: stat_object and create_object. It was expected.
- 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 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 with
SELECT ext_id FROM storage_object WHERE ext_id = ? LIMIT 1
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
