[-1:1152]...\smpd_get_opt_int
[-1:1152].../smpd_get_opt_int
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt_int
[-1:1152].../smpd_get_opt_int
[-1:1152]...\smpd_get_opt_int
[-1:1152].../smpd_get_opt_int
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt_string
[-1:1152].../smpd_get_opt_string
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt_string
[-1:1152].../smpd_get_opt_string
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt_string
[-1:1152].../smpd_get_opt_string
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt_string
[-1:1152].../smpd_get_opt_string
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt_string
[-1:1152].../smpd_get_opt_string
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt_string
[-1:1152].../smpd_get_opt_string
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]...\smpd_get_opt_string
[-1:1152].../smpd_get_opt_string
[-1:1152]...\smpd_get_opt_string
[-1:1152].../smpd_get_opt_string
[-1:1152]...\smpd_get_opt
[-1:1152].../smpd_get_opt
[-1:1152]../smpd_parse_command_args
[-1:1152]..\smpd_get_smpd_data
[-1:1152]...\smpd_get_smpd_data_from_environment
[-1:1152].../smpd_get_smpd_data_from_environment
[-1:1152]...\smpd_get_smpd_data_default
[-1:1152].../smpd_get_smpd_data_default
[-1:1152]../smpd_get_smpd_data
[-1:1152]..\smpd_entry_point
[00:1152]...\smpd_set_smpd_data
[00:1152].../smpd_set_smpd_data
[00:1152]...\SMPDU_Sock_create_set
[00:1152]....\SMPDU_Sock_get_sock_set_id
[00:1152]..../SMPDU_Sock_get_sock_set_id
[00:1152]....created a set for the listener: 504
[00:1152]....\SMPDU_Sock_listen
[00:1152]..../SMPDU_Sock_listen
[00:1152]....smpd listening on port 8676
[00:1152]....\smpd_create_context
[00:1152].....\smpd_init_context
[00:1152]......\smpd_init_command
[00:1152]....../smpd_init_command
[00:1152]......\SMPDU_Sock_set_user_ptr
[00:1152]....../SMPDU_Sock_set_user_ptr
[00:1152]...../smpd_init_context
[00:1152]..../smpd_create_context
[00:1152]....\SMPDU_Sock_set_user_ptr
[00:1152]..../SMPDU_Sock_set_user_ptr
[00:1152]....\smpd_option_on
[00:1152].....\smpd_get_smpd_data
[00:1152]......\smpd_get_smpd_data_from_environment
[00:1152]....../smpd_get_smpd_data_from_environment
[00:1152]......\smpd_get_smpd_data_default
[00:1152]....../smpd_get_smpd_data_default
[00:1152]......Unable to get the data for the key 'no_dynamic_hosts'
[00:1152]...../smpd_get_smpd_data
[00:1152]..../smpd_option_on
[00:1152]....\smpd_insert_into_dynamic_hosts
[00:1152]..../smpd_insert_into_dynamic_hosts
[00:1152]....\smpd_enter_at_state
[00:1152].....sock_waiting for the next event.
[00:1152].....\SMPDU_Sock_wait
[00:1152]...../SMPDU_Sock_wait
[00:1152].....SOCK_OP_ACCEPT event.error = 0, result = 0, context=listener
[00:1152].....\smpd_handle_op_accept
[00:1152]......\smpd_state_smpd_listening
[00:1152].......\SMPDU_Sock_accept
[00:1152]......./SMPDU_Sock_accept
[00:1152].......authenticating new connection
[00:1152].......\smpd_create_context
[00:1152]........\smpd_init_context
[00:1152].........\smpd_init_command
[00:1152]........./smpd_init_command
[00:1152].........\SMPDU_Sock_set_user_ptr
[00:1152]........./SMPDU_Sock_set_user_ptr
[00:1152]......../smpd_init_context
[00:1152]......./smpd_create_context
[00:1152].......\SMPDU_Sock_set_user_ptr
[00:1152]......./SMPDU_Sock_set_user_ptr
[00:1152].......\smpd_gen_authentication_strings
[00:1152]........\smpd_hash
[00:1152]......../smpd_hash
[00:1152]......./smpd_gen_authentication_strings
[00:1152].......posting a write of the challenge string: 1.4.1p1 3800
[00:1152].......\SMPDU_Sock_post_write
[00:1152]........\SMPDU_Sock_post_writev
[00:1152]......../SMPDU_Sock_post_writev
[00:1152]......./SMPDU_Sock_post_write
[00:1152]....../smpd_state_smpd_listening
[00:1152]...../smpd_handle_op_accept
[00:1152].....sock_waiting for the next event.
[00:1152].....\SMPDU_Sock_wait
[00:1152]...../SMPDU_Sock_wait
[00:1152].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:1152].....\smpd_handle_op_write
[00:1152]......\smpd_state_writing_challenge_string
[00:1152].......wrote challenge string: '1.4.1p1 3800'
[00:1152].......\SMPDU_Sock_post_read
[00:1152]........\SMPDU_Sock_post_readv
[00:1152]......../SMPDU_Sock_post_readv
[00:1152]......./SMPDU_Sock_post_read
[00:1152]....../smpd_state_writing_challenge_string
[00:1152]...../smpd_handle_op_write
[00:1152].....sock_waiting for the next event.
[00:1152].....\SMPDU_Sock_wait
[00:1152]...../SMPDU_Sock_wait
[00:1152].....SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[00:1152].....\smpd_handle_op_read
[00:1152]......\smpd_state_reading_challenge_response
[00:1152].......read challenge response: '2c3110da52d2327aa6374caca67da12f'
[00:1152].......\SMPDU_Sock_post_write
[00:1152]........\SMPDU_Sock_post_writev
[00:1152]......../SMPDU_Sock_post_writev
[00:1152]......./SMPDU_Sock_post_write
[00:1152]....../smpd_state_reading_challenge_response
[00:1152]...../smpd_handle_op_read
[00:1152].....sock_waiting for the next event.
[00:1152].....\SMPDU_Sock_wait
[00:1152]...../SMPDU_Sock_wait
[00:1152].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:1152].....\smpd_handle_op_write
[00:1152]......\smpd_state_writing_connect_result
[00:1152].......wrote connect result: 'SUCCESS'
[00:1152].......\SMPDU_Sock_post_read
[00:1152]........\SMPDU_Sock_post_readv
[00:1152]......../SMPDU_Sock_post_readv
[00:1152]......./SMPDU_Sock_post_read
[00:1152]....../smpd_state_writing_connect_result
[00:1152]...../smpd_handle_op_write
[00:1152].....sock_waiting for the next event.
[00:1152].....\SMPDU_Sock_wait
[00:1152]...../SMPDU_Sock_wait
[00:1152].....SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[00:1152].....\smpd_handle_op_read
[00:1152]......\smpd_state_reading_session_request
[00:1152].......read session request: 'process'
[00:1152].......\SMPDU_Sock_post_write
[00:1152]........\SMPDU_Sock_post_writev
[00:1152]......../SMPDU_Sock_post_writev
[00:1152]......./SMPDU_Sock_post_write
[00:1152]....../smpd_state_reading_session_request
[00:1152]...../smpd_handle_op_read
[00:1152].....sock_waiting for the next event.
[00:1152].....\SMPDU_Sock_wait
[00:1152]...../SMPDU_Sock_wait
[00:1152].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:1152].....\smpd_handle_op_write
[00:1152]......\smpd_state_writing_no_cred_request
[00:1152].......wrote no cred request: 'nocredentials'
[00:1152].......\smpd_start_win_mgr
[00:1152]........starting command: "C:\Program Files\MPICH2\bin\smpd.exe" -d 27 -mgr -read 00000000000002C0 -write 00000000000002BC
[00:1152]........CreateProcess
[00:1152]........smpd reading the port string from the manager
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt_int
[-1:1748].../smpd_get_opt_int
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt_int
[-1:1748].../smpd_get_opt_int
[-1:1748]...\smpd_get_opt_int
[-1:1748].../smpd_get_opt_int
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt
[-1:1748].../smpd_get_opt
[-1:1748]...\smpd_get_opt_string
[-1:1748].../smpd_get_opt_string
[-1:1748]...\smpd_get_opt_string
[-1:1748].../smpd_get_opt_string
[-1:1748]...manager creating listener and session sets.
[-1:1748]...\SMPDU_Sock_create_set
[-1:1748]....\SMPDU_Sock_get_sock_set_id
[-1:1748]..../SMPDU_Sock_get_sock_set_id
[-1:1748]....created set for manager listener, 768
[-1:1748]....\SMPDU_Sock_listen
[-1:1748]..../SMPDU_Sock_listen
[-1:1748]....smpd manager listening on port 63400
[-1:1748]....\smpd_create_context
[-1:1748].....\smpd_init_context
[-1:1748]......\smpd_init_command
[-1:1748]....../smpd_init_command
[-1:1748]......\SMPDU_Sock_set_user_ptr
[-1:1748]....../SMPDU_Sock_set_user_ptr
[-1:1748]...../smpd_init_context
[-1:1748]..../smpd_create_context
[-1:1748]....\SMPDU_Sock_set_user_ptr
[-1:1748]..../SMPDU_Sock_set_user_ptr
[-1:1748]....manager writing port back to smpd.
[00:1152]........smpd sending the account to the manager
[00:1152]........\smpd_hide_string_arg
[00:1152].........\first_token
[00:1152]........./first_token
[00:1152].........\compare_token
[00:1152]........./compare_token
[00:1152].........\next_token
[00:1152]..........\first_token
[00:1152]........../first_token
[00:1152]..........\first_token
[00:1152]........../first_token
[00:1152]........./next_token
[00:1152]......../smpd_hide_string_arg
[00:1152]......../smpd_hide_string_arg
[00:1152]........\smpd_hide_string_arg
[00:1152].........\first_token
[00:1152]........./first_token
[00:1152].........\compare_token
[00:1152]........./compare_token
[00:1152].........\next_token
[00:1152]..........\first_token
[00:1152]........../first_token
[00:1152]..........\first_token
[00:1152]........../first_token
[00:1152]........./next_token
[00:1152].........\compare_token
[00:1152]........./compare_token
[00:1152].........\compare_token
[00:1152]........./compare_token
[00:1152].........\next_token
[00:1152]..........\first_token
[00:1152]........../first_token
[00:1152]..........\first_token
[00:1152]........../first_token
[00:1152]........./next_token
[00:1152].........\compare_token
[00:1152]........./compare_token
[00:1152].........\next_token
[00:1152]..........\first_token
[00:1152]........../first_token
[00:1152]..........\first_token
[00:1152]........../first_token
[00:1152]........./next_token
[00:1152].........\compare_token
[00:1152]........./compare_token
[00:1152].........\next_token
[00:1152]..........\first_token
[00:1152]........../first_token
[00:1152]..........\first_token
[00:1152]........../first_token
[00:1152]........./next_token
[00:1152]......../smpd_hide_string_arg
[00:1152]......../smpd_hide_string_arg
[00:1152]........closing the pipe to the manager
[00:1152]........smpd writing reconnect request: port 63400
[00:1152]........\SMPDU_Sock_post_write
[00:1152].........\SMPDU_Sock_post_writev
[00:1152]........./SMPDU_Sock_post_writev
[00:1152]......../SMPDU_Sock_post_write
[00:1152]......./smpd_state_writing_no_cred_request
[00:1152]....../smpd_handle_op_write
[00:1152]......sock_waiting for the next event.
[00:1152]......\SMPDU_Sock_wait
[00:1152]....../SMPDU_Sock_wait
[00:1152]......SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:1152]......\smpd_handle_op_write
[00:1152].......\smpd_state_writing_reconnect_request
[00:1152]........wrote reconnect request: '63400'
[00:1152]........\SMPDU_Sock_post_close
[00:1152].........\SMPDU_Sock_post_read
[00:1152]..........\SMPDU_Sock_post_readv
[00:1152]........../SMPDU_Sock_post_readv
[00:1152]........./SMPDU_Sock_post_read
[00:1152]......../SMPDU_Sock_post_close
[00:1152]......./smpd_state_writing_reconnect_request
[00:1152]....../smpd_handle_op_write
[00:1152]......sock_waiting for the next event.
[00:1152]......\SMPDU_Sock_wait
[00:1152]....../SMPDU_Sock_wait
[00:1152]......SOCK_OP_CLOSE event.error = 0, result = 0, context=undetermined
[00:1152]......\smpd_handle_op_close
[00:1152].......\smpd_get_state_string
[00:1152]......./smpd_get_state_string
[00:1152].......op_close received - SMPD_CLOSING state.
[00:1152].......Unaffiliated undetermined context closing.
[00:1152].......\smpd_free_context
[00:1152]........freeing undetermined context.
[00:1152]........\smpd_init_context
[00:1152].........\smpd_init_command
[00:1152]........./smpd_init_command
[00:1152]......../smpd_init_context
[00:1152]......./smpd_free_context
[00:1152]....../smpd_handle_op_close
[00:1152]......sock_waiting for the next event.
[00:1152]......\SMPDU_Sock_wait
[-1:1748]....\smpd_hide_string_arg
[-1:1748].....\first_token
[-1:1748]...../first_token
[-1:1748].....\compare_token
[-1:1748]...../compare_token
[-1:1748].....\next_token
[-1:1748]......\first_token
[-1:1748]....../first_token
[-1:1748]......\first_token
[-1:1748]....../first_token
[-1:1748]...../next_token
[-1:1748].....\compare_token
[-1:1748]...../compare_token
[-1:1748].....\compare_token
[-1:1748]...../compare_token
[-1:1748].....\next_token
[-1:1748]......\first_token
[-1:1748]....../first_token
[-1:1748]......\first_token
[-1:1748]....../first_token
[-1:1748]...../next_token
[-1:1748].....\compare_token
[-1:1748]...../compare_token
[-1:1748].....\next_token
[-1:1748]......\first_token
[-1:1748]....../first_token
[-1:1748]......\first_token
[-1:1748]....../first_token
[-1:1748]...../next_token
[-1:1748]..../smpd_hide_string_arg
[-1:1748]..../smpd_hide_string_arg
[-1:1748]....\smpd_enter_at_state
[-1:1748].....sock_waiting for the next event.
[-1:1748].....\SMPDU_Sock_wait
[-1:1748]...../SMPDU_Sock_wait
[-1:1748].....SOCK_OP_ACCEPT event.error = 0, result = 0, context=listener
[-1:1748].....\smpd_handle_op_accept
[-1:1748]......\smpd_state_mgr_listening
[-1:1748].......\SMPDU_Sock_accept
[-1:1748]......./SMPDU_Sock_accept
[-1:1748].......accepted re-connection
[-1:1748].......\smpd_create_context
[-1:1748]........\smpd_init_context
[-1:1748].........\smpd_init_command
[-1:1748]........./smpd_init_command
[-1:1748].........\SMPDU_Sock_set_user_ptr
[-1:1748]........./SMPDU_Sock_set_user_ptr
[-1:1748]......../smpd_init_context
[-1:1748]......./smpd_create_context
[-1:1748].......\SMPDU_Sock_set_user_ptr
[-1:1748]......./SMPDU_Sock_set_user_ptr
[-1:1748].......\SMPDU_Sock_post_read
[-1:1748]........\SMPDU_Sock_post_readv
[-1:1748]......../SMPDU_Sock_post_readv
[-1:1748]......./SMPDU_Sock_post_read
[-1:1748].......closing the mgr listener.
[-1:1748].......\SMPDU_Sock_post_close
[-1:1748]......./SMPDU_Sock_post_close
[-1:1748]....../smpd_state_mgr_listening
[-1:1748]...../smpd_handle_op_accept
[-1:1748].....sock_waiting for the next event.
[-1:1748].....\SMPDU_Sock_wait
[-1:1748]...../SMPDU_Sock_wait
[-1:1748].....SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[-1:1748].....\smpd_handle_op_read
[-1:1748]......\smpd_state_reading_session_header
[-1:1748].......read session header: 'id=1 parent=0 level=0'
[-1:1748].......\smpd_interpret_session_header
[-1:1748]........interpreting session header: "id=1 parent=0 level=0"
[-1:1748]........ id = 1
[01:1748]........ parent = 0
[01:1748]........ level = 0
[01:1748]......./smpd_interpret_session_header
[01:1748].......\smpd_post_read_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........posting a read for a command header on the parent context, sock 908
[01:1748]........\SMPDU_Sock_post_read
[01:1748].........\SMPDU_Sock_post_readv
[01:1748]........./SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_read
[01:1748]......./smpd_post_read_command
[01:1748]....../smpd_state_reading_session_header
[01:1748]...../smpd_handle_op_read
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_CLOSE event.error = 0, result = 0, context=listener
[01:1748].....\smpd_handle_op_close
[01:1748]......\smpd_get_state_string
[01:1748]....../smpd_get_state_string
[01:1748]......op_close received - SMPD_MGR_LISTENING state.
[01:1748]......\smpd_free_context
[01:1748].......freeing listener context.
[01:1748].......\smpd_init_context
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_init_context
[01:1748]....../smpd_free_context
[01:1748]...../smpd_handle_op_close
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1748].....\smpd_handle_op_read
[01:1748]......\smpd_state_reading_cmd_header
[01:1748].......read command header
[01:1748].......command header read, posting read for data: 55 bytes
[01:1748].......\SMPDU_Sock_post_read
[01:1748]........\SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_read
[01:1748]....../smpd_state_reading_cmd_header
[01:1748]...../smpd_handle_op_read
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1748].....\smpd_handle_op_read
[01:1748]......\smpd_state_reading_cmd
[01:1748].......read command
[01:1748].......\smpd_parse_command
[01:1748]......./smpd_parse_command
[01:1748].......read command: "cmd=connect src=0 dest=1 tag=0 host=10.97.51.118 id=2 "
[01:1748].......\smpd_handle_command
[01:1748]........handling command:
[01:1748]........ src  = 0
[01:1748]........ dest = 1
[01:1748]........ cmd  = connect
[01:1748]........ tag  = 0
[01:1748]........ ctx  = parent
[01:1748]........ len  = 55
[01:1748]........ str  = cmd=connect src=0 dest=1 tag=0 host=10.97.51.118 id=2 
[01:1748]........\smpd_command_destination
[01:1748].........1 -> 1 : returning NULL context
[01:1748]......../smpd_command_destination
[01:1748]........\smpd_handle_connect_command
[01:1748].........now connecting to 10.97.51.118
[01:1748].........\smpd_create_context
[01:1748]..........\smpd_init_context
[01:1748]...........\smpd_init_command
[01:1748].........../smpd_init_command
[01:1748]........../smpd_init_context
[01:1748]........./smpd_create_context
[01:1748].........\SMPDU_Sock_post_connect
[01:1748]........./SMPDU_Sock_post_connect
[01:1748].........adding new left child context
[01:1748].........\smpd_init_context
[01:1748]..........\smpd_init_command
[01:1748]........../smpd_init_command
[01:1748]..........\SMPDU_Sock_set_user_ptr
[01:1748]........../SMPDU_Sock_set_user_ptr
[01:1748]........./smpd_init_context
[01:1748].........\SMPDU_Sock_set_user_ptr
[01:1748]........./SMPDU_Sock_set_user_ptr
[01:1748]......../smpd_handle_connect_command
[01:1748]......./smpd_handle_command
[01:1748].......\smpd_post_read_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........posting a read for a command header on the parent context, sock 908
[01:1748]........\SMPDU_Sock_post_read
[01:1748].........\SMPDU_Sock_post_readv
[01:1748]........./SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_read
[01:1748]......./smpd_post_read_command
[01:1748]....../smpd_state_reading_cmd
[01:1748]...../smpd_handle_op_read
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_CONNECT event.error = 0, result = 0, context=left
[01:1748].....\smpd_handle_op_connect
[01:1748]......connect succeeded, posting read of the challenge string
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_handle_op_connect
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748].....\smpd_handle_op_read
[01:1748]......\smpd_state_reading_challenge_string
[01:1748].......read challenge string: '1.4.1p1 9221'
[01:1748].......\smpd_verify_version
[01:1748]......./smpd_verify_version
[01:1748].......Verification of smpd version succeeded
[01:1748].......\smpd_hash
[01:1748]......./smpd_hash
[01:1748].......\SMPDU_Sock_post_write
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./SMPDU_Sock_post_write
[01:1748]....../smpd_state_reading_challenge_string
[01:1748]...../smpd_handle_op_read
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:1748].....\smpd_handle_op_write
[01:1748]......\smpd_state_writing_challenge_response
[01:1748].......wrote challenge response: 'dc14b59dcb18d81115a1a8b41408c049'
[01:1748].......\SMPDU_Sock_post_read
[01:1748]........\SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_read
[01:1748]....../smpd_state_writing_challenge_response
[01:1748]...../smpd_handle_op_write
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748].....\smpd_handle_op_read
[01:1748]......\smpd_state_reading_connect_result
[01:1748].......read connect result: 'SUCCESS'
[01:1748].......\SMPDU_Sock_post_write
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./SMPDU_Sock_post_write
[01:1748]....../smpd_state_reading_connect_result
[01:1748]...../smpd_handle_op_read
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:1748].....\smpd_handle_op_write
[01:1748]......\smpd_state_writing_process_session_request
[01:1748].......wrote process session request: 'process'
[01:1748].......\SMPDU_Sock_post_read
[01:1748]........\SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_read
[01:1748]....../smpd_state_writing_process_session_request
[01:1748]...../smpd_handle_op_write
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748].....\smpd_handle_op_read
[01:1748]......\smpd_state_reading_cred_request
[01:1748].......read cred request: 'nocredentials'
[01:1748].......\SMPDU_Sock_post_read
[01:1748]........\SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_read
[01:1748]....../smpd_state_reading_cred_request
[01:1748]...../smpd_handle_op_read
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748].....\smpd_handle_op_read
[01:1748]......\smpd_state_reading_reconnect_request
[01:1748].......read re-connect request: '54273'
[01:1748].......closing the old socket in the left context.
[01:1748].......\SMPDU_Sock_get_sock_id
[01:1748]......./SMPDU_Sock_get_sock_id
[01:1748].......SMPDU_Sock_post_close(924)
[01:1748].......\SMPDU_Sock_post_close
[01:1748]........\SMPDU_Sock_post_read
[01:1748].........\SMPDU_Sock_post_readv
[01:1748]........./SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_read
[01:1748]......./SMPDU_Sock_post_close
[01:1748].......connecting a new socket.
[01:1748].......\smpd_create_context
[01:1748]........\smpd_init_context
[01:1748].........\smpd_init_command
[01:1748]........./smpd_init_command
[01:1748]......../smpd_init_context
[01:1748]......./smpd_create_context
[01:1748].......posting a re-connect to 10.97.51.118:54273 in left context.
[01:1748].......\SMPDU_Sock_post_connect
[01:1748]......./SMPDU_Sock_post_connect
[01:1748]....../smpd_state_reading_reconnect_request
[01:1748]...../smpd_handle_op_read
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_CLOSE event.error = 0, result = 0, context=left
[01:1748].....\smpd_handle_op_close
[01:1748]......\smpd_get_state_string
[01:1748]....../smpd_get_state_string
[01:1748]......op_close received - SMPD_CLOSING state.
[01:1748]......Unaffiliated left context closing.
[01:1748]......\smpd_free_context
[01:1748].......freeing left context.
[01:1748].......\smpd_init_context
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_init_context
[01:1748]....../smpd_free_context
[01:1748]...../smpd_handle_op_close
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_CONNECT event.error = 0, result = 0, context=left
[01:1748].....\smpd_handle_op_connect
[01:1748]......\smpd_generate_session_header
[01:1748].......session header: (id=2 parent=1 level=1)
[01:1748]....../smpd_generate_session_header
[01:1748]......\SMPDU_Sock_post_write
[01:1748].......\SMPDU_Sock_post_writev
[01:1748]......./SMPDU_Sock_post_writev
[01:1748]....../SMPDU_Sock_post_write
[01:1748]...../smpd_handle_op_connect
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:1748].....\smpd_handle_op_write
[01:1748]......\smpd_state_writing_session_header
[01:1748].......wrote session header: 'id=2 parent=1 level=1'
[01:1748].......\smpd_post_read_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........posting a read for a command header on the left context, sock 968
[01:1748]........\SMPDU_Sock_post_read
[01:1748].........\SMPDU_Sock_post_readv
[01:1748]........./SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_read
[01:1748]......./smpd_post_read_command
[01:1748].......\smpd_create_command
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_create_command
[01:1748].......\smpd_add_command_int_arg
[01:1748]......./smpd_add_command_int_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\smpd_command_destination
[01:1748]........1 -> 0 : returning parent_context: 0 < 1
[01:1748]......./smpd_command_destination
[01:1748].......sending result command: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=connect result=SUCCESS "
[01:1748].......\smpd_post_write_command
[01:1748]........\smpd_package_command
[01:1748]......../smpd_package_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........smpd_post_write_command on the parent context sock 908: 86 bytes for command: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=connect result=SUCCESS "
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./smpd_post_write_command
[01:1748]....../smpd_state_writing_session_header
[01:1748]...../smpd_handle_op_write
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1748].....\smpd_handle_op_write
[01:1748]......\smpd_state_writing_cmd
[01:1748].......wrote command
[01:1748].......command written to parent: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=connect result=SUCCESS "
[01:1748].......\smpd_free_command
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_free_command
[01:1748]....../smpd_state_writing_cmd
[01:1748]...../smpd_handle_op_write
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1748].....\smpd_handle_op_read
[01:1748]......\smpd_state_reading_cmd_header
[01:1748].......read command header
[01:1748].......command header read, posting read for data: 34 bytes
[01:1748].......\SMPDU_Sock_post_read
[01:1748]........\SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_read
[01:1748]....../smpd_state_reading_cmd_header
[01:1748]...../smpd_handle_op_read
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1748].....\smpd_handle_op_read
[01:1748]......\smpd_state_reading_cmd
[01:1748].......read command
[01:1748].......\smpd_parse_command
[01:1748]......./smpd_parse_command
[01:1748].......read command: "cmd=start_dbs src=0 dest=1 tag=1 "
[01:1748].......\smpd_handle_command
[01:1748]........handling command:
[01:1748]........ src  = 0
[01:1748]........ dest = 1
[01:1748]........ cmd  = start_dbs
[01:1748]........ tag  = 1
[01:1748]........ ctx  = parent
[01:1748]........ len  = 34
[01:1748]........ str  = cmd=start_dbs src=0 dest=1 tag=1 
[01:1748]........\smpd_command_destination
[01:1748].........1 -> 1 : returning NULL context
[01:1748]......../smpd_command_destination
[01:1748]........\smpd_handle_start_dbs_command
[01:1748].........\smpd_dbs_init
[01:1748]........./smpd_dbs_init
[01:1748].........\smpd_create_command
[01:1748]..........\smpd_init_command
[01:1748]........../smpd_init_command
[01:1748]........./smpd_create_command
[01:1748].........\smpd_add_command_int_arg
[01:1748]........./smpd_add_command_int_arg
[01:1748].........\smpd_add_command_arg
[01:1748]........./smpd_add_command_arg
[01:1748].........\smpd_dbs_create
[01:1748]........./smpd_dbs_create
[01:1748].........\smpd_add_command_arg
[01:1748]........./smpd_add_command_arg
[01:1748].........\smpd_add_command_arg
[01:1748]........./smpd_add_command_arg
[01:1748].........\smpd_add_command_arg
[01:1748]........./smpd_add_command_arg
[01:1748].........sending result command to parent context: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=start_dbs kvs_name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 domain_name=63628958-A50D-4b1a-B625-D186FDE790F1 result=SUCCESS "
[01:1748].........\smpd_post_write_command
[01:1748]..........\smpd_package_command
[01:1748]........../smpd_package_command
[01:1748]..........\SMPDU_Sock_get_sock_id
[01:1748]........../SMPDU_Sock_get_sock_id
[01:1748]..........smpd_post_write_command on the parent context sock 908: 183 bytes for command: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=start_dbs kvs_name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 domain_name=63628958-A50D-4b1a-B625-D186FDE790F1 result=SUCCESS "
[01:1748]..........\SMPDU_Sock_post_writev
[01:1748]........../SMPDU_Sock_post_writev
[01:1748]........./smpd_post_write_command
[01:1748]......../smpd_handle_start_dbs_command
[01:1748]......./smpd_handle_command
[01:1748].......\smpd_post_read_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........posting a read for a command header on the parent context, sock 908
[01:1748]........\SMPDU_Sock_post_read
[01:1748].........\SMPDU_Sock_post_readv
[01:1748]........./SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_read
[01:1748]......./smpd_post_read_command
[01:1748]....../smpd_state_reading_cmd
[01:1748]...../smpd_handle_op_read
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1748].....\smpd_handle_op_write
[01:1748]......\smpd_state_writing_cmd
[01:1748].......wrote command
[01:1748].......command written to parent: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=start_dbs kvs_name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 domain_name=63628958-A50D-4b1a-B625-D186FDE790F1 result=SUCCESS "
[01:1748].......\smpd_free_command
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_free_command
[01:1748]....../smpd_state_writing_cmd
[01:1748]...../smpd_handle_op_write
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1748].....\smpd_handle_op_read
[01:1748]......\smpd_state_reading_cmd_header
[01:1748].......read command header
[01:1748].......command header read, posting read for data: 237 bytes
[01:1748].......\SMPDU_Sock_post_read
[01:1748]........\SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_read
[01:1748]....../smpd_state_reading_cmd_header
[01:1748]...../smpd_handle_op_read
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1748].....\smpd_handle_op_read
[01:1748]......\smpd_state_reading_cmd
[01:1748].......read command
[01:1748].......\smpd_parse_command
[01:1748]......./smpd_parse_command
[01:1748].......read command: "cmd=launch src=0 dest=2 tag=2 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=shm" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=1 i=1 n=2 k=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 kd=63628958-A50D-4b1a-B625-D186FDE790F1 "
[01:1748].......\smpd_handle_command
[01:1748]........handling command:
[01:1748]........ src  = 0
[01:1748]........ dest = 2
[01:1748]........ cmd  = launch
[01:1748]........ tag  = 2
[01:1748]........ ctx  = parent
[01:1748]........ len  = 237
[01:1748]........ str  = cmd=launch src=0 dest=2 tag=2 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=shm" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=1 i=1 n=2 k=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 kd=63628958-A50D-4b1a-B625-D186FDE790F1 
[01:1748]........\smpd_command_destination
[01:1748].........1 -> 2 : returning left_context
[01:1748]......../smpd_command_destination
[01:1748]........forwarding command to 2
[01:1748]........\smpd_forward_command
[01:1748].........\smpd_create_command_copy
[01:1748]........./smpd_create_command_copy
[01:1748].........posting write of forwarded command: "cmd=launch src=0 dest=2 tag=2 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=shm" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=1 i=1 n=2 k=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 kd=63628958-A50D-4b1a-B625-D186FDE790F1 "
[01:1748].........\smpd_post_write_command
[01:1748]..........\smpd_package_command
[01:1748]........../smpd_package_command
[01:1748]..........\SMPDU_Sock_get_sock_id
[01:1748]........../SMPDU_Sock_get_sock_id
[01:1748]..........smpd_post_write_command on the left context sock 968: 250 bytes for command: "cmd=launch src=0 dest=2 tag=2 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=shm" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=1 i=1 n=2 k=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 kd=63628958-A50D-4b1a-B625-D186FDE790F1 "
[01:1748]..........\SMPDU_Sock_post_writev
[01:1748]........../SMPDU_Sock_post_writev
[01:1748]........./smpd_post_write_command
[01:1748]......../smpd_forward_command
[01:1748]......./smpd_handle_command
[01:1748].......\smpd_post_read_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........posting a read for a command header on the parent context, sock 908
[01:1748]........\SMPDU_Sock_post_read
[01:1748].........\SMPDU_Sock_post_readv
[01:1748]........./SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_read
[01:1748]......./smpd_post_read_command
[01:1748]....../smpd_state_reading_cmd
[01:1748]...../smpd_handle_op_read
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:1748].....\smpd_handle_op_write
[01:1748]......\smpd_state_writing_cmd
[01:1748].......wrote command
[01:1748].......command written to left: "cmd=launch src=0 dest=2 tag=2 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=shm" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=1 i=1 n=2 k=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 kd=63628958-A50D-4b1a-B625-D186FDE790F1 "
[01:1748].......\smpd_free_command
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_free_command
[01:1748]....../smpd_state_writing_cmd
[01:1748]...../smpd_handle_op_write
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1748].....\smpd_handle_op_read
[01:1748]......\smpd_state_reading_cmd_header
[01:1748].......read command header
[01:1748].......command header read, posting read for data: 237 bytes
[01:1748].......\SMPDU_Sock_post_read
[01:1748]........\SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_read
[01:1748]....../smpd_state_reading_cmd_header
[01:1748]...../smpd_handle_op_read
[01:1748].....sock_waiting for the next event.
[01:1748].....\SMPDU_Sock_wait
[01:1748]...../SMPDU_Sock_wait
[01:1748].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1748].....\smpd_handle_op_read
[01:1748]......\smpd_state_reading_cmd
[01:1748].......read command
[01:1748].......\smpd_parse_command
[01:1748]......./smpd_parse_command
[01:1748].......read command: "cmd=launch src=0 dest=1 tag=3 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=shm" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=0 i=0 n=2 k=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 kd=63628958-A50D-4b1a-B625-D186FDE790F1 "
[01:1748].......\smpd_handle_command
[01:1748]........handling command:
[01:1748]........ src  = 0
[01:1748]........ dest = 1
[01:1748]........ cmd  = launch
[01:1748]........ tag  = 3
[01:1748]........ ctx  = parent
[01:1748]........ len  = 237
[01:1748]........ str  = cmd=launch src=0 dest=1 tag=3 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=shm" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=0 i=0 n=2 k=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 kd=63628958-A50D-4b1a-B625-D186FDE790F1 
[01:1748]........\smpd_command_destination
[01:1748].........1 -> 1 : returning NULL context
[01:1748]......../smpd_command_destination
[01:1748]........\smpd_handle_launch_command
[01:1748].........\smpd_create_process_struct
[01:1748]..........\smpd_create_context
[01:1748]...........\smpd_init_context
[01:1748]............\smpd_init_command
[01:1748]............/smpd_init_command
[01:1748].........../smpd_init_context
[01:1748]........../smpd_create_context
[01:1748]..........\smpd_create_context
[01:1748]...........\smpd_init_context
[01:1748]............\smpd_init_command
[01:1748]............/smpd_init_command
[01:1748].........../smpd_init_context
[01:1748]........../smpd_create_context
[01:1748]..........\smpd_create_context
[01:1748]...........\smpd_init_context
[01:1748]............\smpd_init_command
[01:1748]............/smpd_init_command
[01:1748].........../smpd_init_context
[01:1748]........../smpd_create_context
[01:1748]..........\smpd_create_context
[01:1748]...........\smpd_init_context
[01:1748]............\smpd_init_command
[01:1748]............/smpd_init_command
[01:1748].........../smpd_init_context
[01:1748]........../smpd_create_context
[01:1748]........./smpd_create_process_struct
[01:1748].........launching: 'cpi.exe'
[01:1748].........\smpd_launch_process
[01:1748]..........searching for 'cpi.exe' in 'C:\Program Files\MPICH2\examples'
[01:1748]..........\smpd_search_path
[01:1748]........../smpd_search_path
[01:1748]..........\smpd_priority_class_to_win_class
[01:1748]........../smpd_priority_class_to_win_class
[01:1748]..........\smpd_priority_to_win_priority
[01:1748]........../smpd_priority_to_win_priority
[01:1748]..........\smpd_make_socket_loop_choose
[01:1748]...........\smpd_get_hostname
[01:1748].........../smpd_get_hostname
[01:1748]........../smpd_make_socket_loop_choose
[01:1748]..........\smpd_make_socket_loop_choose
[01:1748]...........\smpd_get_hostname
[01:1748].........../smpd_get_hostname
[01:1748]........../smpd_make_socket_loop_choose
[01:1748]..........\smpd_make_socket_loop_choose
[01:1748]...........\smpd_get_hostname
[01:1748].........../smpd_get_hostname
[01:1748]........../smpd_make_socket_loop_choose
[01:1748]..........\SMPDU_Sock_listen
[01:1748]........../SMPDU_Sock_listen
[01:1748]..........pmi listening on port 63410
[01:1748]..........\smpd_create_context
[01:1748]...........\smpd_init_context
[01:1748]............\smpd_init_command
[01:1748]............/smpd_init_command
[01:1748]............\SMPDU_Sock_set_user_ptr
[01:1748]............/SMPDU_Sock_set_user_ptr
[01:1748].........../smpd_init_context
[01:1748]........../smpd_create_context
[01:1748]..........\SMPDU_Sock_set_user_ptr
[01:1748]........../SMPDU_Sock_set_user_ptr
[01:1748]........./SMPDU_Sock_get_host_description
[01:1748].........\SMPDU_Sock_hostname_to_host_description
[01:1748]..........adding host: EC2AMAZ-BULC3HN to host list
[01:1748]..........adding host: EC2AMAZ-BULC3HN.ec2.internal to host list
[01:1748]..........adding host: ::1 to host list
[01:1748]..........adding host: 10.190.238.8 to host list
[01:1748]........./SMPDU_Sock_hostname_to_host_description
[01:1748]......../SMPDU_Sock_get_host_description
[01:1748]........\SetEnvironmentVariables
[01:1748].........setting environment variable: <MPICH2_CHANNEL> = <shm>
[01:1748]......../SetEnvironmentVariables
[01:1748]........env: PMI_RANK=0
[01:1748]........env: PMI_SIZE=2
[01:1748]........env: PMI_KVS=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31
[01:1748]........env: PMI_DOMAIN=63628958-A50D-4b1a-B625-D186FDE790F1
[01:1748]........env: PMI_HOST=EC2AMAZ-BULC3HN EC2AMAZ-BULC3HN.ec2.internal ::1 10.190.238.8 
[01:1748]........env: PMI_PORT=63410
[01:1748]........env: PMI_SMPD_ID=1
[01:1748]........env: PMI_SMPD_KEY=0
[01:1748]........env: PMI_CLIQUE=0
[01:1748]........env: PMI_SPAWN=0
[01:1748]........env: PMI_APPNUM=0
[01:1748]........CreateProcess(C:\Program Files\MPICH2\examples\cpi.exe)
[01:1748]........\RemoveEnvironmentVariables
[01:1748]......../RemoveEnvironmentVariables
[01:1748]........\SMPDU_Sock_native_to_sock
[01:1748]......../SMPDU_Sock_native_to_sock
[01:1748]........\SMPDU_Sock_native_to_sock
[01:1748]......../SMPDU_Sock_native_to_sock
[01:1748]........\SMPDU_Sock_native_to_sock
[01:1748]......../SMPDU_Sock_native_to_sock
[01:1748]........\SMPDU_Sock_set_user_ptr
[01:1748]......../SMPDU_Sock_set_user_ptr
[01:1748]........\SMPDU_Sock_set_user_ptr
[01:1748]......../SMPDU_Sock_set_user_ptr
[01:1748]........\SMPDU_Sock_set_user_ptr
[01:1748]......../SMPDU_Sock_set_user_ptr
[01:1748]........\SMPDU_Sock_post_read
[01:1748].........\SMPDU_Sock_post_readv
[01:1748]........./SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_read
[01:1748]........\SMPDU_Sock_post_read
[01:1748].........\SMPDU_Sock_post_readv
[01:1748]........./SMPDU_Sock_post_readv
[01:1748]......../SMPDU_Sock_post_read
[01:1748]........\smpd_process_to_registry
[01:1748]......../smpd_process_to_registry
[01:1748]......./smpd_launch_process
[01:1748].......\smpd_create_command
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_create_command
[01:1748].......\smpd_add_command_int_arg
[01:1748]......./smpd_add_command_int_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\smpd_add_command_int_arg
[01:1748]......./smpd_add_command_int_arg
[01:1748].......\smpd_add_command_int_arg
[01:1748]......./smpd_add_command_int_arg
[01:1748].......\smpd_post_write_command
[01:1748]........\smpd_package_command
[01:1748]......../smpd_package_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........smpd_post_write_command on the parent context sock 908: 147 bytes for command: "cmd=result src=1 dest=0 tag=2 cmd_tag=3 cmd_orig=launch result=SUCCESS pg_id=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 pg_rank=0 pg_ctx=0 "
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./smpd_post_write_command
[01:1748]....../smpd_handle_launch_command
[01:1748]...../smpd_handle_command
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the parent context, sock 908
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_reading_cmd
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1748]...\smpd_handle_op_write
[01:1748]....\smpd_state_writing_cmd
[01:1748].....wrote command
[01:1748].....command written to parent: "cmd=result src=1 dest=0 tag=2 cmd_tag=3 cmd_orig=launch result=SUCCESS pg_id=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 pg_rank=0 pg_ctx=0 "
[01:1748].....\smpd_free_command
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_free_command
[01:1748]..../smpd_state_writing_cmd
[01:1748].../smpd_handle_op_write
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748]....*** entering smpd_pinthread pid:2348 sock:780 ***
[01:1748]....*** entering smpd_piothread pid:2348 sock:972 ***
[01:1748]....*** entering smpd_piothread pid:2348 sock:980 ***
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_ACCEPT event.error = 0, result = 0, context=PMI_LISTENER
[01:1748]...\smpd_handle_op_accept
[01:1748]....\smpd_state_pmi_listening
[01:1748].....\SMPDU_Sock_accept
[01:1748]...../SMPDU_Sock_accept
[01:1748].....\SMPDU_Sock_post_close
[01:1748]...../SMPDU_Sock_post_close
[01:1748].....\SMPDU_Sock_set_user_ptr
[01:1748]...../SMPDU_Sock_set_user_ptr
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the pmi context, sock 1124
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_pmi_listening
[01:1748].../smpd_handle_op_accept
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_CLOSE event.error = 0, result = 0, context=PMI_LISTENER
[01:1748]...\smpd_handle_op_close
[01:1748]....\smpd_get_state_string
[01:1748]..../smpd_get_state_string
[01:1748]....op_close received - SMPD_CLOSING state.
[01:1748]....Unaffiliated PMI_LISTENER context closing.
[01:1748]....\smpd_free_context
[01:1748].....freeing PMI_LISTENER context.
[01:1748].....\smpd_init_context
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_init_context
[01:1748]..../smpd_free_context
[01:1748].../smpd_handle_op_close
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748]....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd_header
[01:1748].....read command header
[01:1748].....command header read, posting read for data: 134 bytes
[01:1748].....\SMPDU_Sock_post_read
[01:1748]......\SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_readv
[01:1748]...../SMPDU_Sock_post_read
[01:1748]..../smpd_state_reading_cmd_header
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd
[01:1748].....read command
[01:1748].....\smpd_parse_command
[01:1748]...../smpd_parse_command
[01:1748].....read command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 pg_rank=1 pg_ctx=0 "
[01:1748].....\smpd_handle_command
[01:1748]......handling command:
[01:1748]...... src  = 2
[01:1748]...... dest = 0
[01:1748]...... cmd  = result
[01:1748]...... tag  = 0
[01:1748]...... ctx  = left
[01:1748]...... len  = 134
[01:1748]...... str  = cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 pg_rank=1 pg_ctx=0 
[01:1748]......\smpd_command_destination
[01:1748].......1 -> 0 : returning parent_context: 0 < 1
[01:1748]....../smpd_command_destination
[01:1748]......forwarding command to 0
[01:1748]......\smpd_forward_command
[01:1748].......\smpd_create_command_copy
[01:1748]......./smpd_create_command_copy
[01:1748].......posting write of forwarded command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 pg_rank=1 pg_ctx=0 "
[01:1748].......\smpd_post_write_command
[01:1748]........\smpd_package_command
[01:1748]......../smpd_package_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........smpd_post_write_command on the parent context sock 908: 147 bytes for command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 pg_rank=1 pg_ctx=0 "
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./smpd_post_write_command
[01:1748]....../smpd_forward_command
[01:1748]...../smpd_handle_command
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the left context, sock 968
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_reading_cmd
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1748]...\smpd_handle_op_write
[01:1748]....\smpd_state_writing_cmd
[01:1748].....wrote command
[01:1748].....command written to parent: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 pg_rank=1 pg_ctx=0 "
[01:1748].....\smpd_free_command
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_free_command
[01:1748]..../smpd_state_writing_cmd
[01:1748].../smpd_handle_op_write
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd_header
[01:1748].....read command header
[01:1748].....command header read, posting read for data: 105 bytes
[01:1748].....\SMPDU_Sock_post_read
[01:1748]......\SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_readv
[01:1748]...../SMPDU_Sock_post_read
[01:1748]..../smpd_state_reading_cmd_header
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd
[01:1748].....read command
[01:1748].....\smpd_parse_command
[01:1748]...../smpd_parse_command
[01:1748].....read command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=0 value=2 node_id=1 "
[01:1748].....\smpd_handle_command
[01:1748]......handling command:
[01:1748]...... src  = 1
[01:1748]...... dest = 0
[01:1748]...... cmd  = init
[01:1748]...... tag  = 0
[01:1748]...... ctx  = pmi
[01:1748]...... len  = 105
[01:1748]...... str  = cmd=init src=1 dest=0 tag=0 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=0 value=2 node_id=1 
[01:1748]......\smpd_command_destination
[01:1748].......1 -> 0 : returning parent_context: 0 < 1
[01:1748]....../smpd_command_destination
[01:1748]......forwarding command to 0
[01:1748]......\smpd_forward_command
[01:1748].......\smpd_create_command_copy
[01:1748]......./smpd_create_command_copy
[01:1748].......posting write of forwarded command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=0 value=2 node_id=1 "
[01:1748].......\smpd_post_write_command
[01:1748]........\smpd_package_command
[01:1748]......../smpd_package_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........smpd_post_write_command on the parent context sock 908: 118 bytes for command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=0 value=2 node_id=1 "
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./smpd_post_write_command
[01:1748]....../smpd_forward_command
[01:1748]...../smpd_handle_command
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the pmi context, sock 1124
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_reading_cmd
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1748]...\smpd_handle_op_write
[01:1748]....\smpd_state_writing_cmd
[01:1748].....wrote command
[01:1748].....command written to parent: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=0 value=2 node_id=1 "
[01:1748].....\smpd_free_command
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_free_command
[01:1748]..../smpd_state_writing_cmd
[01:1748].../smpd_handle_op_write
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd_header
[01:1748].....read command header
[01:1748].....command header read, posting read for data: 66 bytes
[01:1748].....\SMPDU_Sock_post_read
[01:1748]......\SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_readv
[01:1748]...../SMPDU_Sock_post_read
[01:1748]..../smpd_state_reading_cmd_header
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd
[01:1748].....read command
[01:1748].....\smpd_parse_command
[01:1748]...../smpd_parse_command
[01:1748].....read command: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:1748].....\smpd_handle_command
[01:1748]......handling command:
[01:1748]...... src  = 0
[01:1748]...... dest = 1
[01:1748]...... cmd  = result
[01:1748]...... tag  = 4
[01:1748]...... ctx  = parent
[01:1748]...... len  = 66
[01:1748]...... str  = cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS 
[01:1748]......\smpd_command_destination
[01:1748].......1 -> 1 : returning NULL context
[01:1748]....../smpd_command_destination
[01:1748]......\smpd_handle_result
[01:1748].......forwarding the dbs result command to the pmi context 0.
[01:1748].......\smpd_forward_command
[01:1748]........\smpd_create_command_copy
[01:1748]......../smpd_create_command_copy
[01:1748]........posting write of forwarded command: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:1748]........\smpd_post_write_command
[01:1748].........\smpd_package_command
[01:1748]........./smpd_package_command
[01:1748].........\SMPDU_Sock_get_sock_id
[01:1748]........./SMPDU_Sock_get_sock_id
[01:1748].........smpd_post_write_command on the pmi context sock 1124: 79 bytes for command: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:1748].........\SMPDU_Sock_post_writev
[01:1748]........./SMPDU_Sock_post_writev
[01:1748]......../smpd_post_write_command
[01:1748]......./smpd_forward_command
[01:1748]....../smpd_handle_result
[01:1748]...../smpd_handle_command
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the parent context, sock 908
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_reading_cmd
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1748]...\smpd_handle_op_write
[01:1748]....\smpd_state_writing_cmd
[01:1748].....wrote command
[01:1748].....command written to pmi: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:1748].....\smpd_free_command
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_free_command
[01:1748]..../smpd_state_writing_cmd
[01:1748].../smpd_handle_op_write
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd_header
[01:1748].....read command header
[01:1748].....command header read, posting read for data: 138 bytes
[01:1748].....\SMPDU_Sock_post_read
[01:1748]......\SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_readv
[01:1748]...../SMPDU_Sock_post_read
[01:1748]..../smpd_state_reading_cmd_header
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd
[01:1748].....read command
[01:1748].....\smpd_parse_command
[01:1748]...../smpd_parse_command
[01:1748].....read command: "cmd=dbput src=1 dest=1 tag=1 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=SHMEMKEY value=A7C4A2B7-4CD7-4c8c-BF62-186AE666205A "
[01:1748].....\smpd_handle_command
[01:1748]......handling command:
[01:1748]...... src  = 1
[01:1748]...... dest = 1
[01:1748]...... cmd  = dbput
[01:1748]...... tag  = 1
[01:1748]...... ctx  = pmi
[01:1748]...... len  = 138
[01:1748]...... str  = cmd=dbput src=1 dest=1 tag=1 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=SHMEMKEY value=A7C4A2B7-4CD7-4c8c-BF62-186AE666205A 
[01:1748]......\smpd_command_destination
[01:1748].......1 -> 1 : returning NULL context
[01:1748]....../smpd_command_destination
[01:1748]......\smpd_handle_dbs_command
[01:1748].......\smpd_create_command
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_create_command
[01:1748].......\smpd_add_command_int_arg
[01:1748]......./smpd_add_command_int_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\get_name_key_value
[01:1748]......./get_name_key_value
[01:1748].......\smpd_dbs_put
[01:1748]......./smpd_dbs_put
[01:1748].......sending reply to dbs command 'cmd=dbput src=1 dest=1 tag=1 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=SHMEMKEY value=A7C4A2B7-4CD7-4c8c-BF62-186AE666205A '.
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......sending result command to pmi context: "cmd=result src=1 dest=1 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:1748].......\smpd_post_write_command
[01:1748]........\smpd_package_command
[01:1748]......../smpd_package_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........smpd_post_write_command on the pmi context sock 1124: 98 bytes for command: "cmd=result src=1 dest=1 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./smpd_post_write_command
[01:1748]....../smpd_handle_dbs_command
[01:1748]...../smpd_handle_command
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the pmi context, sock 1124
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_reading_cmd
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1748]...\smpd_handle_op_write
[01:1748]....\smpd_state_writing_cmd
[01:1748].....wrote command
[01:1748].....command written to pmi: "cmd=result src=1 dest=1 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:1748].....\smpd_free_command
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_free_command
[01:1748]..../smpd_state_writing_cmd
[01:1748].../smpd_handle_op_write
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd_header
[01:1748].....read command header
[01:1748].....command header read, posting read for data: 116 bytes
[01:1748].....\SMPDU_Sock_post_read
[01:1748]......\SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_readv
[01:1748]...../SMPDU_Sock_post_read
[01:1748]..../smpd_state_reading_cmd_header
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd
[01:1748].....read command
[01:1748].....\smpd_parse_command
[01:1748]...../smpd_parse_command
[01:1748].....read command: "cmd=dbput src=1 dest=1 tag=2 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=SHMHOST value=EC2AMAZ-BULC3HN "
[01:1748].....\smpd_handle_command
[01:1748]......handling command:
[01:1748]...... src  = 1
[01:1748]...... dest = 1
[01:1748]...... cmd  = dbput
[01:1748]...... tag  = 2
[01:1748]...... ctx  = pmi
[01:1748]...... len  = 116
[01:1748]...... str  = cmd=dbput src=1 dest=1 tag=2 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=SHMHOST value=EC2AMAZ-BULC3HN 
[01:1748]......\smpd_command_destination
[01:1748].......1 -> 1 : returning NULL context
[01:1748]....../smpd_command_destination
[01:1748]......\smpd_handle_dbs_command
[01:1748].......\smpd_create_command
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_create_command
[01:1748].......\smpd_add_command_int_arg
[01:1748]......./smpd_add_command_int_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\get_name_key_value
[01:1748]......./get_name_key_value
[01:1748].......\smpd_dbs_put
[01:1748]......./smpd_dbs_put
[01:1748].......sending reply to dbs command 'cmd=dbput src=1 dest=1 tag=2 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=SHMHOST value=EC2AMAZ-BULC3HN '.
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......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:1748].......\smpd_post_write_command
[01:1748]........\smpd_package_command
[01:1748]......../smpd_package_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........smpd_post_write_command on the pmi context sock 1124: 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:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./smpd_post_write_command
[01:1748]....../smpd_handle_dbs_command
[01:1748]...../smpd_handle_command
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the pmi context, sock 1124
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_reading_cmd
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1748]...\smpd_handle_op_write
[01:1748]....\smpd_state_writing_cmd
[01:1748].....wrote command
[01:1748].....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:1748].....\smpd_free_command
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_free_command
[01:1748]..../smpd_state_writing_cmd
[01:1748].../smpd_handle_op_write
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd_header
[01:1748].....read command header
[01:1748].....command header read, posting read for data: 92 bytes
[01:1748].....\SMPDU_Sock_post_read
[01:1748]......\SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_readv
[01:1748]...../SMPDU_Sock_post_read
[01:1748]..../smpd_state_reading_cmd_header
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd
[01:1748].....read command
[01:1748].....\smpd_parse_command
[01:1748]...../smpd_parse_command
[01:1748].....read command: "cmd=barrier src=1 dest=1 tag=3 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 value=2 "
[01:1748].....\smpd_handle_command
[01:1748]......handling command:
[01:1748]...... src  = 1
[01:1748]...... dest = 1
[01:1748]...... cmd  = barrier
[01:1748]...... tag  = 3
[01:1748]...... ctx  = pmi
[01:1748]...... len  = 92
[01:1748]...... str  = cmd=barrier src=1 dest=1 tag=3 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 value=2 
[01:1748]......\smpd_command_destination
[01:1748].......1 -> 1 : returning NULL context
[01:1748]....../smpd_command_destination
[01:1748]......\smpd_handle_barrier_command
[01:1748].......initializing barrier(BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31): in=1 size=2
[01:1748]....../smpd_handle_barrier_command
[01:1748]...../smpd_handle_command
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the pmi context, sock 1124
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_reading_cmd
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd_header
[01:1748].....read command header
[01:1748].....command header read, posting read for data: 105 bytes
[01:1748].....\SMPDU_Sock_post_read
[01:1748]......\SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_readv
[01:1748]...../SMPDU_Sock_post_read
[01:1748]..../smpd_state_reading_cmd_header
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd
[01:1748].....read command
[01:1748].....\smpd_parse_command
[01:1748]...../smpd_parse_command
[01:1748].....read command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=1 value=2 node_id=2 "
[01:1748].....\smpd_handle_command
[01:1748]......handling command:
[01:1748]...... src  = 2
[01:1748]...... dest = 0
[01:1748]...... cmd  = init
[01:1748]...... tag  = 0
[01:1748]...... ctx  = left
[01:1748]...... len  = 105
[01:1748]...... str  = cmd=init src=2 dest=0 tag=0 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=1 value=2 node_id=2 
[01:1748]......\smpd_command_destination
[01:1748].......1 -> 0 : returning parent_context: 0 < 1
[01:1748]....../smpd_command_destination
[01:1748]......forwarding command to 0
[01:1748]......\smpd_forward_command
[01:1748].......\smpd_create_command_copy
[01:1748]......./smpd_create_command_copy
[01:1748].......posting write of forwarded command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=1 value=2 node_id=2 "
[01:1748].......\smpd_post_write_command
[01:1748]........\smpd_package_command
[01:1748]......../smpd_package_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........smpd_post_write_command on the parent context sock 908: 118 bytes for command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=1 value=2 node_id=2 "
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./smpd_post_write_command
[01:1748]....../smpd_forward_command
[01:1748]...../smpd_handle_command
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the left context, sock 968
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_reading_cmd
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1748]...\smpd_handle_op_write
[01:1748]....\smpd_state_writing_cmd
[01:1748].....wrote command
[01:1748].....command written to parent: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=1 value=2 node_id=2 "
[01:1748].....\smpd_free_command
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_free_command
[01:1748]..../smpd_state_writing_cmd
[01:1748].../smpd_handle_op_write
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd_header
[01:1748].....read command header
[01:1748].....command header read, posting read for data: 66 bytes
[01:1748].....\SMPDU_Sock_post_read
[01:1748]......\SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_readv
[01:1748]...../SMPDU_Sock_post_read
[01:1748]..../smpd_state_reading_cmd_header
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd
[01:1748].....read command
[01:1748].....\smpd_parse_command
[01:1748]...../smpd_parse_command
[01:1748].....read command: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:1748].....\smpd_handle_command
[01:1748]......handling command:
[01:1748]...... src  = 0
[01:1748]...... dest = 2
[01:1748]...... cmd  = result
[01:1748]...... tag  = 5
[01:1748]...... ctx  = parent
[01:1748]...... len  = 66
[01:1748]...... str  = cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS 
[01:1748]......\smpd_command_destination
[01:1748].......1 -> 2 : returning left_context
[01:1748]....../smpd_command_destination
[01:1748]......forwarding command to 2
[01:1748]......\smpd_forward_command
[01:1748].......\smpd_create_command_copy
[01:1748]......./smpd_create_command_copy
[01:1748].......posting write of forwarded command: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:1748].......\smpd_post_write_command
[01:1748]........\smpd_package_command
[01:1748]......../smpd_package_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........smpd_post_write_command on the left context sock 968: 79 bytes for command: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./smpd_post_write_command
[01:1748]....../smpd_forward_command
[01:1748]...../smpd_handle_command
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the parent context, sock 908
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_reading_cmd
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_write
[01:1748]....\smpd_state_writing_cmd
[01:1748].....wrote command
[01:1748].....command written to left: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:1748].....\smpd_free_command
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_free_command
[01:1748]..../smpd_state_writing_cmd
[01:1748].../smpd_handle_op_write
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd_header
[01:1748].....read command header
[01:1748].....command header read, posting read for data: 92 bytes
[01:1748].....\SMPDU_Sock_post_read
[01:1748]......\SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_readv
[01:1748]...../SMPDU_Sock_post_read
[01:1748]..../smpd_state_reading_cmd_header
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd
[01:1748].....read command
[01:1748].....\smpd_parse_command
[01:1748]...../smpd_parse_command
[01:1748].....read command: "cmd=barrier src=2 dest=1 tag=1 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 value=2 "
[01:1748].....\smpd_handle_command
[01:1748]......handling command:
[01:1748]...... src  = 2
[01:1748]...... dest = 1
[01:1748]...... cmd  = barrier
[01:1748]...... tag  = 1
[01:1748]...... ctx  = left
[01:1748]...... len  = 92
[01:1748]...... str  = cmd=barrier src=2 dest=1 tag=1 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 value=2 
[01:1748]......\smpd_command_destination
[01:1748].......1 -> 1 : returning NULL context
[01:1748]....../smpd_command_destination
[01:1748]......\smpd_handle_barrier_command
[01:1748].......incrementing barrier(BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31) incount from 1 to 2 out of 2
[01:1748].......all in barrier, sending result back to all participators.
[01:1748].......\smpd_create_command
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_create_command
[01:1748].......\smpd_add_command_int_arg
[01:1748]......./smpd_add_command_int_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......sending reply to barrier command 'BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31'.
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......sending result command to left context: "cmd=result src=1 dest=1 tag=5 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:1748].......\smpd_post_write_command
[01:1748]........\smpd_package_command
[01:1748]......../smpd_package_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........smpd_post_write_command on the pmi context sock 1124: 100 bytes for command: "cmd=result src=1 dest=1 tag=5 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./smpd_post_write_command
[01:1748].......\smpd_create_command
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_create_command
[01:1748].......\smpd_add_command_int_arg
[01:1748]......./smpd_add_command_int_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......sending reply to barrier command 'BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31'.
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......sending result command to left context: "cmd=result src=1 dest=2 tag=6 cmd_tag=1 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:1748].......\smpd_post_write_command
[01:1748]........\smpd_package_command
[01:1748]......../smpd_package_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........smpd_post_write_command on the left context sock 968: 100 bytes for command: "cmd=result src=1 dest=2 tag=6 cmd_tag=1 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./smpd_post_write_command
[01:1748]....../smpd_handle_barrier_command
[01:1748]...../smpd_handle_command
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the left context, sock 968
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_reading_cmd
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1748]...\smpd_handle_op_write
[01:1748]....\smpd_state_writing_cmd
[01:1748].....wrote command
[01:1748].....command written to pmi: "cmd=result src=1 dest=1 tag=5 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:1748].....\smpd_free_command
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_free_command
[01:1748]..../smpd_state_writing_cmd
[01:1748].../smpd_handle_op_write
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_write
[01:1748]....\smpd_state_writing_cmd
[01:1748].....wrote command
[01:1748].....command written to left: "cmd=result src=1 dest=2 tag=6 cmd_tag=1 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:1748].....\smpd_free_command
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_free_command
[01:1748]..../smpd_state_writing_cmd
[01:1748].../smpd_handle_op_write
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd_header
[01:1748].....read command header
[01:1748].....command header read, posting read for data: 95 bytes
[01:1748].....\SMPDU_Sock_post_read
[01:1748]......\SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_readv
[01:1748]...../SMPDU_Sock_post_read
[01:1748]..../smpd_state_reading_cmd_header
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd
[01:1748].....read command
[01:1748].....\smpd_parse_command
[01:1748]...../smpd_parse_command
[01:1748].....read command: "cmd=dbget src=2 dest=1 tag=2 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=SHMEMKEY "
[01:1748].....\smpd_handle_command
[01:1748]......handling command:
[01:1748]...... src  = 2
[01:1748]...... dest = 1
[01:1748]...... cmd  = dbget
[01:1748]...... tag  = 2
[01:1748]...... ctx  = left
[01:1748]...... len  = 95
[01:1748]...... str  = cmd=dbget src=2 dest=1 tag=2 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=SHMEMKEY 
[01:1748]......\smpd_command_destination
[01:1748].......1 -> 1 : returning NULL context
[01:1748]....../smpd_command_destination
[01:1748]......\smpd_handle_dbs_command
[01:1748].......\smpd_create_command
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_create_command
[01:1748].......\smpd_add_command_int_arg
[01:1748]......./smpd_add_command_int_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\get_name_key_value
[01:1748]......./get_name_key_value
[01:1748].......\smpd_dbs_get
[01:1748]......./smpd_dbs_get
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......sending reply to dbs command 'cmd=dbget src=2 dest=1 tag=2 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=SHMEMKEY '.
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......sending result command to left context: "cmd=result src=1 dest=2 tag=7 cmd_tag=2 cmd_orig=dbget ctx_key=0 value=A7C4A2B7-4CD7-4c8c-BF62-186AE666205A result=DBS_SUCCESS "
[01:1748].......\smpd_post_write_command
[01:1748]........\smpd_package_command
[01:1748]......../smpd_package_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........smpd_post_write_command on the left context sock 968: 141 bytes for command: "cmd=result src=1 dest=2 tag=7 cmd_tag=2 cmd_orig=dbget ctx_key=0 value=A7C4A2B7-4CD7-4c8c-BF62-186AE666205A result=DBS_SUCCESS "
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./smpd_post_write_command
[01:1748]....../smpd_handle_dbs_command
[01:1748]...../smpd_handle_command
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the left context, sock 968
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_reading_cmd
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_write
[01:1748]....\smpd_state_writing_cmd
[01:1748].....wrote command
[01:1748].....command written to left: "cmd=result src=1 dest=2 tag=7 cmd_tag=2 cmd_orig=dbget ctx_key=0 value=A7C4A2B7-4CD7-4c8c-BF62-186AE666205A result=DBS_SUCCESS "
[01:1748].....\smpd_free_command
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_free_command
[01:1748]..../smpd_state_writing_cmd
[01:1748].../smpd_handle_op_write
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd_header
[01:1748].....read command header
[01:1748].....command header read, posting read for data: 94 bytes
[01:1748].....\SMPDU_Sock_post_read
[01:1748]......\SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_readv
[01:1748]...../SMPDU_Sock_post_read
[01:1748]..../smpd_state_reading_cmd_header
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_READ event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_read
[01:1748]....\smpd_state_reading_cmd
[01:1748].....read command
[01:1748].....\smpd_parse_command
[01:1748]...../smpd_parse_command
[01:1748].....read command: "cmd=dbget src=2 dest=1 tag=3 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=SHMHOST "
[01:1748].....\smpd_handle_command
[01:1748]......handling command:
[01:1748]...... src  = 2
[01:1748]...... dest = 1
[01:1748]...... cmd  = dbget
[01:1748]...... tag  = 3
[01:1748]...... ctx  = left
[01:1748]...... len  = 94
[01:1748]...... str  = cmd=dbget src=2 dest=1 tag=3 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=SHMHOST 
[01:1748]......\smpd_command_destination
[01:1748].......1 -> 1 : returning NULL context
[01:1748]....../smpd_command_destination
[01:1748]......\smpd_handle_dbs_command
[01:1748].......\smpd_create_command
[01:1748]........\smpd_init_command
[01:1748]......../smpd_init_command
[01:1748]......./smpd_create_command
[01:1748].......\smpd_add_command_int_arg
[01:1748]......./smpd_add_command_int_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......\get_name_key_value
[01:1748]......./get_name_key_value
[01:1748].......\smpd_dbs_get
[01:1748]......./smpd_dbs_get
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......sending reply to dbs command 'cmd=dbget src=2 dest=1 tag=3 ctx_key=0 name=BEE5F80A-5135-4744-BB8B-1BD3C7DF6D31 key=SHMHOST '.
[01:1748].......\smpd_add_command_arg
[01:1748]......./smpd_add_command_arg
[01:1748].......sending result command to left context: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS "
[01:1748].......\smpd_post_write_command
[01:1748]........\smpd_package_command
[01:1748]......../smpd_package_command
[01:1748]........\SMPDU_Sock_get_sock_id
[01:1748]......../SMPDU_Sock_get_sock_id
[01:1748]........smpd_post_write_command on the left context sock 968: 120 bytes for command: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS "
[01:1748]........\SMPDU_Sock_post_writev
[01:1748]......../SMPDU_Sock_post_writev
[01:1748]......./smpd_post_write_command
[01:1748]....../smpd_handle_dbs_command
[01:1748]...../smpd_handle_command
[01:1748].....\smpd_post_read_command
[01:1748]......\SMPDU_Sock_get_sock_id
[01:1748]....../SMPDU_Sock_get_sock_id
[01:1748]......posting a read for a command header on the left context, sock 968
[01:1748]......\SMPDU_Sock_post_read
[01:1748].......\SMPDU_Sock_post_readv
[01:1748]......./SMPDU_Sock_post_readv
[01:1748]....../SMPDU_Sock_post_read
[01:1748]...../smpd_post_read_command
[01:1748]..../smpd_state_reading_cmd
[01:1748].../smpd_handle_op_read
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait
[01:1748].../SMPDU_Sock_wait
[01:1748]...SOCK_OP_WRITE event.error = 0, result = 0, context=left
[01:1748]...\smpd_handle_op_write
[01:1748]....\smpd_state_writing_cmd
[01:1748].....wrote command
[01:1748].....command written to left: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS "
[01:1748].....\smpd_free_command
[01:1748]......\smpd_init_command
[01:1748]....../smpd_init_command
[01:1748]...../smpd_free_command
[01:1748]..../smpd_state_writing_cmd
[01:1748].../smpd_handle_op_write
[01:1748]...sock_waiting for the next event.
[01:1748]...\SMPDU_Sock_wait