return simgrid::xbt::string_printf("%i.%i.%i", major, minor, patch);
}
+/* Classes GilScopedAcquire and GilScopedRelease have the same purpose as pybind11::gil_scoped_acquire and
+ * pybind11::gil_scoped_release. Refer to the manual of pybind11 for details:
+ * https://pybind11.readthedocs.io/en/stable/advanced/misc.html#global-interpreter-lock-gil
+ *
+ * The pybind11 versions are however too sophisticated (using TLS for example) and don't work well with all kinds of
+ * contexts.
+ * See also https://github.com/pybind/pybind11/issues/1276, which may be related.
+ *
+ * Briefly, GilScopedAcquire can be used on actor creation to acquire a new PyThreadState. The PyThreadState has to be
+ * released for context switches (i.e. before simcalls). That's the purpose of GilScopedRelease.
+ *
+ * Like their pybind11 counterparts, both classes use a RAII pattern.
+ */
+class XBT_PRIVATE GilScopedAcquire {
+ static PyThreadState* acquire()
+ {
+ PyThreadState* state = PyThreadState_New(PyInterpreterState_Head());
+ PyEval_AcquireThread(state);
+ return state;
+ }
+ static void release(PyThreadState* state)
+ {
+ PyEval_ReleaseThread(state);
+ PyThreadState_Clear(state);
+ PyThreadState_Delete(state);
+ }
+
+ std::unique_ptr<PyThreadState, decltype(&release)> thread_state{acquire(), &release};
+
+public:
+ void reset() { thread_state.reset(); }
+};
+
+class XBT_PRIVATE GilScopedRelease {
+ std::unique_ptr<PyThreadState, decltype(&PyEval_RestoreThread)> thread_state{PyEval_SaveThread(),
+ &PyEval_RestoreThread};
+};
+
} // namespace
PYBIND11_DECLARE_HOLDER_TYPE(T, boost::intrusive_ptr<T>)
.def("info", [](const char* s) { XBT_INFO("%s", s); }, "Display a logging message of 'info' priority.")
.def("error", [](const char* s) { XBT_ERROR("%s", s); }, "Display a logging message of 'error' priority.")
.def("execute", py::overload_cast<double, double>(&simgrid::s4u::this_actor::execute),
+ py::call_guard<GilScopedRelease>(),
"Block the current actor, computing the given amount of flops at the given priority, "
"see :cpp:func:`void simgrid::s4u::this_actor::execute(double, double)`",
py::arg("flops"), py::arg("priority") = 1)
- .def("exec_init", py::overload_cast<double>(&simgrid::s4u::this_actor::exec_init))
+ .def("exec_init", py::overload_cast<double>(&simgrid::s4u::this_actor::exec_init),
+ py::call_guard<GilScopedRelease>())
.def("get_host", &simgrid::s4u::this_actor::get_host, "Retrieves host on which the current actor is located")
- .def("set_host", &simgrid::s4u::this_actor::set_host,
+ .def("set_host", &simgrid::s4u::this_actor::set_host, py::call_guard<GilScopedRelease>(),
"Moves the current actor to another host, see :cpp:func:`void simgrid::s4u::this_actor::set_host()`",
py::arg("dest"))
.def("sleep_for", static_cast<void (*)(double)>(&simgrid::s4u::this_actor::sleep_for),
- "Block the actor sleeping for that amount of seconds, "
- "see :cpp:func:`void simgrid::s4u::this_actor::sleep_for`",
+ py::call_guard<GilScopedRelease>(), "Block the actor sleeping for that amount of seconds, "
+ "see :cpp:func:`void simgrid::s4u::this_actor::sleep_for`",
py::arg("duration"))
.def("sleep_until", static_cast<void (*)(double)>(&simgrid::s4u::this_actor::sleep_until),
- "Block the actor sleeping until the specified timestamp, "
- "see :cpp:func:`void simgrid::s4u::this_actor::sleep_until`",
+ py::call_guard<GilScopedRelease>(), "Block the actor sleeping until the specified timestamp, "
+ "see :cpp:func:`void simgrid::s4u::this_actor::sleep_until`",
py::arg("duration"))
- .def("suspend", &simgrid::s4u::this_actor::suspend,
+ .def("suspend", &simgrid::s4u::this_actor::suspend, py::call_guard<GilScopedRelease>(),
"Suspend the current actor, that is blocked until resume()ed by another actor. "
"see :cpp:func:`void simgrid::s4u::this_actor::suspend`")
- .def("yield_", &simgrid::s4u::this_actor::yield,
+ .def("yield_", &simgrid::s4u::this_actor::yield, py::call_guard<GilScopedRelease>(),
"Yield the actor, see :cpp:func:`void simgrid::s4u::this_actor::yield()`")
- .def("exit", &simgrid::s4u::this_actor::exit, "kill the current actor")
+ .def("exit", &simgrid::s4u::this_actor::exit, py::call_guard<GilScopedRelease>(), "kill the current actor")
.def("on_exit",
[](py::object fun) {
simgrid::s4u::this_actor::on_exit([fun](bool /*failed*/) {
+ GilScopedAcquire py_context; // need a new context for callback
try {
fun();
} catch (const py::error_already_set& e) {
- xbt_die("Error while executing the on_exit lambda: %s", e.what());
+ std::string what = e.what();
+ py_context.reset();
+ xbt_die("Error while executing the on_exit lambda: %s", what.c_str());
}
});
},
- "");
+ py::call_guard<GilScopedRelease>(), "");
/* Class Engine */
py::class_<Engine>(m, "Engine", "Simulation Engine, see :ref:`class s4u::Engine <API_s4u_Engine>`")
.def("load_deployment", &Engine::load_deployment,
"Load a deployment file and launch the actors that it contains, see "
":cpp:func:`simgrid::s4u::Engine::load_deployment()`")
- .def("run", &Engine::run, "Run the simulation")
+ .def("run", &Engine::run, py::call_guard<GilScopedRelease>(), "Run the simulation")
.def("register_actor",
[](Engine* e, const std::string& name, py::object fun_or_class) {
e->register_actor(name, [fun_or_class](std::vector<std::string> args) {
+ GilScopedAcquire py_context;
try {
/* Convert the std::vector into a py::tuple */
py::tuple params(args.size() - 1);
if (py::isinstance<py::function>(res))
res();
} catch (const py::error_already_set& ex) {
- if (ex.matches(pyForcefulKillEx)) {
+ bool ffk = ex.matches(pyForcefulKillEx);
+ py_context.reset();
+ if (ffk) {
XBT_VERB("Actor killed");
- /* Stop here that ForcefulKill exception which was meant to free the RAII stuff on the stack */
- } else {
- throw;
+ /* Forward that ForcefulKill exception */
+ simgrid::ForcefulKillException::do_throw();
}
+ throw;
}
});
},
"Retrieve the cound of defined pstate levels, see :cpp:func:`simgrid::s4u::Host::get_pstate_count`")
.def("get_pstate_speed", &Host::get_pstate_speed,
"Retrieve the maximal speed at the given pstate, see :cpp:func:`simgrid::s4u::Host::get_pstate_speed`")
- .def_property("pstate", &Host::get_pstate, &Host::set_pstate, "The current pstate")
-
- .def("current", &Host::current,
+ .def_property("pstate", &Host::get_pstate,
+ [](Host* h, int i) {
+ GilScopedRelease gil_guard;
+ h->set_pstate(i);
+ },
+ "The current pstate")
+ .def("current", &Host::current, py::call_guard<GilScopedRelease>(),
"Retrieves the host on which the running actor is located, see :cpp:func:`simgrid::s4u::Host::current()`")
.def_property_readonly("name",
[](const Host* self) {
m, "Mailbox", "Mailbox, see :ref:`class s4u::Mailbox <API_s4u_Mailbox>`")
.def("__str__", [](const Mailbox* self) { return std::string("Mailbox(") + self->get_cname() + ")"; },
"Textual representation of the Mailbox`")
- .def("by_name", &Mailbox::by_name,
+ .def("by_name", &Mailbox::by_name, py::call_guard<GilScopedRelease>(),
"Retrieve a Mailbox from its name, see :cpp:func:`simgrid::s4u::Mailbox::by_name()`")
.def_property_readonly("name",
[](const Mailbox* self) {
data.inc_ref();
self->put(data.ptr(), size);
},
+ py::call_guard<GilScopedRelease>(),
"Blocking data transmission, see :cpp:func:`void simgrid::s4u::Mailbox::put(void*, uint64_t)`")
.def("put_async",
[](Mailbox* self, py::object data, int size) {
data.inc_ref();
return self->put_async(data.ptr(), size);
},
+ py::call_guard<GilScopedRelease>(),
"Non-blocking data transmission, see :cpp:func:`void simgrid::s4u::Mailbox::put_async(void*, uint64_t)`")
.def("get",
[](Mailbox* self) {
py::object data = pybind11::reinterpret_steal<py::object>(static_cast<PyObject*>(self->get()));
- data.dec_ref();
+ // data.dec_ref(); // FIXME: why does it break python-actor-create?
return data;
},
+ py::call_guard<GilScopedRelease>(),
"Blocking data reception, see :cpp:func:`void* simgrid::s4u::Mailbox::get()`");
/* Class Comm */
py::class_<simgrid::s4u::Comm, simgrid::s4u::CommPtr>(m, "Comm",
"Communication, see :ref:`class s4u::Comm <API_s4u_Comm>`")
- .def("test", &simgrid::s4u::Comm::test,
+ .def("test", &simgrid::s4u::Comm::test, py::call_guard<GilScopedRelease>(),
"Test whether the communication is terminated, see :cpp:func:`simgrid::s4u::Comm::test()`")
- .def("wait", &simgrid::s4u::Comm::wait,
+ .def("wait", &simgrid::s4u::Comm::wait, py::call_guard<GilScopedRelease>(),
"Block until the completion of that communication, see :cpp:func:`simgrid::s4u::Comm::wait()`")
- .def("wait_all", &simgrid::s4u::Comm::wait_all,
+ .def("wait_all", &simgrid::s4u::Comm::wait_all, py::call_guard<GilScopedRelease>(),
"Block until the completion of all communications in the list, see "
":cpp:func:`simgrid::s4u::Comm::wait_all()`")
- .def("wait_any", &simgrid::s4u::Comm::wait_any,
+ .def("wait_any", &simgrid::s4u::Comm::wait_any, py::call_guard<GilScopedRelease>(),
"Block until the completion of any communication in the list and return the index of the terminated one, "
"see :cpp:func:`simgrid::s4u::Comm::wait_any()`");
/* Class Exec */
py::class_<simgrid::s4u::Exec, simgrid::s4u::ExecPtr>(m, "Exec",
"Execution, see :ref:`class s4u::Exec <API_s4u_Exec>`")
- .def_property_readonly("remaining", &simgrid::s4u::Exec::get_remaining,
+ .def_property_readonly("remaining",
+ [](simgrid::s4u::ExecPtr self) {
+ GilScopedRelease gil_guard;
+ return self->get_remaining();
+ },
"Amount of flops that remain to be computed until completion, see "
":cpp:func:`simgrid::s4u::Exec::get_remaining()`")
- .def_property_readonly("remaining_ratio", &simgrid::s4u::Exec::get_remaining_ratio,
+ .def_property_readonly("remaining_ratio",
+ [](simgrid::s4u::ExecPtr self) {
+ GilScopedRelease gil_guard;
+ return self->get_remaining_ratio();
+ },
"Amount of work remaining until completion from 0 (completely done) to 1 (nothing done "
"yet). See :cpp:func:`simgrid::s4u::Exec::get_remaining_ratio()`")
.def_property("host",
},
&simgrid::s4u::Exec::set_host,
"Host on which this execution runs. See :cpp:func:`simgrid::s4u::ExecSeq::get_host()`")
- .def("test", &simgrid::s4u::Exec::test,
+ .def("test", &simgrid::s4u::Exec::test, py::call_guard<GilScopedRelease>(),
"Test whether the execution is terminated, see :cpp:func:`simgrid::s4u::Exec::test()`")
- .def("cancel", &simgrid::s4u::Exec::cancel, "Cancel that execution, see :cpp:func:`simgrid::s4u::Exec::cancel()`")
- .def("start", &simgrid::s4u::Exec::start, "Start that execution, see :cpp:func:`simgrid::s4u::Exec::start()`")
- .def("wait", &simgrid::s4u::Exec::wait,
+ .def("cancel", &simgrid::s4u::Exec::cancel, py::call_guard<GilScopedRelease>(),
+ "Cancel that execution, see :cpp:func:`simgrid::s4u::Exec::cancel()`")
+ .def("start", &simgrid::s4u::Exec::start, py::call_guard<GilScopedRelease>(),
+ "Start that execution, see :cpp:func:`simgrid::s4u::Exec::start()`")
+ .def("wait", &simgrid::s4u::Exec::wait, py::call_guard<GilScopedRelease>(),
"Block until the completion of that execution, see :cpp:func:`simgrid::s4u::Exec::wait()`");
/* Class Actor */
"application, see :ref:`class s4u::Actor <API_s4u_Actor>`")
.def("create",
[](py::str name, Host* host, py::object fun, py::args args) {
+ fun.inc_ref(); // FIXME: why is this needed for tests like exec-async, exec-dvfs and exec-remote?
+ args.inc_ref(); // FIXME: why is this needed for tests like actor-migrate?
return simgrid::s4u::Actor::create(name, host, [fun, args]() {
+ GilScopedAcquire py_context;
try {
fun(*args);
} catch (const py::error_already_set& ex) {
- if (ex.matches(pyForcefulKillEx)) {
+ bool ffk = ex.matches(pyForcefulKillEx);
+ py_context.reset();
+ if (ffk) {
XBT_VERB("Actor killed");
- /* Stop here that ForcefulKill exception which was meant to free the RAII stuff on the stack */
- } else {
- throw;
+ /* Forward that ForcefulKill exception */
+ simgrid::ForcefulKillException::do_throw();
}
+ throw;
}
});
},
- "Create an actor from a function or an object.")
- .def_property("host", &Actor::get_host, &Actor::set_host, "The host on which this actor is located")
+ py::call_guard<GilScopedRelease>(), "Create an actor from a function or an object.")
+ .def_property("host", &Actor::get_host,
+ [](Actor* a, Host* h) {
+ GilScopedRelease gil_guard;
+ a->set_host(h);
+ },
+ "The host on which this actor is located")
.def_property_readonly("name", &Actor::get_cname, "The name of this actor.")
.def_property_readonly("pid", &Actor::get_pid, "The PID (unique identifier) of this actor.")
.def_property_readonly("ppid", &Actor::get_ppid,
"The PID (unique identifier) of the actor that created this one.")
.def("by_pid", &Actor::by_pid, "Retrieve an actor by its PID")
- .def("daemonize", &Actor::daemonize,
+ .def("daemonize", &Actor::daemonize, py::call_guard<GilScopedRelease>(),
"This actor will be automatically terminated when the last non-daemon actor finishes (more info in the C++ "
"documentation).")
.def("is_daemon", &Actor::is_daemon,
"Returns True if that actor is a daemon and will be terminated automatically when the last non-daemon actor "
"terminates.")
- .def("join", py::overload_cast<double>(&Actor::join),
+ .def("join", py::overload_cast<double>(&Actor::join), py::call_guard<GilScopedRelease>(),
"Wait for the actor to finish (more info in the C++ documentation).", py::arg("timeout"))
- .def("kill", &Actor::kill, "Kill that actor")
- .def("kill_all", &Actor::kill_all, "Kill all actors but the caller.")
+ .def("kill", &Actor::kill, py::call_guard<GilScopedRelease>(), "Kill that actor")
+ .def("kill_all", &Actor::kill_all, py::call_guard<GilScopedRelease>(), "Kill all actors but the caller.")
.def("self", &Actor::self, "Retrieves the current actor.")
.def("is_suspended", &Actor::is_suspended, "Returns True if that actor is currently suspended.")
- .def("suspend", &Actor::suspend, "Suspend that actor, that is blocked until resume()ed by another actor.")
- .def("resume", &Actor::resume, "Resume that actor, that was previously suspend()ed.");
+ .def("suspend", &Actor::suspend, py::call_guard<GilScopedRelease>(),
+ "Suspend that actor, that is blocked until resume()ed by another actor.")
+ .def("resume", &Actor::resume, py::call_guard<GilScopedRelease>(),
+ "Resume that actor, that was previously suspend()ed.");
}
--- /dev/null
+#!/usr/bin/env tesh
+
+$ ${pythoncmd:=python3} ${PYTHON_TOOL_OPTIONS:=} ${bindir:=.}/corrupt-stack.py ${platfdir}/small_platform_fatpipe.xml corrupt-stack_d.xml "--log=root.fmt:[%10.6r]%e(%i:%P@%h)%e%m%n"
+> [ 0.000000] (1:sleeper3@Ruby) 3-Iter 6
+> [ 0.000000] (1:sleeper3@Ruby) 5-Iter 5
+> [ 0.000000] (1:sleeper3@Ruby) 1-Iter 4
+> [ 0.000000] (1:sleeper3@Ruby) 3-Iter 3
+> [ 0.000000] (1:sleeper3@Ruby) 5-Iter 2
+> [ 0.000000] (1:sleeper3@Ruby) 1-Iter 1
+> [ 0.000000] (2:sleeper1@Tremblay) 1-Iter 16
+> [ 0.000000] (2:sleeper1@Tremblay) 3-Iter 15
+> [ 0.000000] (2:sleeper1@Tremblay) 5-Iter 14
+> [ 0.000000] (2:sleeper1@Tremblay) 1-Iter 13
+> [ 0.000000] (2:sleeper1@Tremblay) 3-Iter 12
+> [ 0.000000] (2:sleeper1@Tremblay) 5-Iter 11
+> [ 0.000000] (2:sleeper1@Tremblay) 1-Iter 10
+> [ 0.000000] (2:sleeper1@Tremblay) 3-Iter 9
+> [ 0.000000] (2:sleeper1@Tremblay) 5-Iter 8
+> [ 0.000000] (2:sleeper1@Tremblay) 1-Iter 7
+> [ 0.000000] (2:sleeper1@Tremblay) 3-Iter 6
+> [ 0.000000] (2:sleeper1@Tremblay) 5-Iter 5
+> [ 0.000000] (2:sleeper1@Tremblay) 1-Iter 4
+> [ 0.000000] (2:sleeper1@Tremblay) 3-Iter 3
+> [ 0.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [ 0.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 0.000000] (3:sleeper5@Java) 5-Iter 4
+> [ 0.000000] (3:sleeper5@Java) 1-Iter 3
+> [ 0.000000] (3:sleeper5@Java) 3-Iter 2
+> [ 0.000000] (3:sleeper5@Java) 5-Iter 1
+> [ 1.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 1.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 2.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [ 2.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 3.000000] (1:sleeper3@Ruby) 1-Mid (1)
+> [ 3.000000] (1:sleeper3@Ruby) 1-Done (1)
+> [ 3.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 3.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 3.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [ 4.000000] (2:sleeper1@Tremblay) 3-Mid (3)
+> [ 4.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 5.000000] (3:sleeper5@Java) 5-Mid (1)
+> [ 5.000000] (3:sleeper5@Java) 5-Done (1)
+> [ 5.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 5.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 5.000000] (2:sleeper1@Tremblay) 3-Done (3)
+> [ 6.000000] (1:sleeper3@Ruby) 5-Mid (2)
+> [ 6.000000] (1:sleeper3@Ruby) 1-Iter 1
+> [ 6.000000] (2:sleeper1@Tremblay) 1-Mid (4)
+> [ 6.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [ 6.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 7.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 7.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 8.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [ 8.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 9.000000] (1:sleeper3@Ruby) 1-Mid (1)
+> [ 9.000000] (1:sleeper3@Ruby) 1-Done (1)
+> [ 9.000000] (1:sleeper3@Ruby) 5-Done (2)
+> [ 9.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 9.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 9.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [ 9.000000] (2:sleeper1@Tremblay) 1-Done (4)
+> [ 10.000000] (3:sleeper5@Java) 3-Mid (2)
+> [ 10.000000] (3:sleeper5@Java) 5-Iter 1
+> [ 10.000000] (2:sleeper1@Tremblay) 5-Mid (5)
+> [ 10.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [ 10.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 11.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 11.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 12.000000] (1:sleeper3@Ruby) 3-Mid (3)
+> [ 12.000000] (1:sleeper3@Ruby) 5-Iter 1
+> [ 12.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [ 12.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 13.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 13.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 13.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [ 13.000000] (2:sleeper1@Tremblay) 5-Done (5)
+> [ 14.000000] (2:sleeper1@Tremblay) 3-Mid (6)
+> [ 14.000000] (2:sleeper1@Tremblay) 5-Iter 3
+> [ 14.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [ 14.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 15.000000] (3:sleeper5@Java) 5-Mid (1)
+> [ 15.000000] (3:sleeper5@Java) 5-Done (1)
+> [ 15.000000] (3:sleeper5@Java) 3-Done (2)
+> [ 15.000000] (1:sleeper3@Ruby) 5-Mid (1)
+> [ 15.000000] (1:sleeper3@Ruby) 5-Done (1)
+> [ 15.000000] (1:sleeper3@Ruby) 3-Done (3)
+> [ 15.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 15.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 16.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [ 16.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 17.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 17.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 17.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [ 18.000000] (1:sleeper3@Ruby) 1-Mid (4)
+> [ 18.000000] (1:sleeper3@Ruby) 3-Iter 2
+> [ 18.000000] (1:sleeper3@Ruby) 5-Iter 1
+> [ 18.000000] (2:sleeper1@Tremblay) 5-Mid (3)
+> [ 18.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 19.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 19.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 19.000000] (2:sleeper1@Tremblay) 5-Done (3)
+> [ 19.000000] (2:sleeper1@Tremblay) 3-Done (6)
+> [ 20.000000] (3:sleeper5@Java) 1-Mid (3)
+> [ 20.000000] (3:sleeper5@Java) 3-Iter 1
+> [ 20.000000] (2:sleeper1@Tremblay) 1-Mid (7)
+> [ 20.000000] (2:sleeper1@Tremblay) 3-Iter 3
+> [ 20.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [ 20.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 21.000000] (1:sleeper3@Ruby) 5-Mid (1)
+> [ 21.000000] (1:sleeper3@Ruby) 5-Done (1)
+> [ 21.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 21.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 22.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [ 22.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 23.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 23.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 23.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [ 24.000000] (1:sleeper3@Ruby) 3-Mid (2)
+> [ 24.000000] (1:sleeper3@Ruby) 5-Iter 1
+> [ 24.000000] (2:sleeper1@Tremblay) 3-Mid (3)
+> [ 24.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 25.000000] (3:sleeper5@Java) 3-Mid (1)
+> [ 25.000000] (3:sleeper5@Java) 3-Done (1)
+> [ 25.000000] (3:sleeper5@Java) 1-Done (3)
+> [ 25.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 25.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 25.000000] (2:sleeper1@Tremblay) 3-Done (3)
+> [ 25.000000] (2:sleeper1@Tremblay) 1-Done (7)
+> [ 26.000000] (2:sleeper1@Tremblay) 5-Mid (8)
+> [ 26.000000] (2:sleeper1@Tremblay) 1-Iter 4
+> [ 26.000000] (2:sleeper1@Tremblay) 3-Iter 3
+> [ 26.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [ 26.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 27.000000] (1:sleeper3@Ruby) 5-Mid (1)
+> [ 27.000000] (1:sleeper3@Ruby) 5-Done (1)
+> [ 27.000000] (1:sleeper3@Ruby) 3-Done (2)
+> [ 27.000000] (1:sleeper3@Ruby) 1-Done (4)
+> [ 27.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 27.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 28.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [ 28.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 29.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 29.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 29.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [ 30.000000] (3:sleeper5@Java) 5-Mid (4)
+> [ 30.000000] (3:sleeper5@Java) 1-Iter 2
+> [ 30.000000] (3:sleeper5@Java) 3-Iter 1
+> [ 30.000000] (1:sleeper3@Ruby) 5-Mid (5)
+> [ 30.000000] (1:sleeper3@Ruby) 1-Iter 2
+> [ 30.000000] (1:sleeper3@Ruby) 3-Iter 1
+> [ 30.000000] (2:sleeper1@Tremblay) 3-Mid (3)
+> [ 30.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 31.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 31.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 31.000000] (2:sleeper1@Tremblay) 3-Done (3)
+> [ 32.000000] (2:sleeper1@Tremblay) 1-Mid (4)
+> [ 32.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [ 32.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 33.000000] (1:sleeper3@Ruby) 3-Mid (1)
+> [ 33.000000] (1:sleeper3@Ruby) 3-Done (1)
+> [ 33.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 33.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 34.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [ 34.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 35.000000] (3:sleeper5@Java) 3-Mid (1)
+> [ 35.000000] (3:sleeper5@Java) 3-Done (1)
+> [ 35.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 35.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 35.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [ 35.000000] (2:sleeper1@Tremblay) 1-Done (4)
+> [ 35.000000] (2:sleeper1@Tremblay) 5-Done (8)
+> [ 36.000000] (1:sleeper3@Ruby) 1-Mid (2)
+> [ 36.000000] (1:sleeper3@Ruby) 3-Iter 1
+> [ 36.000000] (2:sleeper1@Tremblay) 3-Mid (9)
+> [ 36.000000] (2:sleeper1@Tremblay) 5-Iter 4
+> [ 36.000000] (2:sleeper1@Tremblay) 1-Iter 3
+> [ 36.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [ 36.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 37.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 37.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 38.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [ 38.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 39.000000] (1:sleeper3@Ruby) 3-Mid (1)
+> [ 39.000000] (1:sleeper3@Ruby) 3-Done (1)
+> [ 39.000000] (1:sleeper3@Ruby) 1-Done (2)
+> [ 39.000000] (1:sleeper3@Ruby) 5-Done (5)
+> [ 39.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 39.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 39.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [ 40.000000] (3:sleeper5@Java) 1-Mid (2)
+> [ 40.000000] (3:sleeper5@Java) 3-Iter 1
+> [ 40.000000] (2:sleeper1@Tremblay) 1-Mid (3)
+> [ 40.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 41.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 41.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 41.000000] (2:sleeper1@Tremblay) 1-Done (3)
+> [ 42.000000] (1:sleeper3@Ruby) 3-Mid (6)
+> [ 42.000000] (1:sleeper3@Ruby) 5-Iter 3
+> [ 42.000000] (1:sleeper3@Ruby) 1-Iter 2
+> [ 42.000000] (1:sleeper3@Ruby) 3-Iter 1
+> [ 42.000000] (2:sleeper1@Tremblay) 5-Mid (4)
+> [ 42.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [ 42.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 43.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 43.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 44.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [ 44.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 45.000000] (3:sleeper5@Java) 3-Mid (1)
+> [ 45.000000] (3:sleeper5@Java) 3-Done (1)
+> [ 45.000000] (3:sleeper5@Java) 1-Done (2)
+> [ 45.000000] (3:sleeper5@Java) 5-Done (4)
+> [ 45.000000] (1:sleeper3@Ruby) 3-Mid (1)
+> [ 45.000000] (1:sleeper3@Ruby) 3-Done (1)
+> [ 45.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 45.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 45.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [ 45.000000] (2:sleeper1@Tremblay) 5-Done (4)
+> [ 45.000000] (2:sleeper1@Tremblay) 3-Done (9)
+> [ 46.000000] (2:sleeper1@Tremblay) 1-Mid (10)
+> [ 46.000000] (2:sleeper1@Tremblay) 3-Iter 5
+> [ 46.000000] (2:sleeper1@Tremblay) 5-Iter 4
+> [ 46.000000] (2:sleeper1@Tremblay) 1-Iter 3
+> [ 46.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [ 46.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 47.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 47.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 48.000000] (1:sleeper3@Ruby) 1-Mid (2)
+> [ 48.000000] (1:sleeper3@Ruby) 3-Iter 1
+> [ 48.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [ 48.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 49.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 49.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 49.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [ 50.000000] (2:sleeper1@Tremblay) 1-Mid (3)
+> [ 50.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 51.000000] (1:sleeper3@Ruby) 3-Mid (1)
+> [ 51.000000] (1:sleeper3@Ruby) 3-Done (1)
+> [ 51.000000] (1:sleeper3@Ruby) 1-Done (2)
+> [ 51.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 51.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 51.000000] (2:sleeper1@Tremblay) 1-Done (3)
+> [ 52.000000] (2:sleeper1@Tremblay) 5-Mid (4)
+> [ 52.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [ 52.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 53.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 53.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 54.000000] (1:sleeper3@Ruby) 5-Mid (3)
+> [ 54.000000] (1:sleeper3@Ruby) 1-Iter 1
+> [ 54.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [ 54.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 55.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 55.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 55.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [ 55.000000] (2:sleeper1@Tremblay) 5-Done (4)
+> [ 56.000000] (2:sleeper1@Tremblay) 3-Mid (5)
+> [ 56.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [ 56.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 57.000000] (1:sleeper3@Ruby) 1-Mid (1)
+> [ 57.000000] (1:sleeper3@Ruby) 1-Done (1)
+> [ 57.000000] (1:sleeper3@Ruby) 5-Done (3)
+> [ 57.000000] (1:sleeper3@Ruby) 3-Done (6)
+> [ 57.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 57.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 58.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [ 58.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 59.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 59.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 59.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [ 59.000000] (2:sleeper1@Tremblay) 3-Done (5)
+> [ 59.000000] (2:sleeper1@Tremblay) 1-Done (10)
+> [ 60.000000] (2:sleeper1@Tremblay) 5-Mid (11)
+> [ 60.000000] (2:sleeper1@Tremblay) 1-Iter 5
+> [ 60.000000] (2:sleeper1@Tremblay) 3-Iter 4
+> [ 60.000000] (2:sleeper1@Tremblay) 5-Iter 3
+> [ 60.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [ 60.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 61.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 61.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 62.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [ 62.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 63.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 63.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 63.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [ 64.000000] (2:sleeper1@Tremblay) 5-Mid (3)
+> [ 64.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 65.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 65.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 65.000000] (2:sleeper1@Tremblay) 5-Done (3)
+> [ 66.000000] (2:sleeper1@Tremblay) 3-Mid (4)
+> [ 66.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [ 66.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 67.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 67.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 68.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [ 68.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 69.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 69.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 69.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [ 69.000000] (2:sleeper1@Tremblay) 3-Done (4)
+> [ 70.000000] (2:sleeper1@Tremblay) 1-Mid (5)
+> [ 70.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [ 70.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 71.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 71.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 72.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [ 72.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 73.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 73.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 73.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [ 73.000000] (2:sleeper1@Tremblay) 1-Done (5)
+> [ 73.000000] (2:sleeper1@Tremblay) 5-Done (11)
+> [ 74.000000] (2:sleeper1@Tremblay) 3-Mid (12)
+> [ 74.000000] (2:sleeper1@Tremblay) 5-Iter 6
+> [ 74.000000] (2:sleeper1@Tremblay) 1-Iter 5
+> [ 74.000000] (2:sleeper1@Tremblay) 3-Iter 4
+> [ 74.000000] (2:sleeper1@Tremblay) 5-Iter 3
+> [ 74.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [ 74.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 75.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 75.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 76.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [ 76.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 77.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 77.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 77.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [ 78.000000] (2:sleeper1@Tremblay) 5-Mid (3)
+> [ 78.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 79.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 79.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 79.000000] (2:sleeper1@Tremblay) 5-Done (3)
+> [ 80.000000] (2:sleeper1@Tremblay) 3-Mid (4)
+> [ 80.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [ 80.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 81.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 81.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 82.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [ 82.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 83.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 83.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 83.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [ 83.000000] (2:sleeper1@Tremblay) 3-Done (4)
+> [ 84.000000] (2:sleeper1@Tremblay) 1-Mid (5)
+> [ 84.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [ 84.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 85.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 85.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 86.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [ 86.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 87.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 87.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 87.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [ 87.000000] (2:sleeper1@Tremblay) 1-Done (5)
+> [ 88.000000] (2:sleeper1@Tremblay) 5-Mid (6)
+> [ 88.000000] (2:sleeper1@Tremblay) 1-Iter 3
+> [ 88.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [ 88.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 89.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 89.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 90.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [ 90.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 91.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 91.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 91.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [ 92.000000] (2:sleeper1@Tremblay) 1-Mid (3)
+> [ 92.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [ 93.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [ 93.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [ 93.000000] (2:sleeper1@Tremblay) 1-Done (3)
+> [ 93.000000] (2:sleeper1@Tremblay) 5-Done (6)
+> [ 93.000000] (2:sleeper1@Tremblay) 3-Done (12)
+> [ 94.000000] (2:sleeper1@Tremblay) 1-Mid (13)
+> [ 94.000000] (2:sleeper1@Tremblay) 3-Iter 6
+> [ 94.000000] (2:sleeper1@Tremblay) 5-Iter 5
+> [ 94.000000] (2:sleeper1@Tremblay) 1-Iter 4
+> [ 94.000000] (2:sleeper1@Tremblay) 3-Iter 3
+> [ 94.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [ 94.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 95.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 95.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 96.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [ 96.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [ 97.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [ 97.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [ 97.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [ 98.000000] (2:sleeper1@Tremblay) 3-Mid (3)
+> [ 98.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [ 99.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [ 99.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [ 99.000000] (2:sleeper1@Tremblay) 3-Done (3)
+> [100.000000] (2:sleeper1@Tremblay) 1-Mid (4)
+> [100.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [100.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [101.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [101.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [102.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [102.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [103.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [103.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [103.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [103.000000] (2:sleeper1@Tremblay) 1-Done (4)
+> [104.000000] (2:sleeper1@Tremblay) 5-Mid (5)
+> [104.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [104.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [105.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [105.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [106.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [106.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [107.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [107.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [107.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [107.000000] (2:sleeper1@Tremblay) 5-Done (5)
+> [108.000000] (2:sleeper1@Tremblay) 3-Mid (6)
+> [108.000000] (2:sleeper1@Tremblay) 5-Iter 3
+> [108.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [108.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [109.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [109.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [110.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [110.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [111.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [111.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [111.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [112.000000] (2:sleeper1@Tremblay) 5-Mid (3)
+> [112.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [113.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [113.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [113.000000] (2:sleeper1@Tremblay) 5-Done (3)
+> [113.000000] (2:sleeper1@Tremblay) 3-Done (6)
+> [113.000000] (2:sleeper1@Tremblay) 1-Done (13)
+> [114.000000] (2:sleeper1@Tremblay) 5-Mid (14)
+> [114.000000] (2:sleeper1@Tremblay) 1-Iter 7
+> [114.000000] (2:sleeper1@Tremblay) 3-Iter 6
+> [114.000000] (2:sleeper1@Tremblay) 5-Iter 5
+> [114.000000] (2:sleeper1@Tremblay) 1-Iter 4
+> [114.000000] (2:sleeper1@Tremblay) 3-Iter 3
+> [114.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [114.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [115.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [115.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [116.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [116.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [117.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [117.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [117.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [118.000000] (2:sleeper1@Tremblay) 3-Mid (3)
+> [118.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [119.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [119.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [119.000000] (2:sleeper1@Tremblay) 3-Done (3)
+> [120.000000] (2:sleeper1@Tremblay) 1-Mid (4)
+> [120.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [120.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [121.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [121.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [122.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [122.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [123.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [123.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [123.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [123.000000] (2:sleeper1@Tremblay) 1-Done (4)
+> [124.000000] (2:sleeper1@Tremblay) 5-Mid (5)
+> [124.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [124.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [125.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [125.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [126.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [126.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [127.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [127.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [127.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [127.000000] (2:sleeper1@Tremblay) 5-Done (5)
+> [128.000000] (2:sleeper1@Tremblay) 3-Mid (6)
+> [128.000000] (2:sleeper1@Tremblay) 5-Iter 3
+> [128.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [128.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [129.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [129.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [130.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [130.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [131.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [131.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [131.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [132.000000] (2:sleeper1@Tremblay) 5-Mid (3)
+> [132.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [133.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [133.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [133.000000] (2:sleeper1@Tremblay) 5-Done (3)
+> [133.000000] (2:sleeper1@Tremblay) 3-Done (6)
+> [134.000000] (2:sleeper1@Tremblay) 1-Mid (7)
+> [134.000000] (2:sleeper1@Tremblay) 3-Iter 3
+> [134.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [134.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [135.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [135.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [136.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [136.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [137.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [137.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [137.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [138.000000] (2:sleeper1@Tremblay) 3-Mid (3)
+> [138.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [139.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [139.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [139.000000] (2:sleeper1@Tremblay) 3-Done (3)
+> [139.000000] (2:sleeper1@Tremblay) 1-Done (7)
+> [139.000000] (2:sleeper1@Tremblay) 5-Done (14)
+> [140.000000] (2:sleeper1@Tremblay) 3-Mid (15)
+> [140.000000] (2:sleeper1@Tremblay) 5-Iter 7
+> [140.000000] (2:sleeper1@Tremblay) 1-Iter 6
+> [140.000000] (2:sleeper1@Tremblay) 3-Iter 5
+> [140.000000] (2:sleeper1@Tremblay) 5-Iter 4
+> [140.000000] (2:sleeper1@Tremblay) 1-Iter 3
+> [140.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [140.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [141.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [141.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [142.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [142.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [143.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [143.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [143.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [144.000000] (2:sleeper1@Tremblay) 1-Mid (3)
+> [144.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [145.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [145.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [145.000000] (2:sleeper1@Tremblay) 1-Done (3)
+> [146.000000] (2:sleeper1@Tremblay) 5-Mid (4)
+> [146.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [146.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [147.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [147.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [148.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [148.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [149.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [149.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [149.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [149.000000] (2:sleeper1@Tremblay) 5-Done (4)
+> [150.000000] (2:sleeper1@Tremblay) 3-Mid (5)
+> [150.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [150.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [151.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [151.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [152.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [152.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [153.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [153.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [153.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [153.000000] (2:sleeper1@Tremblay) 3-Done (5)
+> [154.000000] (2:sleeper1@Tremblay) 1-Mid (6)
+> [154.000000] (2:sleeper1@Tremblay) 3-Iter 3
+> [154.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [154.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [155.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [155.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [156.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [156.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [157.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [157.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [157.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [158.000000] (2:sleeper1@Tremblay) 3-Mid (3)
+> [158.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [159.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [159.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [159.000000] (2:sleeper1@Tremblay) 3-Done (3)
+> [159.000000] (2:sleeper1@Tremblay) 1-Done (6)
+> [160.000000] (2:sleeper1@Tremblay) 5-Mid (7)
+> [160.000000] (2:sleeper1@Tremblay) 1-Iter 3
+> [160.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [160.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [161.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [161.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [162.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [162.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [163.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [163.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [163.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [164.000000] (2:sleeper1@Tremblay) 1-Mid (3)
+> [164.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [165.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [165.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [165.000000] (2:sleeper1@Tremblay) 1-Done (3)
+> [165.000000] (2:sleeper1@Tremblay) 5-Done (7)
+> [165.000000] (2:sleeper1@Tremblay) 3-Done (15)
+> [166.000000] (2:sleeper1@Tremblay) 1-Mid (16)
+> [166.000000] (2:sleeper1@Tremblay) 3-Iter 8
+> [166.000000] (2:sleeper1@Tremblay) 5-Iter 7
+> [166.000000] (2:sleeper1@Tremblay) 1-Iter 6
+> [166.000000] (2:sleeper1@Tremblay) 3-Iter 5
+> [166.000000] (2:sleeper1@Tremblay) 5-Iter 4
+> [166.000000] (2:sleeper1@Tremblay) 1-Iter 3
+> [166.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [166.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [167.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [167.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [168.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [168.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [169.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [169.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [169.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [170.000000] (2:sleeper1@Tremblay) 1-Mid (3)
+> [170.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [171.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [171.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [171.000000] (2:sleeper1@Tremblay) 1-Done (3)
+> [172.000000] (2:sleeper1@Tremblay) 5-Mid (4)
+> [172.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [172.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [173.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [173.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [174.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [174.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [175.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [175.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [175.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [175.000000] (2:sleeper1@Tremblay) 5-Done (4)
+> [176.000000] (2:sleeper1@Tremblay) 3-Mid (5)
+> [176.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [176.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [177.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [177.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [178.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [178.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [179.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [179.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [179.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [179.000000] (2:sleeper1@Tremblay) 3-Done (5)
+> [180.000000] (2:sleeper1@Tremblay) 1-Mid (6)
+> [180.000000] (2:sleeper1@Tremblay) 3-Iter 3
+> [180.000000] (2:sleeper1@Tremblay) 5-Iter 2
+> [180.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [181.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [181.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [182.000000] (2:sleeper1@Tremblay) 5-Mid (2)
+> [182.000000] (2:sleeper1@Tremblay) 1-Iter 1
+> [183.000000] (2:sleeper1@Tremblay) 1-Mid (1)
+> [183.000000] (2:sleeper1@Tremblay) 1-Done (1)
+> [183.000000] (2:sleeper1@Tremblay) 5-Done (2)
+> [184.000000] (2:sleeper1@Tremblay) 3-Mid (3)
+> [184.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [185.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [185.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [185.000000] (2:sleeper1@Tremblay) 3-Done (3)
+> [185.000000] (2:sleeper1@Tremblay) 1-Done (6)
+> [186.000000] (2:sleeper1@Tremblay) 5-Mid (7)
+> [186.000000] (2:sleeper1@Tremblay) 1-Iter 3
+> [186.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [186.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [187.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [187.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [188.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [188.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [189.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [189.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [189.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [190.000000] (2:sleeper1@Tremblay) 1-Mid (3)
+> [190.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [191.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [191.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [191.000000] (2:sleeper1@Tremblay) 1-Done (3)
+> [191.000000] (2:sleeper1@Tremblay) 5-Done (7)
+> [192.000000] (2:sleeper1@Tremblay) 3-Mid (8)
+> [192.000000] (2:sleeper1@Tremblay) 5-Iter 4
+> [192.000000] (2:sleeper1@Tremblay) 1-Iter 3
+> [192.000000] (2:sleeper1@Tremblay) 3-Iter 2
+> [192.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [193.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [193.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [194.000000] (2:sleeper1@Tremblay) 3-Mid (2)
+> [194.000000] (2:sleeper1@Tremblay) 5-Iter 1
+> [195.000000] (2:sleeper1@Tremblay) 5-Mid (1)
+> [195.000000] (2:sleeper1@Tremblay) 5-Done (1)
+> [195.000000] (2:sleeper1@Tremblay) 3-Done (2)
+> [196.000000] (2:sleeper1@Tremblay) 1-Mid (3)
+> [196.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [197.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [197.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [197.000000] (2:sleeper1@Tremblay) 1-Done (3)
+> [198.000000] (2:sleeper1@Tremblay) 5-Mid (4)
+> [198.000000] (2:sleeper1@Tremblay) 1-Iter 2
+> [198.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [199.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [199.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [200.000000] (2:sleeper1@Tremblay) 1-Mid (2)
+> [200.000000] (2:sleeper1@Tremblay) 3-Iter 1
+> [201.000000] (2:sleeper1@Tremblay) 3-Mid (1)
+> [201.000000] (2:sleeper1@Tremblay) 3-Done (1)
+> [201.000000] (2:sleeper1@Tremblay) 1-Done (2)
+> [201.000000] (2:sleeper1@Tremblay) 5-Done (4)
+> [201.000000] (2:sleeper1@Tremblay) 3-Done (8)
+> [201.000000] (2:sleeper1@Tremblay) 1-Done (16)
+> [201.000000] (0:maestro@) Finalize!