ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=endpoint_list elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=endpoint_list elapsed_ms=457 budget=none json=false show_all=true producer=loading_subnets
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.subnets elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.subnets elapsed_ms=2 budget=none outcome=returned rows=3
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.read_model elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.node_identity elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.node_identity elapsed_ms=0 budget=none outcome=returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.identity elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.identity elapsed_ms=0 budget=none outcome=resolved producer=endpoint_env
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.enumerate elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="chert" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=1 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="deployah" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Ok(()) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="doyle" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="emphasys" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Ok(()) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="engine-room" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="flynn" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="gears" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Ok(()) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="hertz" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="hfen-user" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="lia" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="liam" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Ok(()) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="mobile-gw" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Ok(()) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="perri" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="sparrow" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=1 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="swan" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="todlando" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="webbie" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=1 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.enumerate elapsed_ms=54 budget=none outcome=returned rows=17 producer=scan_complete
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshots elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none subnet="BIGNET"
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none outcome=read bytes=20204
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_parse elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_parse elapsed_ms=0 budget=none outcome=parsed
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none subnet="BS-Resources"
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none outcome=read bytes=7822
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_parse elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_parse elapsed_ms=0 budget=none outcome=parsed
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none subnet="SPT_DEV"
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none outcome=read bytes=11241
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_parse elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_parse elapsed_ms=0 budget=none outcome=parsed
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none subnet="SPT_MANTLE"
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none outcome=read bytes=11241
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_parse elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_parse elapsed_ms=0 budget=none outcome=parsed
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none subnet="TESUTO"
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none outcome=read bytes=8807
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_parse elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_parse elapsed_ms=0 budget=none outcome=parsed
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none subnet="ws272-rig"
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_read elapsed_ms=0 budget=none outcome=read bytes=9248
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_parse elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshot_parse elapsed_ms=0 budget=none outcome=parsed
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.snapshots elapsed_ms=4 budget=none outcome=returned snapshots=6 producer=scan_complete
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.visibility elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.visibility elapsed_ms=0 budget=none outcome=returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.project_index elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.project_index elapsed_ms=0 budget=none outcome=returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.enumerate elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="chert" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="deployah" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Ok(()) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="doyle" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="emphasys" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Ok(()) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="engine-room" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="flynn" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="gears" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Ok(()) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="hertz" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="hfen-user" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="lia" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="liam" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Ok(()) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="mobile-gw" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Ok(()) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="perri" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="sparrow" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=1 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="swan" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=1 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="todlando" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=1 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement endpoint="webbie" producer=opening_registry
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.open elapsed_ms=0 budget=none outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=0 budget=busy_handler_per_statement_plus_wal_iterations busy_budget_ms=5000 wal_attempt_budget=200 producer=setting_busy_handler
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.tune elapsed_ms=1 budget=busy_handler_per_statement_plus_wal_iterations outcome=wal_ready attempts=1 sleep_ms=0 producer=pragma_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.schema elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.select_address elapsed_ms=0 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows)
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=sqlite.lookup_address elapsed_ms=2 budget=sqlite_busy_handler_per_statement outcome=Err(QueryReturnedNoRows) producer=query_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.enumerate elapsed_ms=57 budget=none outcome=returned rows=17 producer=scan_complete
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.nested elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.nested elapsed_ms=0 budget=none parent="todlando"
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.nested elapsed_ms=0 budget=none outcome=returned rows=1 producer=scan_complete
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.read_model elapsed_ms=127 budget=none outcome=returned local_rows=17 remote_groups=5 nested_rows=0
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=unlisted.evidence elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=unlisted.evidence elapsed_ms=0 budget=none outcome=returned rows=1 producer=evidence_gathered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.identity elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.identity elapsed_ms=0 budget=none outcome=resolved producer=endpoint_env
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=unlisted.worklist elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=unlisted.worklist elapsed_ms=0 budget=none outcome=ready rows=1 probes=1 not_asked=0 window_cap=16 outer_budget_ms=10000
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=unlisted.barrier elapsed_ms=0 budget=sequential_windows_of_per_probe_wall_waits outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=unlisted.barrier elapsed_ms=0 budget=sequential_windows_of_per_probe_wall_waits probes=1 cap=16 budget_ms=10000
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=unlisted.window elapsed_ms=0 budget=wrapper_join_barrier outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=unlisted.window elapsed_ms=0 budget=wrapper_join_barrier window=0 probes=1 prior_wrappers_joined=0
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=unlisted.barrier elapsed_ms=0 budget=sequential_windows_of_per_probe_wall_waits outcome=queued index=0 endpoint="w2-helper-gateway" node="69d4fbd62b10d197d92492783b2a1deacf301531d46b5f6a374109640e93107d"
ROSTER_WAIT pid=52512 thread=ThreadId(3) stage=unlisted.wrapper elapsed_ms=0 budget=per_probe_wall_wait outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(3) stage=unlisted.wrapper elapsed_ms=0 budget=per_probe_wall_wait index=0 endpoint="w2-helper-gateway" node="69d4fbd62b10d197d92492783b2a1deacf301531d46b5f6a374109640e93107d"
ROSTER_WAIT pid=52512 thread=ThreadId(3) stage=unlisted.outer_wait elapsed_ms=0 budget=wall_recv_timeout outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(3) stage=unlisted.outer_wait elapsed_ms=0 budget=wall_recv_timeout budget_ms=10000 producer=queued
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=unlisted.worker elapsed_ms=0 budget=outer_cutoff_does_not_cancel outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=unlisted.worker elapsed_ms=0 budget=outer_cutoff_does_not_cancel index=0 endpoint="w2-helper-gateway" node="69d4fbd62b10d197d92492783b2a1deacf301531d46b5f6a374109640e93107d"
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=presence.ensure_broker elapsed_ms=0 budget=startup_accumulated_sleeps_not_wall outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=presence.ensure_broker elapsed_ms=0 budget=startup_accumulated_sleeps_not_wall endpoint="w2-helper-gateway" node="69d4fbd62b10d197d92492783b2a1deacf301531d46b5f6a374109640e93107d" probe="p-f3fecf9f64a7" producer=ensure_running
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=presence.ensure_broker elapsed_ms=0 budget=startup_accumulated_sleeps_not_wall outcome=Ok(())
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=presence.ipc_connect_hello elapsed_ms=0 budget=none_before_carrier_installed outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=presence.ipc_connect_hello elapsed_ms=0 budget=none_before_carrier_installed outcome=Ok(()) carrier_budget_ms=30000
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=presence.route elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=presence.route elapsed_ms=0 budget=none outcome=address
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=presence.dial elapsed_ms=0 budget=carrier_wall_reply_includes_broker_permit_quic_membership outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=presence.dial elapsed_ms=0 budget=carrier_wall_reply_includes_broker_permit_quic_membership budget_ms=30000 producer=net_dial_requested broker_substages=not_observed
PUMP_IPC_READER: spawned
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=presence.dial elapsed_ms=628 budget=carrier_wall_reply_includes_broker_permit_quic_membership outcome=Err(Custom { kind: Other, error: "connect failed: Internal consistency error" })
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=unlisted.worker elapsed_ms=631 budget=outer_cutoff_does_not_cancel outcome=returned producer=probe_returned
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=unlisted.producer_exit elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(4) stage=unlisted.producer_exit elapsed_ms=0 budget=none outcome=returned receiver_present=true
ROSTER_WAIT pid=52512 thread=ThreadId(3) stage=unlisted.outer_wait elapsed_ms=632 budget=wall_recv_timeout outcome=received budget_ms=10000 producer_progress=2 cutoff_is_completion=false
ROSTER_WAIT pid=52512 thread=ThreadId(3) stage=unlisted.wrapper elapsed_ms=632 budget=per_probe_wall_wait outcome=returned index=0
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=unlisted.window elapsed_ms=633 budget=wrapper_join_barrier index=0 outcome=joined wrappers_joined=1
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=unlisted.window elapsed_ms=633 budget=wrapper_join_barrier outcome=complete window=0 wrappers_joined=1 producer_completion=not_implied
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=unlisted.barrier elapsed_ms=634 budget=sequential_windows_of_per_probe_wall_waits outcome=complete wrappers_joined=1 producer_completion=not_implied
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.render elapsed_ms=0 budget=none outcome=started producer=entered
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=roster.render elapsed_ms=0 budget=none outcome=returned producer=print_returned
ROSTER_WAIT pid=52512 thread=ThreadId(2) stage=endpoint_list elapsed_ms=1230 budget=none outcome=returned rc=0