[-1:17426]..\smpd_get_opt_int
[-1:17426]../smpd_get_opt_int
[-1:17426]..\smpd_get_opt
[-1:17426]../smpd_get_opt
[-1:17426]..\smpd_get_opt_int
[-1:17426]../smpd_get_opt_int
[-1:17426]..\smpd_get_opt_int
[-1:17426]../smpd_get_opt_int
[-1:17426]..\smpd_get_opt
[-1:17426]../smpd_get_opt
[-1:17426]..\smpd_get_opt
[-1:17426]../smpd_get_opt
[-1:17426]..\smpd_get_opt_string
[-1:17426]../smpd_get_opt_string
[-1:17426]..\smpd_get_opt
[-1:17426]../smpd_get_opt
[-1:17426]..\smpd_get_opt_string
[-1:17426]../smpd_get_opt_string
[-1:17426]..\smpd_get_opt
[-1:17426]../smpd_get_opt
[-1:17426]..\smpd_get_opt_string
[-1:17426]../smpd_get_opt_string
[-1:17426]..\smpd_get_opt
[-1:17426]../smpd_get_opt
[-1:17426]..\smpd_get_opt_string
[-1:17426]../smpd_get_opt_string
[-1:17426]..\smpd_get_opt
[-1:17426]../smpd_get_opt
[-1:17426]..\smpd_get_opt_string
[-1:17426]../smpd_get_opt_string
[-1:17426]..\smpd_get_opt
[-1:17426]../smpd_get_opt
[-1:17426]..\smpd_get_opt_string
[-1:17426]../smpd_get_opt_string
[-1:17426]..\smpd_get_opt
[-1:17426]../smpd_get_opt
[-1:17426]..\smpd_get_opt_string
[-1:17426]../smpd_get_opt_string
[-1:17426]..\smpd_get_opt_string
[-1:17426]../smpd_get_opt_string
[-1:17426]..\smpd_get_opt
[-1:17426]../smpd_get_opt
[-1:17426]./smpd_parse_command_args
[-1:17426].\smpd_get_smpd_data
[-1:17426]..\smpd_hide_string_arg
[-1:17426]...\first_token
[-1:17426].../first_token
[-1:17426]...\compare_token
[-1:17426].../compare_token
[-1:17426]...\next_token
[-1:17426]....\first_token
[-1:17426]..../first_token
[-1:17426]....\first_token
[-1:17426]..../first_token
[-1:17426].../next_token
[-1:17426]../smpd_hide_string_arg
[-1:17426]../smpd_hide_string_arg
[-1:17426]..\smpd_get_smpd_data_from_environment
[-1:17426]../smpd_get_smpd_data_from_environment
[-1:17426]..\smpd_parse_smpd_file
[-1:17426]...\smpd_open_smpd_file
[-1:17426].../smpd_open_smpd_file
[-1:17426]...\str_replace
[-1:17426].../str_replace
[-1:17426]../smpd_parse_smpd_file
[-1:17426]..\smpd_hide_string_arg
[-1:17426]...\first_token
[-1:17426].../first_token
[-1:17426]...\compare_token
[-1:17426].../compare_token
[-1:17426]...\next_token
[-1:17426]....\first_token
[-1:17426]..../first_token
[-1:17426]....\first_token
[-1:17426]..../first_token
[-1:17426].../next_token
[-1:17426]../smpd_hide_string_arg
[-1:17426]../smpd_hide_string_arg
[-1:17426]./smpd_get_smpd_data
[-1:17426].\smpd_entry_point
[00:17426]..\SMPDU_Sock_create_set
[00:17426]../SMPDU_Sock_create_set
[00:17426]..\SMPDU_Sock_get_sock_set_id
[00:17426]../SMPDU_Sock_get_sock_set_id
[00:17426]..created a set for the listener: 0
[00:17426]..\SMPDU_Sock_listen
[00:17426]...Sock buf size = -1
[00:17426]...\SMPDU_Socki_sock_alloc
[00:17426].../SMPDU_Socki_sock_alloc
[00:17426]../SMPDU_Sock_listen
[00:17426]..smpd listening on port 8676
[00:17426]..\smpd_create_context
[00:17426]...\smpd_init_context
[00:17426]....\smpd_init_command
[00:17426]..../smpd_init_command
[00:17426]....\SMPDU_Sock_set_user_ptr
[00:17426]..../SMPDU_Sock_set_user_ptr
[00:17426].../smpd_init_context
[00:17426]../smpd_create_context
[00:17426]..\SMPDU_Sock_set_user_ptr
[00:17426]../SMPDU_Sock_set_user_ptr
[00:17426]..\smpd_option_on
[00:17426]...\smpd_get_smpd_data
[00:17426]....getting smpd data: no_dynamic_hosts
[00:17426]....\smpd_get_smpd_data_from_environment
[00:17426]..../smpd_get_smpd_data_from_environment
[00:17426]....\smpd_parse_smpd_file
[00:17426].....\smpd_open_smpd_file
[00:17426]...../smpd_open_smpd_file
[00:17426].....\str_replace
[00:17426]...../str_replace
[00:17426]..../smpd_parse_smpd_file
[00:17426]....\smpd_get_smpd_data_default
[00:17426]..../smpd_get_smpd_data_default
[00:17426]....smpd data: failed to get no_dynamic_hosts
[00:17426].../smpd_get_smpd_data
[00:17426]../smpd_option_on
[00:17426]..\smpd_insert_into_dynamic_hosts
[00:17426]...\smpd_lock_smpd_data
[00:17426].../smpd_lock_smpd_data
[00:17426]...\smpd_get_smpd_data
[00:17426]....getting smpd data: dynamic_hosts
[00:17426]....\smpd_get_smpd_data_from_environment
[00:17426]..../smpd_get_smpd_data_from_environment
[00:17426]....\smpd_parse_smpd_file
[00:17426].....\smpd_open_smpd_file
[00:17426]...../smpd_open_smpd_file
[00:17426].....\str_replace
[00:17426]...../str_replace
[00:17426]..../smpd_parse_smpd_file
[00:17426]....smpd data: dynamic_hosts=jeltz.mc.vanderbilt.edu
[00:17426].../smpd_get_smpd_data
[00:17426]...\smpd_unlock_smpd_data
[00:17426].../smpd_unlock_smpd_data
[00:17426]...\smpd_get_hostname
[00:17426].../smpd_get_hostname
[00:17426]../smpd_insert_into_dynamic_hosts
[00:17426]..\smpd_enter_at_state
[00:17426]...sock_waiting for the next event.
[00:17426]...\SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426]....\SMPDU_Sock_wait
[00:17426]..../SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_event_enqueue
[00:17426]..../SMPDU_Socki_event_enqueue
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426].../SMPDU_Sock_wait
[00:17426]...SOCK_OP_ACCEPT event.error = 0, result = 0, context=listener
[00:17426]...\smpd_handle_op_accept
[00:17426]....\smpd_state_smpd_listening
[00:17426].....\SMPDU_Sock_accept
[00:17426]......Sock buf size = -1
[00:17426]......\SMPDU_Socki_sock_alloc
[00:17426]....../SMPDU_Socki_sock_alloc
[00:17426]...../SMPDU_Sock_accept
[00:17426].....authenticating new connection
[00:17426].....\smpd_create_context
[00:17426]......\smpd_init_context
[00:17426].......\smpd_init_command
[00:17426]......./smpd_init_command
[00:17426].......\SMPDU_Sock_set_user_ptr
[00:17426]......./SMPDU_Sock_set_user_ptr
[00:17426]....../smpd_init_context
[00:17426]...../smpd_create_context
[00:17426].....\SMPDU_Sock_set_user_ptr
[00:17426]...../SMPDU_Sock_set_user_ptr
[00:17426].....\smpd_gen_authentication_strings
[00:17426]......\smpd_hash
[00:17426]....../smpd_hash
[00:17426]...../smpd_gen_authentication_strings
[00:17426].....posting a write of the challenge string: 1.3a1 228303270
[00:17426].....\SMPDU_Sock_post_write
[00:17426]...../SMPDU_Sock_post_write
[00:17426]..../smpd_state_smpd_listening
[00:17426].../smpd_handle_op_accept
[00:17426]...sock_waiting for the next event.
[00:17426]...\SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426]....\SMPDU_Sock_wait
[00:17426]..../SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_handle_write
[00:17426].....\SMPDU_Socki_handle_write
[00:17426]...../SMPDU_Socki_handle_write
[00:17426].....\SMPDU_Socki_event_enqueue
[00:17426]...../SMPDU_Socki_event_enqueue
[00:17426]..../SMPDU_Socki_handle_write
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426].../SMPDU_Sock_wait
[00:17426]...SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:17426]...\smpd_handle_op_write
[00:17426]....\smpd_state_writing_challenge_string
[00:17426].....wrote challenge string: '1.3a1 228303270'
[00:17426].....\SMPDU_Sock_post_read
[00:17426]...../SMPDU_Sock_post_read
[00:17426]..../smpd_state_writing_challenge_string
[00:17426].../smpd_handle_op_write
[00:17426]...sock_waiting for the next event.
[00:17426]...\SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426]....\SMPDU_Sock_wait
[00:17426]..../SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_handle_read
[00:17426].....\SMPDU_Socki_handle_read
[00:17426]...../SMPDU_Socki_handle_read
[00:17426].....\SMPDU_Socki_event_enqueue
[00:17426]...../SMPDU_Socki_event_enqueue
[00:17426]..../SMPDU_Socki_handle_read
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426].../SMPDU_Sock_wait
[00:17426]...SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[00:17426]...\smpd_handle_op_read
[00:17426]....\smpd_state_reading_challenge_response
[00:17426].....read challenge response: '21083cf7706167bb9483cd2eda25fa10'
[00:17426].....\SMPDU_Sock_post_write
[00:17426]...../SMPDU_Sock_post_write
[00:17426]..../smpd_state_reading_challenge_response
[00:17426].../smpd_handle_op_read
[00:17426]...sock_waiting for the next event.
[00:17426]...\SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426]....\SMPDU_Sock_wait
[00:17426]..../SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_handle_write
[00:17426].....\SMPDU_Socki_handle_write
[00:17426]...../SMPDU_Socki_handle_write
[00:17426].....\SMPDU_Socki_event_enqueue
[00:17426]...../SMPDU_Socki_event_enqueue
[00:17426]..../SMPDU_Socki_handle_write
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426].../SMPDU_Sock_wait
[00:17426]...SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:17426]...\smpd_handle_op_write
[00:17426]....\smpd_state_writing_connect_result
[00:17426].....wrote connect result: 'SUCCESS'
[00:17426].....\SMPDU_Sock_post_read
[00:17426]...../SMPDU_Sock_post_read
[00:17426]..../smpd_state_writing_connect_result
[00:17426].../smpd_handle_op_write
[00:17426]...sock_waiting for the next event.
[00:17426]...\SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426]....\SMPDU_Sock_wait
[00:17426]..../SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_handle_read
[00:17426].....\SMPDU_Socki_handle_read
[00:17426]...../SMPDU_Socki_handle_read
[00:17426].....\SMPDU_Socki_event_enqueue
[00:17426]...../SMPDU_Socki_event_enqueue
[00:17426]..../SMPDU_Socki_handle_read
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426].../SMPDU_Sock_wait
[00:17426]...SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[00:17426]...\smpd_handle_op_read
[00:17426]....\smpd_state_reading_session_request
[00:17426].....read session request: 'process'
[00:17426].....\SMPDU_Sock_post_write
[00:17426]...../SMPDU_Sock_post_write
[00:17426]..../smpd_state_reading_session_request
[00:17426].../smpd_handle_op_read
[00:17426]...sock_waiting for the next event.
[00:17426]...\SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426]....\SMPDU_Sock_wait
[00:17426]..../SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_handle_write
[00:17426].....\SMPDU_Socki_handle_write
[00:17426]...../SMPDU_Socki_handle_write
[00:17426].....\SMPDU_Socki_event_enqueue
[00:17426]...../SMPDU_Socki_event_enqueue
[00:17426]..../SMPDU_Socki_handle_write
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426].../SMPDU_Sock_wait
[00:17426]...SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:17426]...\smpd_handle_op_write
[00:17426]....\smpd_state_writing_no_cred_request
[00:17426].....wrote no cred request: 'nocredentials'
[00:17426].....smpd writing noreconnect request
[00:17426].....\SMPDU_Sock_post_write
[00:17426]...../SMPDU_Sock_post_write
[00:17426]..../smpd_state_writing_no_cred_request
[00:17426].../smpd_handle_op_write
[00:17426]...sock_waiting for the next event.
[00:17426]...\SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426]....\SMPDU_Sock_wait
[00:17426]..../SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_handle_write
[00:17426].....\SMPDU_Socki_handle_write
[00:17426]...../SMPDU_Socki_handle_write
[00:17426].....\SMPDU_Socki_event_enqueue
[00:17426]...../SMPDU_Socki_event_enqueue
[00:17426]..../SMPDU_Socki_handle_write
[00:17426]....\SMPDU_Socki_event_dequeue
[00:17426]..../SMPDU_Socki_event_dequeue
[00:17426].../SMPDU_Sock_wait
[00:17426]...SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:17426]...\smpd_handle_op_write
[00:17426]....\smpd_state_writing_no_reconnect_request
[00:17426].....wrote no reconnect request: '-1'
[00:18195].....\SMPDU_Sock_get_sock_id
[00:18195]...../SMPDU_Sock_get_sock_id
[00:18195].....child closing listener sock(0) after fork.
[00:18195].....\SMPDU_Sock_post_close
[00:18195]......\SMPDU_Socki_event_enqueue
[00:18195]....../SMPDU_Socki_event_enqueue
[00:18195]...../SMPDU_Sock_post_close
[00:18195].....\SMPDU_Sock_post_read
[00:18195]...../SMPDU_Sock_post_read
[00:18195]..../smpd_state_writing_no_reconnect_request
[00:18195].../smpd_handle_op_write
[00:18195]...sock_waiting for the next event.
[00:18195]...\SMPDU_Sock_wait
[00:18195]....\SMPDU_Socki_event_dequeue
[00:18195]..../SMPDU_Socki_event_dequeue
[00:18195]....\SMPDU_Socki_sock_free
[00:18195]..../SMPDU_Socki_sock_free
[00:18195].../SMPDU_Sock_wait
[00:18195]...SOCK_OP_CLOSE event.error = 0, result = 0, context=listener
[00:18195]...\smpd_handle_op_close
[00:18195]....\smpd_get_state_string
[00:18195]..../smpd_get_state_string
[00:18195]....op_close received - SMPD_CLOSING state.
[00:18195]....Unaffiliated listener context closing.
[00:18195]....\smpd_free_context
[00:18195].....freeing listener context.
[00:18195].....\smpd_init_context
[00:18195]......\smpd_init_command
[00:18195]....../smpd_init_command
[00:18195]...../smpd_init_context
[00:18195]..../smpd_free_context
[00:18195].../smpd_handle_op_close
[00:18195]...sock_waiting for the next event.
[00:18195]...\SMPDU_Sock_wait
[00:18195]....\SMPDU_Socki_event_dequeue
[00:18195]..../SMPDU_Socki_event_dequeue
[00:18195]....\SMPDU_Sock_wait
[00:18195]..../SMPDU_Sock_wait
[00:18195]....\SMPDU_Socki_handle_read
[00:18195].....\SMPDU_Socki_handle_read
[00:18195]...../SMPDU_Socki_handle_read
[00:18195].....\SMPDU_Socki_event_enqueue
[00:18195]...../SMPDU_Socki_event_enqueue
[00:18195]..../SMPDU_Socki_handle_read
[00:18195]....\SMPDU_Socki_event_dequeue
[00:18195]..../SMPDU_Socki_event_dequeue
[00:18195].../SMPDU_Sock_wait
[00:18195]...SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[00:18195]...\smpd_handle_op_read
[00:18195]....\smpd_state_reading_session_header
[00:18195].....read session header: 'id=1 parent=0 level=0'
[00:18195].....\smpd_interpret_session_header
[00:18195]......interpreting session header: "id=1 parent=0 level=0"
[00:18195]...... id = 1
[01:18195]...... parent = 0
[01:18195]...... level = 0
[01:18195]...../smpd_interpret_session_header
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the parent context, sock 1
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_session_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 54 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[00:17426].....\SMPDU_Sock_get_sock[01:[00:17426]...../SMPDU_Sock_get_[01:18195].....read command: "cmd=connect src=0 dest=1 tag=0 host=deepthought id=2 "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[00:17426]......\smpd_init_command
[00:17426]....../smpd_init_command
[00:17426]...../smpd_init_context
[01:18195]...... src  = 0
ontext
[00:17426].../smpd_handle_op_close
[00:17426]...sock_waiting for the next event.
[00:17426]...\SMPDU_Sock_wait
[00:17426]....\SMPDU_Socki_event_dequeue
[01:18195]...... dest = 1
[01:18195]...... cmd  = connect
[01:18195]...... tag  = 0
[01:18195]...... ctx  = parent
[01:18195]...... len  = 54
[01:18195]...... str  = cmd=connect src=0 dest=1 tag=0 host=deepthought id=2 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_connect_command
[01:18195].......now connecting to deepthought
[01:18195].......\smpd_create_context
[01:18195]........\smpd_init_context
[01:18195].........\smpd_init_command
[01:18195]........./smpd_init_command
[01:18195]......../smpd_init_context
[01:18195]......./smpd_create_context
[01:18195].......\SMPDU_Sock_post_connect_ifaddr
[01:18195]........\SMPDU_Socki_sock_alloc
[01:18195]......../SMPDU_Socki_sock_alloc
[01:18195]........Sock buf size = -1
[01:18195]........Connecting to 172.020.003.001:8676
[01:18195]........Setting state to SOCKI_STATE_CONNECTING for sock 0x9b25128[01:18195]......./SMPDU_Sock_post_connect_ifaddr
[01:18195].......adding new left child context
[01:18195].......\smpd_init_context
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]........\SMPDU_Sock_set_user_ptr
[01:18195]......../SMPDU_Sock_set_user_ptr
[01:18195]......./smpd_init_context
[01:18195].......\SMPDU_Sock_set_user_ptr
[01:18195]......./SMPDU_Sock_set_user_ptr
[01:18195]....../smpd_handle_connect_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the parent context, sock 1
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_connect
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_connect
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_CONNECT event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_connect
[01:18195]....connect succeeded, posting read of the challenge string
[01:18195]....\SMPDU_Sock_post_read
[01:18195]..../SMPDU_Sock_post_read
[01:18195].../smpd_handle_op_connect
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_challenge_string
[01:18195].....read challenge string: '1.3a1 12630'
[01:18195].....\smpd_verify_version
[01:18195]...../smpd_verify_version
[01:18195].....Verification of smpd version succeeded
[01:18195].....\smpd_hash
[01:18195]...../smpd_hash
[01:18195].....\SMPDU_Sock_post_write
[01:18195]...../SMPDU_Sock_post_write
[01:18195]..../smpd_state_reading_challenge_string
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_challenge_response
[01:18195].....wrote challenge response: 'e195b6ea84f028e09855708c9d5be98e'
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_writing_challenge_response
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_connect_result
[01:18195].....read connect result: 'SUCCESS'
[01:18195].....\SMPDU_Sock_post_write
[01:18195]...../SMPDU_Sock_post_write
[01:18195]..../smpd_state_reading_connect_result
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_process_session_request
[01:18195].....wrote process session request: 'process'
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_writing_process_session_request
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cred_request
[01:18195].....read cred request: 'nocredentials'
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cred_request
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_reconnect_request
[01:18195].....read re-connect request: '59774'
[01:18195].....closing the old socket in the left context.
[01:18195].....\SMPDU_Sock_get_sock_id
[01:18195]...../SMPDU_Sock_get_sock_id
[01:18195].....SMPDU_Sock_post_close(0)
[01:18195].....\SMPDU_Sock_post_close
[01:18195]......\SMPDU_Socki_event_enqueue
[01:18195]....../SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Sock_post_close
[01:18195].....connecting a new socket.
[01:18195].....\smpd_create_context
[01:18195]......\smpd_init_context
[01:18195].......\smpd_init_command
[01:18195]......./smpd_init_command
[01:18195]....../smpd_init_context
[01:18195]...../smpd_create_context
[01:18195].....posting a re-connect to deepthought:59774 in left context.
[01:18195].....\SMPDU_Sock_post_connect_ifaddr
[01:18195]......\SMPDU_Socki_sock_alloc
[01:18195]....../SMPDU_Socki_sock_alloc
[01:18195]......Sock buf size = -1
[01:18195]......Connecting to 172.020.003.001:59774
[01:18195]......Setting state to SOCKI_STATE_CONNECTING for sock 0x9b251b8[01:18195]...../SMPDU_Sock_post_connect_ifaddr
[01:18195]..../smpd_state_reading_reconnect_request
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Socki_sock_free
[01:18195]..../SMPDU_Socki_sock_free
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_CLOSE event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_close
[01:18195]....\smpd_get_state_string
[01:18195]..../smpd_get_state_string
[01:18195]....op_close received - SMPD_CLOSING state.
[01:18195]....Unaffiliated left context closing.
[01:18195]....\smpd_free_context
[01:18195].....freeing left context.
[01:18195].....\smpd_init_context
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_init_context
[01:18195]..../smpd_free_context
[01:18195].../smpd_handle_op_close
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_connect
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_connect
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_CONNECT event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_connect
[01:18195]....\smpd_generate_session_header
[01:18195].....session header: (id=2 parent=1 level=1)
[01:18195]..../smpd_generate_session_header
[01:18195]....\SMPDU_Sock_post_write
[01:18195]..../SMPDU_Sock_post_write
[01:18195].../smpd_handle_op_connect
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_session_header
[01:18195].....wrote session header: 'id=2 parent=1 level=1'
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the left context, sock 2
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195].....\smpd_create_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_create_command
[01:18195].....\smpd_add_command_int_arg
[01:18195]...../smpd_add_command_int_arg
[01:18195].....\smpd_add_command_arg
[01:18195]...../smpd_add_command_arg
[01:18195].....\smpd_add_command_arg
[01:18195]...../smpd_add_command_arg
[01:18195].....\smpd_command_destination
[01:18195]......1 -> 0 : returning parent_context: 0 < 1
[01:18195]...../smpd_command_destination
[01:18195].....sending result command: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=connect result=SUCCESS "
[01:18195].....\smpd_post_write_command
[01:18195]......\smpd_package_command
[01:18195]....../smpd_package_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......smpd_post_write_command on the parent context sock 1: 86 bytes for command: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=connect result=SUCCESS "
[01:18195]......\SMPDU_Sock_post_writev
[01:18195]....../SMPDU_Sock_post_writev
[01:18195]...../smpd_post_write_command
[01:18195]..../smpd_state_writing_session_header
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to parent: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=connect result=SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 34 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=start_dbs src=0 dest=1 tag=1 "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 0
[01:18195]...... dest = 1
[01:18195]...... cmd  = start_dbs
[01:18195]...... tag  = 1
[01:18195]...... ctx  = parent
[01:18195]...... len  = 34
[01:18195]...... str  = cmd=start_dbs src=0 dest=1 tag=1 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_start_dbs_command
[01:18195].......\smpd_dbs_init
[01:18195]......./smpd_dbs_init
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_dbs_create
[01:18195]......./smpd_dbs_create
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to parent context: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=start_dbs kvs_name=6E555341175A97463606544C1E5894E7 domain_name=175A97463606544C1E5894E7D9BA1A6 result=SUCCESS "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the parent context sock 1: 174 bytes for command: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=start_dbs kvs_name=6E555341175A97463606544C1E5894E7 domain_name=175A97463606544C1E5894E7D9BA1A6 result=SUCCESS "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_handle_start_dbs_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the parent context, sock 1
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to parent: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=start_dbs kvs_name=6E555341175A97463606544C1E5894E7 domain_name=175A97463606544C1E5894E7D9BA1A6 result=SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 429 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=launch src=0 dest=2 tag=2 c="myrimatch -ProteinDatabase /hactar/fasta/Mark12-Rpal-20050929-reverse.fasta /hactar/data/unorganized/Hybrid/20061020-Rpal-Orbi-Orbi/raw/Rpal_Std_2d_FullMS_Orbi30k_MSMS_Orbi7k_Centroid_Run1_102006_01.mzML" s=0 a=0 d=/hactar/bumbershoot/src/myrimatch/trunk p=/hactar/bumbershoot/bin;c:/test;/hactar/bumbershoot/bin q=1 i=1 n=2 k=6E555341175A97463606544C1E5894E7 kd=175A97463606544C1E5894E7D9BA1A6 "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 0
[01:18195]...... dest = 2
[01:18195]...... cmd  = launch
[01:18195]...... tag  = 2
[01:18195]...... ctx  = parent
[01:18195]...... len  = 429
[01:18195]...... str  = cmd=launch src=0 dest=2 tag=2 c="myrimatch -ProteinDatabase /hactar/fasta/Mark12-Rpal-20050929-reverse.fasta /hactar/data/unorganized/Hybrid/20061020-Rpal-Orbi-Orbi/raw/Rpal_Std_2d_FullMS_Orbi30k_MSMS_Orbi7k_Centroid_Run1_102006_01.mzML" s=0 a=0 d=/hactar/bumbershoot/src/myrimatch/trunk p=/hactar/bumbershoot/bin;c:/test;/hactar/bumbershoot/bin q=1 i=1 n=2 k=6E555341175A97463606544C1E5894E7 kd=175A97463606544C1E5894E7D9BA1A6 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 2 : returning left_context
[01:18195]....../smpd_command_destination
[01:18195]......forwarding command to 2
[01:18195]......\smpd_forward_command
[01:18195].......\smpd_create_command_copy
[01:18195]......./smpd_create_command_copy
[01:18195].......posting write of forwarded command: "cmd=launch src=0 dest=2 tag=2 c="myrimatch -ProteinDatabase /hactar/fasta/Mark12-Rpal-20050929-reverse.fasta /hactar/data/unorganized/Hybrid/20061020-Rpal-Orbi-Orbi/raw/Rpal_Std_2d_FullMS_Orbi30k_MSMS_Orbi7k_Centroid_Run1_102006_01.mzML" s=0 a=0 d=/hactar/bumbershoot/src/myrimatch/trunk p=/hactar/bumbershoot/bin;c:/test;/hactar/bumbershoot/bin q=1 i=1 n=2 k=6E555341175A97463606544C1E5894E7 kd=175A97463606544C1E5894E7D9BA1A6 "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the left context sock 2: 442 bytes for command: "cmd=launch src=0 dest=2 tag=2 c="myrimatch -ProteinDatabase /hactar/fasta/Mark12-Rpal-20050929-reverse.fasta /hactar/data/unorganized/Hybrid/20061020-Rpal-Orbi-Orbi/raw/Rpal_Std_2d_FullMS_Orbi30k_MSMS_Orbi7k_Centroid_Run1_102006_01.mzML" s=0 a=0 d=/hactar/bumbershoot/src/myrimatch/trunk p=/hactar/bumbershoot/bin;c:/test;/hactar/bumbershoot/bin q=1 i=1 n=2 k=6E555341175A97463606544C1E5894E7 kd=175A97463606544C1E5894E7D9BA1A6 "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_forward_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the parent context, sock 1
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to left: "cmd=launch src=0 dest=2 tag=2 c="myrimatch -ProteinDatabase /hactar/fasta/Mark12-Rpal-20050929-reverse.fasta /hactar/data/unorganized/Hybrid/20061020-Rpal-Orbi-Orbi/raw/Rpal_Std_2d_FullMS_Orbi30k_MSMS_Orbi7k_Centroid_Run1_102006_01.mzML" s=0 a=0 d=/hactar/bumbershoot/src/myrimatch/trunk p=/hactar/bumbershoot/bin;c:/test;/hactar/bumbershoot/bin q=1 i=1 n=2 k=6E555341175A97463606544C1E5894E7 kd=175A97463606544C1E5894E7D9BA1A6 "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 429 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=launch src=0 dest=1 tag=3 c="myrimatch -ProteinDatabase /hactar/fasta/Mark12-Rpal-20050929-reverse.fasta /hactar/data/unorganized/Hybrid/20061020-Rpal-Orbi-Orbi/raw/Rpal_Std_2d_FullMS_Orbi30k_MSMS_Orbi7k_Centroid_Run1_102006_01.mzML" s=0 a=0 d=/hactar/bumbershoot/src/myrimatch/trunk p=/hactar/bumbershoot/bin;c:/test;/hactar/bumbershoot/bin q=0 i=0 n=2 k=6E555341175A97463606544C1E5894E7 kd=175A97463606544C1E5894E7D9BA1A6 "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 0
[01:18195]...... dest = 1
[01:18195]...... cmd  = launch
[01:18195]...... tag  = 3
[01:18195]...... ctx  = parent
[01:18195]...... len  = 429
[01:18195]...... str  = cmd=launch src=0 dest=1 tag=3 c="myrimatch -ProteinDatabase /hactar/fasta/Mark12-Rpal-20050929-reverse.fasta /hactar/data/unorganized/Hybrid/20061020-Rpal-Orbi-Orbi/raw/Rpal_Std_2d_FullMS_Orbi30k_MSMS_Orbi7k_Centroid_Run1_102006_01.mzML" s=0 a=0 d=/hactar/bumbershoot/src/myrimatch/trunk p=/hactar/bumbershoot/bin;c:/test;/hactar/bumbershoot/bin q=0 i=0 n=2 k=6E555341175A97463606544C1E5894E7 kd=175A97463606544C1E5894E7D9BA1A6 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_launch_command
[01:18195].......\smpd_create_process_struct
[01:18195]........\smpd_create_context
[01:18195].........\smpd_init_context
[01:18195]..........\smpd_init_command
[01:18195]........../smpd_init_command
[01:18195]........./smpd_init_context
[01:18195]......../smpd_create_context
[01:18195]........\smpd_create_context
[01:18195].........\smpd_init_context
[01:18195]..........\smpd_init_command
[01:18195]........../smpd_init_command
[01:18195]........./smpd_init_context
[01:18195]......../smpd_create_context
[01:18195]........\smpd_create_context
[01:18195].........\smpd_init_context
[01:18195]..........\smpd_init_command
[01:18195]........../smpd_init_command
[01:18195]........./smpd_init_context
[01:18195]......../smpd_create_context
[01:18195]........\smpd_create_context
[01:18195].........\smpd_init_context
[01:18195]..........\smpd_init_command
[01:18195]........../smpd_init_command
[01:18195]........./smpd_init_context
[01:18195]......../smpd_create_context
[01:18195]......./smpd_create_process_struct
[01:18195].......launching: 'myrimatch -ProteinDatabase /hactar/fasta/Mark12-Rpal-20050929-reverse.fasta /hactar/data/unorganized/Hybrid/20061020-Rpal-Orbi-Orbi/raw/Rpal_Std_2d_FullMS_Orbi30k_MSMS_Orbi7k_Centroid_Run1_102006_01.mzML'
[01:18195].......\smpd_launch_process
[01:18195]........searching for 'myrimatch' in '/hactar/bumbershoot/bin;c:/test;/hactar/bumbershoot/bin'
[01:18195]........\smpd_search_path
[01:18195]......../smpd_search_path
[01:18195]........found: '/hactar/bumbershoot/bin/myrimatch'
[01:18200]........client is alive and about to exec '/hactar/bumbershoot/bin/myrimatch'
[01:18200]........env: PMI_RANK=0
[01:18200]........env: PMI_SIZE=2
[01:18200]........env: PMI_KVS=6E555341175A97463606544C1E5894E7
[01:18200]........env: PMI_DOMAIN=175A97463606544C1E5894E7D9BA1A6
[01:18200]........env: PMI_SMPD_FD=13
[01:18195]........\SMPDU_Sock_native_[01:18200]........env: PMI_SMPD_KEY=0
[01:18200]........env: PMI_SPAWN=0
[01:18200]........env: PMI_APPNUM=0
[01:18200]........env: PMI_CLI[01:18195]........\SMPDU_Sock_native_to_sock
[01:18195].........\SMPDU_Socki_sock_alloc
[01:18195]........./SMPDU_Socki_sock_alloc
[01:18195]......../SMPDU_Sock_native_to_sock
[01:18195]........\SMPDU_Sock_native_to_sock
[01:18195].........\SMPDU_Socki_sock_alloc
[01:18195]........./SMPDU_Socki_sock_alloc
[01:18195]......../SMPDU_Sock_native_to_sock
[01:18195]........\SMPDU_Sock_native_to_sock
[01:18195].........\SMPDU_Socki_sock_alloc
[01:18195]........./SMPDU_Socki_sock_alloc
[01:18195]......../SMPDU_Sock_native_to_sock
[01:18195]........\SMPDU_Sock_set_user_ptr
[01:18195]......../SMPDU_Sock_set_user_ptr
[01:18195]........\SMPDU_Sock_set_user_ptr
[01:18195]......../SMPDU_Sock_set_user_ptr
[01:18195]........\SMPDU_Sock_set_user_ptr
[01:18195]......../SMPDU_Sock_set_user_ptr
[01:18195]........\SMPDU_Sock_set_user_ptr
[01:18195]......../SMPDU_Sock_set_user_ptr
[01:18195]........\SMPDU_Sock_post_read
[01:18195]......../SMPDU_Sock_post_read
[01:18195]........\SMPDU_Sock_post_read
[01:18195]......../SMPDU_Sock_post_read
[01:18195]........\smpd_post_read_command
[01:18195].........\SMPDU_Sock_get_sock_id
[01:18195]........./SMPDU_Sock_get_sock_id
[01:18195].........posting a read for a command header on the pmi context, sock 5
[01:18195].........\SMPDU_Sock_post_read
[01:18195]........./SMPDU_Sock_post_read
[01:18195]......../smpd_post_read_command
[01:18195]......./smpd_launch_process
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the parent context sock 1: 143 bytes for command: "cmd=result src=1 dest=0 tag=2 cmd_tag=3 cmd_orig=launch result=SUCCESS pg_id=6E555341175A97463606544C1E5894E7 pg_rank=0 pg_ctx=0 "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_handle_launch_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the parent context, sock 1
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to parent: "cmd=result src=1 dest=0 tag=2 cmd_tag=3 cmd_orig=launch result=SUCCESS pg_id=6E555341175A97463606544C1E5894E7 pg_rank=0 pg_ctx=0 "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 101 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=0 value=2 node_id=1 "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 1
[01:18195]...... dest = 0
[01:18195]...... cmd  = init
[01:18195]...... tag  = 0
[01:18195]...... ctx  = pmi
[01:18195]...... len  = 101
[01:18195]...... str  = cmd=init src=1 dest=0 tag=0 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=0 value=2 node_id=1 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 0 : returning parent_context: 0 < 1
[01:18195]....../smpd_command_destination
[01:18195]......forwarding command to 0
[01:18195]......\smpd_forward_command
[01:18195].......\smpd_create_command_copy
[01:18195]......./smpd_create_command_copy
[01:18195].......posting write of forwarded command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=0 value=2 node_id=1 "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the parent context sock 1: 114 bytes for command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=0 value=2 node_id=1 "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_forward_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the pmi context, sock 5
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to parent: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=0 value=2 node_id=1 "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 66 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 0
[01:18195]...... dest = 1
[01:18195]...... cmd  = result
[01:18195]...... tag  = 4
[01:18195]...... ctx  = parent
[01:18195]...... len  = 66
[01:18195]...... str  = cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_result
[01:18195].......forwarding the dbs result command to the pmi context 0.
[01:18195].......\smpd_forward_command
[01:18195]........\smpd_create_command_copy
[01:18195]......../smpd_create_command_copy
[01:18195]........posting write of forwarded command: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:18195]........\smpd_post_write_command
[01:18195].........\smpd_package_command
[01:18195]........./smpd_package_command
[01:18195].........\SMPDU_Sock_get_sock_id
[01:18195]........./SMPDU_Sock_get_sock_id
[01:18195].........smpd_post_write_command on the pmi context sock 5: 79 bytes for command: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:18195].........\SMPDU_Sock_post_writev
[01:18195]........./SMPDU_Sock_post_writev
[01:18195]......../smpd_post_write_command
[01:18195]......./smpd_forward_command
[01:18195]....../smpd_handle_result
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the parent context, sock 1
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to pmi: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 102 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=dbget src=1 dest=1 tag=1 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=PMI_process_mapping "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 1
[01:18195]...... dest = 1
[01:18195]...... cmd  = dbget
[01:18195]...... tag  = 1
[01:18195]...... ctx  = pmi
[01:18195]...... len  = 102
[01:18195]...... str  = cmd=dbget src=1 dest=1 tag=1 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=PMI_process_mapping 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_dbs_command
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\get_name_key_value
[01:18195]......./get_name_key_value
[01:18195].......\smpd_dbs_get
[01:18195]......./smpd_dbs_get
[01:18195].......sending reply to dbs command 'cmd=dbget src=1 dest=1 tag=1 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=PMI_process_mapping '.
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to pmi context: "cmd=result src=1 dest=1 tag=3 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the pmi context sock 5: 95 bytes for command: "cmd=result src=1 dest=1 tag=3 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_handle_dbs_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the pmi context, sock 5
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to pmi: "cmd=result src=1 dest=1 tag=3 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 124 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=dbput src=1 dest=1 tag=2 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=hostname[0] value=jeltz.mc.vanderbilt.edu "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 1
[01:18195]...... dest = 1
[01:18195]...... cmd  = dbput
[01:18195]...... tag  = 2
[01:18195]...... ctx  = pmi
[01:18195]...... len  = 124
[01:18195]...... str  = cmd=dbput src=1 dest=1 tag=2 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=hostname[0] value=jeltz.mc.vanderbilt.edu 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_dbs_command
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\get_name_key_value
[01:18195]......./get_name_key_value
[01:18195].......\smpd_dbs_put
[01:18195]......./smpd_dbs_put
[01:18195].......sending reply to dbs command 'cmd=dbput src=1 dest=1 tag=2 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=hostname[0] value=jeltz.mc.vanderbilt.edu '.
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to pmi context: "cmd=result src=1 dest=1 tag=4 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the pmi context sock 5: 98 bytes for command: "cmd=result src=1 dest=1 tag=4 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_handle_dbs_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the pmi context, sock 5
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to pmi: "cmd=result src=1 dest=1 tag=4 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 88 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=barrier src=1 dest=1 tag=3 ctx_key=0 name=6E555341175A97463606544C1E5894E7 value=2 "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 1
[01:18195]...... dest = 1
[01:18195]...... cmd  = barrier
[01:18195]...... tag  = 3
[01:18195]...... ctx  = pmi
[01:18195]...... len  = 88
[01:18195]...... str  = cmd=barrier src=1 dest=1 tag=3 ctx_key=0 name=6E555341175A97463606544C1E5894E7 value=2 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_barrier_command
[01:18195].......initializing barrier(6E555341175A97463606544C1E5894E7): in=1 size=2
[01:18195]....../smpd_handle_barrier_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the pmi context, sock 5
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 130 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=6E555341175A97463606544C1E5894E7 pg_rank=1 pg_ctx=0 "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 2
[01:18195]...... dest = 0
[01:18195]...... cmd  = result
[01:18195]...... tag  = 0
[01:18195]...... ctx  = left
[01:18195]...... len  = 130
[01:18195]...... str  = cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=6E555341175A97463606544C1E5894E7 pg_rank=1 pg_ctx=0 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 0 : returning parent_context: 0 < 1
[01:18195]....../smpd_command_destination
[01:18195]......forwarding command to 0
[01:18195]......\smpd_forward_command
[01:18195].......\smpd_create_command_copy
[01:18195]......./smpd_create_command_copy
[01:18195].......posting write of forwarded command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=6E555341175A97463606544C1E5894E7 pg_rank=1 pg_ctx=0 "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the parent context sock 1: 143 bytes for command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=6E555341175A97463606544C1E5894E7 pg_rank=1 pg_ctx=0 "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_forward_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the left context, sock 2
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to parent: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=6E555341175A97463606544C1E5894E7 pg_rank=1 pg_ctx=0 "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 101 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=1 value=2 node_id=2 "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 2
[01:18195]...... dest = 0
[01:18195]...... cmd  = init
[01:18195]...... tag  = 0
[01:18195]...... ctx  = left
[01:18195]...... len  = 101
[01:18195]...... str  = cmd=init src=2 dest=0 tag=0 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=1 value=2 node_id=2 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 0 : returning parent_context: 0 < 1
[01:18195]....../smpd_command_destination
[01:18195]......forwarding command to 0
[01:18195]......\smpd_forward_command
[01:18195].......\smpd_create_command_copy
[01:18195]......./smpd_create_command_copy
[01:18195].......posting write of forwarded command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=1 value=2 node_id=2 "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the parent context sock 1: 114 bytes for command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=1 value=2 node_id=2 "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_forward_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the left context, sock 2
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to parent: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=1 value=2 node_id=2 "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 66 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 0
[01:18195]...... dest = 2
[01:18195]...... cmd  = result
[01:18195]...... tag  = 5
[01:18195]...... ctx  = parent
[01:18195]...... len  = 66
[01:18195]...... str  = cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 2 : returning left_context
[01:18195]....../smpd_command_destination
[01:18195]......forwarding command to 2
[01:18195]......\smpd_forward_command
[01:18195].......\smpd_create_command_copy
[01:18195]......./smpd_create_command_copy
[01:18195].......posting write of forwarded command: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the left context sock 2: 79 bytes for command: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_forward_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the parent context, sock 1
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to left: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 102 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=dbget src=2 dest=1 tag=1 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=PMI_process_mapping "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 2
[01:18195]...... dest = 1
[01:18195]...... cmd  = dbget
[01:18195]...... tag  = 1
[01:18195]...... ctx  = left
[01:18195]...... len  = 102
[01:18195]...... str  = cmd=dbget src=2 dest=1 tag=1 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=PMI_process_mapping 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_dbs_command
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\get_name_key_value
[01:18195]......./get_name_key_value
[01:18195].......\smpd_dbs_get
[01:18195]......./smpd_dbs_get
[01:18195].......sending reply to dbs command 'cmd=dbget src=2 dest=1 tag=1 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=PMI_process_mapping '.
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to left context: "cmd=result src=1 dest=2 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the left context sock 2: 95 bytes for command: "cmd=result src=1 dest=2 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_handle_dbs_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the left context, sock 2
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to left: "cmd=result src=1 dest=2 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 112 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=dbput src=2 dest=1 tag=2 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=hostname[1] value=DeepThought "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 2
[01:18195]...... dest = 1
[01:18195]...... cmd  = dbput
[01:18195]...... tag  = 2
[01:18195]...... ctx  = left
[01:18195]...... len  = 112
[01:18195]...... str  = cmd=dbput src=2 dest=1 tag=2 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=hostname[1] value=DeepThought 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_dbs_command
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\get_name_key_value
[01:18195]......./get_name_key_value
[01:18195].......\smpd_dbs_put
[01:18195]......./smpd_dbs_put
[01:18195].......sending reply to dbs command 'cmd=dbput src=2 dest=1 tag=2 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=hostname[1] value=DeepThought '.
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to left context: "cmd=result src=1 dest=2 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the left context sock 2: 98 bytes for command: "cmd=result src=1 dest=2 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_handle_dbs_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the left context, sock 2
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to left: "cmd=result src=1 dest=2 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 88 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=barrier src=2 dest=1 tag=3 ctx_key=0 name=6E555341175A97463606544C1E5894E7 value=2 "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 2
[01:18195]...... dest = 1
[01:18195]...... cmd  = barrier
[01:18195]...... tag  = 3
[01:18195]...... ctx  = left
[01:18195]...... len  = 88
[01:18195]...... str  = cmd=barrier src=2 dest=1 tag=3 ctx_key=0 name=6E555341175A97463606544C1E5894E7 value=2 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_barrier_command
[01:18195].......incrementing barrier(6E555341175A97463606544C1E5894E7) incount from 1 to 2 out of 2
[01:18195].......all in barrier, sending result back to all participators.
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending reply to barrier command '6E555341175A97463606544C1E5894E7'.
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to left context: "cmd=result src=1 dest=1 tag=7 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the pmi context sock 5: 100 bytes for command: "cmd=result src=1 dest=1 tag=7 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending reply to barrier command '6E555341175A97463606544C1E5894E7'.
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to left context: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the left context sock 2: 100 bytes for command: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_handle_barrier_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the left context, sock 2
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to pmi: "cmd=result src=1 dest=1 tag=7 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to left: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 94 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=dbget src=1 dest=1 tag=4 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=hostname[1] "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 1
[01:18195]...... dest = 1
[01:18195]...... cmd  = dbget
[01:18195]...... tag  = 4
[01:18195]...... ctx  = pmi
[01:18195]...... len  = 94
[01:18195]...... str  = cmd=dbget src=1 dest=1 tag=4 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=hostname[1] 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_dbs_command
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\get_name_key_value
[01:18195]......./get_name_key_value
[01:18195].......\smpd_dbs_get
[01:18195]......./smpd_dbs_get
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending reply to dbs command 'cmd=dbget src=1 dest=1 tag=4 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=hostname[1] '.
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to pmi context: "cmd=result src=1 dest=1 tag=9 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=DeepThought result=DBS_SUCCESS "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the pmi context sock 5: 116 bytes for command: "cmd=result src=1 dest=1 tag=9 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=DeepThought result=DBS_SUCCESS "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_handle_dbs_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the pmi context, sock 5
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to pmi: "cmd=result src=1 dest=1 tag=9 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=DeepThought result=DBS_SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 88 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=barrier src=1 dest=1 tag=5 ctx_key=0 name=6E555341175A97463606544C1E5894E7 value=2 "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 1
[01:18195]...... dest = 1
[01:18195]...... cmd  = barrier
[01:18195]...... tag  = 5
[01:18195]...... ctx  = pmi
[01:18195]...... len  = 88
[01:18195]...... str  = cmd=barrier src=1 dest=1 tag=5 ctx_key=0 name=6E555341175A97463606544C1E5894E7 value=2 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_barrier_command
[01:18195].......initializing barrier(6E555341175A97463606544C1E5894E7): in=1 size=2
[01:18195]....../smpd_handle_barrier_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the pmi context, sock 5
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 94 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=dbget src=2 dest=1 tag=4 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=hostname[0] "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 2
[01:18195]...... dest = 1
[01:18195]...... cmd  = dbget
[01:18195]...... tag  = 4
[01:18195]...... ctx  = left
[01:18195]...... len  = 94
[01:18195]...... str  = cmd=dbget src=2 dest=1 tag=4 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=hostname[0] 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_dbs_command
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\get_name_key_value
[01:18195]......./get_name_key_value
[01:18195].......\smpd_dbs_get
[01:18195]......./smpd_dbs_get
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending reply to dbs command 'cmd=dbget src=2 dest=1 tag=4 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=hostname[0] '.
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to left context: "cmd=result src=1 dest=2 tag=10 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=jeltz.mc.vanderbilt.edu result=DBS_SUCCESS "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the left context sock 2: 129 bytes for command: "cmd=result src=1 dest=2 tag=10 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=jeltz.mc.vanderbilt.edu result=DBS_SUCCESS "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_handle_dbs_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the left context, sock 2
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to left: "cmd=result src=1 dest=2 tag=10 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=jeltz.mc.vanderbilt.edu result=DBS_SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 178 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=dbput src=2 dest=1 tag=5 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=P1-businesscard value="port=59783 description=DeepThought.ds.vanderbilt.edu ifname=172.20.3.1 " "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 2
[01:18195]...... dest = 1
[01:18195]...... cmd  = dbput
[01:18195]...... tag  = 5
[01:18195]...... ctx  = left
[01:18195]...... len  = 178
[01:18195]...... str  = cmd=dbput src=2 dest=1 tag=5 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=P1-businesscard value="port=59783 description=DeepThought.ds.vanderbilt.edu ifname=172.20.3.1 " 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_dbs_command
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\get_name_key_value
[01:18195]......./get_name_key_value
[01:18195].......\smpd_dbs_put
[01:18195]......./smpd_dbs_put
[01:18195].......sending reply to dbs command 'cmd=dbput src=2 dest=1 tag=5 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=P1-businesscard value="port=59783 description=DeepThought.ds.vanderbilt.edu ifname=172.20.3.1 " '.
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to left context: "cmd=result src=1 dest=2 tag=11 cmd_tag=5 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the left context sock 2: 99 bytes for command: "cmd=result src=1 dest=2 tag=11 cmd_tag=5 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_handle_dbs_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the left context, sock 2
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to left: "cmd=result src=1 dest=2 tag=11 cmd_tag=5 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 88 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=barrier src=2 dest=1 tag=6 ctx_key=0 name=6E555341175A97463606544C1E5894E7 value=2 "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 2
[01:18195]...... dest = 1
[01:18195]...... cmd  = barrier
[01:18195]...... tag  = 6
[01:18195]...... ctx  = left
[01:18195]...... len  = 88
[01:18195]...... str  = cmd=barrier src=2 dest=1 tag=6 ctx_key=0 name=6E555341175A97463606544C1E5894E7 value=2 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_barrier_command
[01:18195].......incrementing barrier(6E555341175A97463606544C1E5894E7) incount from 1 to 2 out of 2
[01:18195].......all in barrier, sending result back to all participators.
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending reply to barrier command '6E555341175A97463606544C1E5894E7'.
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to left context: "cmd=result src=1 dest=1 tag=12 cmd_tag=5 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the pmi context sock 5: 101 bytes for command: "cmd=result src=1 dest=1 tag=12 cmd_tag=5 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending reply to barrier command '6E555341175A97463606544C1E5894E7'.
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to left context: "cmd=result src=1 dest=2 tag=13 cmd_tag=6 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the left context sock 2: 101 bytes for command: "cmd=result src=1 dest=2 tag=13 cmd_tag=6 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_handle_barrier_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the left context, sock 2
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to pmi: "cmd=result src=1 dest=1 tag=12 cmd_tag=5 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to left: "cmd=result src=1 dest=2 tag=13 cmd_tag=6 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 177 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=dbput src=1 dest=1 tag=6 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=P0-businesscard value="description=jeltz.mc.vanderbilt.edu port=43038 ifname=160.129.189.222 " "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 1
[01:18195]...... dest = 1
[01:18195]...... cmd  = dbput
[01:18195]...... tag  = 6
[01:18195]...... ctx  = pmi
[01:18195]...... len  = 177
[01:18195]...... str  = cmd=dbput src=1 dest=1 tag=6 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=P0-businesscard value="description=jeltz.mc.vanderbilt.edu port=43038 ifname=160.129.189.222 " 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_dbs_command
[01:18195].......\smpd_create_command
[01:18195]........\smpd_init_command
[01:18195]......../smpd_init_command
[01:18195]......./smpd_create_command
[01:18195].......\smpd_add_command_int_arg
[01:18195]......./smpd_add_command_int_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......\get_name_key_value
[01:18195]......./get_name_key_value
[01:18195].......\smpd_dbs_put
[01:18195]......./smpd_dbs_put
[01:18195].......sending reply to dbs command 'cmd=dbput src=1 dest=1 tag=6 ctx_key=0 name=6E555341175A97463606544C1E5894E7 key=P0-businesscard value="description=jeltz.mc.vanderbilt.edu port=43038 ifname=160.129.189.222 " '.
[01:18195].......\smpd_add_command_arg
[01:18195]......./smpd_add_command_arg
[01:18195].......sending result command to pmi context: "cmd=result src=1 dest=1 tag=14 cmd_tag=6 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the pmi context sock 5: 99 bytes for command: "cmd=result src=1 dest=1 tag=14 cmd_tag=6 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_handle_dbs_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the pmi context, sock 5
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to pmi: "cmd=result src=1 dest=1 tag=14 cmd_tag=6 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 88 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=barrier src=1 dest=1 tag=7 ctx_key=0 name=6E555341175A97463606544C1E5894E7 value=2 "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 1
[01:18195]...... dest = 1
[01:18195]...... cmd  = barrier
[01:18195]...... tag  = 7
[01:18195]...... ctx  = pmi
[01:18195]...... len  = 88
[01:18195]...... str  = cmd=barrier src=1 dest=1 tag=7 ctx_key=0 name=6E555341175A97463606544C1E5894E7 value=2 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 1 : returning NULL context
[01:18195]....../smpd_command_destination
[01:18195]......\smpd_handle_barrier_command
[01:18195].......initializing barrier(6E555341175A97463606544C1E5894E7): in=1 size=2
[01:18195]....../smpd_handle_barrier_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the pmi context, sock 5
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....read command header
[01:18195].....command header read, posting read for data: 130 bytes
[01:18195].....\SMPDU_Sock_post_read
[01:18195]...../SMPDU_Sock_post_read
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd
[01:18195].....read command
[01:18195].....\smpd_parse_command
[01:18195]...../smpd_parse_command
[01:18195].....read command: "cmd=stdout src=2 dest=0 tag=1 rank=1 first=1 data=50726F6365737320233120284465657054686F7567687429206973207374617274696E672E0D0A "
[01:18195].....\smpd_handle_command
[01:18195]......handling command:
[01:18195]...... src  = 2
[01:18195]...... dest = 0
[01:18195]...... cmd  = stdout
[01:18195]...... tag  = 1
[01:18195]...... ctx  = left
[01:18195]...... len  = 130
[01:18195]...... str  = cmd=stdout src=2 dest=0 tag=1 rank=1 first=1 data=50726F6365737320233120284465657054686F7567687429206973207374617274696E672E0D0A 
[01:18195]......\smpd_command_destination
[01:18195].......1 -> 0 : returning parent_context: 0 < 1
[01:18195]....../smpd_command_destination
[01:18195]......forwarding command to 0
[01:18195]......\smpd_forward_command
[01:18195].......\smpd_create_command_copy
[01:18195]......./smpd_create_command_copy
[01:18195].......posting write of forwarded command: "cmd=stdout src=2 dest=0 tag=1 rank=1 first=1 data=50726F6365737320233120284465657054686F7567687429206973207374617274696E672E0D0A "
[01:18195].......\smpd_post_write_command
[01:18195]........\smpd_package_command
[01:18195]......../smpd_package_command
[01:18195]........\SMPDU_Sock_get_sock_id
[01:18195]......../SMPDU_Sock_get_sock_id
[01:18195]........smpd_post_write_command on the parent context sock 1: 143 bytes for command: "cmd=stdout src=2 dest=0 tag=1 rank=1 first=1 data=50726F6365737320233120284465657054686F7567687429206973207374617274696E672E0D0A "
[01:18195]........\SMPDU_Sock_post_writev
[01:18195]......../SMPDU_Sock_post_writev
[01:18195]......./smpd_post_write_command
[01:18195]....../smpd_forward_command
[01:18195]...../smpd_handle_command
[01:18195].....\smpd_post_read_command
[01:18195]......\SMPDU_Sock_get_sock_id
[01:18195]....../SMPDU_Sock_get_sock_id
[01:18195]......posting a read for a command header on the left context, sock 2
[01:18195]......\SMPDU_Sock_post_read
[01:18195]....../SMPDU_Sock_post_read
[01:18195]...../smpd_post_read_command
[01:18195]..../smpd_state_reading_cmd
[01:18195].../smpd_handle_op_read
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_handle_write
[01:18195]...../SMPDU_Socki_handle_write
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_write
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:18195]...\smpd_handle_op_write
[01:18195]....\smpd_state_writing_cmd
[01:18195].....wrote command
[01:18195].....command written to parent: "cmd=stdout src=2 dest=0 tag=1 rank=1 first=1 data=50726F6365737320233120284465657054686F7567687429206973207374617274696E672E0D0A "
[01:18195].....\smpd_free_command
[01:18195]......\smpd_init_command
[01:18195]....../smpd_init_command
[01:18195]...../smpd_free_command
[01:18195]..../smpd_state_writing_cmd
[01:18195].../smpd_handle_op_write
[01:18195]...sock_waiting for the next event.
[01:18195]...\SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195]....\SMPDU_Sock_wait
[01:18195]..../SMPDU_Sock_wait
[01:18195]....\SMPDU_Socki_handle_read
[01:18195].....\SMPDU_Socki_handle_read
[01:18195]...../SMPDU_Socki_handle_read
[01:18195].....Connection closed while trying to read on socket, sock_set->id = 0, sock_id = 1
[01:18195].....\SMPDU_Socki_event_enqueue
[01:18195]...../SMPDU_Socki_event_enqueue
[01:18195]..../SMPDU_Socki_handle_read
[01:18195]....\SMPDU_Socki_event_dequeue
[01:18195]..../SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Sock_wait
[01:18195]...SOCK_OP_READ event.error = -1, result = 0, context=parent
[01:18195]...ERROR:op_read error on parent context: Error = -1

[01:18195]...\smpd_handle_op_read
[01:18195]....\smpd_state_reading_cmd_header
[01:18195].....ERROR:unable to read the cmd header on the parent context, Error = -1
.
[01:18195]..../smpd_state_reading_cmd_header
[01:18195].../smpd_handle_op_read
[01:18195]...ERROR:connection to my parent broken, aborting.
[01:18195]../smpd_enter_at_state
[01:18195]..ERROR:state machine failed.
[01:18195]..\SMPDU_Sock_destroy_set
[01:18195]...\SMPDU_Socki_event_dequeue
[01:18195].../SMPDU_Socki_event_dequeue
[01:18195]../SMPDU_Sock_destroy_set
[01:18195]./smpd_entry_point
[01:18195].calling SMPDU_Sock_finalize
[01:18195].\SMPDU_Sock_finalize
[01:18195]./SMPDU_Sock_finalize
[01:18195].\smpd_exit
[01:18195]..\smpd_kill_all_processes
[01:18195]../smpd_kill_all_processes
[01:18195]..\smpd_dbs_finalize
[01:18195]../smpd_dbs_finalize
[01:18195]..\SMPDU_Sock_finalize
[01:18195]...\SMPDU_Socki_free_eventq_mem
[01:18195].../SMPDU_Socki_free_eventq_mem
[01:18195]../SMPDU_Sock_finalize
[00:17426]..../SMPDU_Sock_wait
[00:17426]....\SMPDU_Sock_wait