[-1:1360]...\smpd_get_opt_int
[-1:1360].../smpd_get_opt_int
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt_int
[-1:1360].../smpd_get_opt_int
[-1:1360]...\smpd_get_opt_int
[-1:1360].../smpd_get_opt_int
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt_string
[-1:1360].../smpd_get_opt_string
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt_string
[-1:1360].../smpd_get_opt_string
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt_string
[-1:1360].../smpd_get_opt_string
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt_string
[-1:1360].../smpd_get_opt_string
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt_string
[-1:1360].../smpd_get_opt_string
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt_string
[-1:1360].../smpd_get_opt_string
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]...\smpd_get_opt_string
[-1:1360].../smpd_get_opt_string
[-1:1360]...\smpd_get_opt_string
[-1:1360].../smpd_get_opt_string
[-1:1360]...\smpd_get_opt
[-1:1360].../smpd_get_opt
[-1:1360]../smpd_parse_command_args
[-1:1360]..\smpd_get_smpd_data
[-1:1360]...\smpd_get_smpd_data_from_environment
[-1:1360].../smpd_get_smpd_data_from_environment
[-1:1360]...\smpd_get_smpd_data_default
[-1:1360].../smpd_get_smpd_data_default
[-1:1360]../smpd_get_smpd_data
[-1:1360]..\smpd_entry_point
[00:1360]...\smpd_set_smpd_data
[00:1360].../smpd_set_smpd_data
[00:1360]...\SMPDU_Sock_create_set
[00:1360]....\SMPDU_Sock_get_sock_set_id
[00:1360]..../SMPDU_Sock_get_sock_set_id
[00:1360]....created a set for the listener: 504
[00:1360]....\SMPDU_Sock_listen
[00:1360]..../SMPDU_Sock_listen
[00:1360]....smpd listening on port 8676
[00:1360]....\smpd_create_context
[00:1360].....\smpd_init_context
[00:1360]......\smpd_init_command
[00:1360]....../smpd_init_command
[00:1360]......\SMPDU_Sock_set_user_ptr
[00:1360]....../SMPDU_Sock_set_user_ptr
[00:1360]...../smpd_init_context
[00:1360]..../smpd_create_context
[00:1360]....\SMPDU_Sock_set_user_ptr
[00:1360]..../SMPDU_Sock_set_user_ptr
[00:1360]....\smpd_option_on
[00:1360].....\smpd_get_smpd_data
[00:1360]......\smpd_get_smpd_data_from_environment
[00:1360]....../smpd_get_smpd_data_from_environment
[00:1360]......\smpd_get_smpd_data_default
[00:1360]....../smpd_get_smpd_data_default
[00:1360]......Unable to get the data for the key 'no_dynamic_hosts'
[00:1360]...../smpd_get_smpd_data
[00:1360]..../smpd_option_on
[00:1360]....\smpd_insert_into_dynamic_hosts
[00:1360]..../smpd_insert_into_dynamic_hosts
[00:1360]....\smpd_enter_at_state
[00:1360].....sock_waiting for the next event.
[00:1360].....\SMPDU_Sock_wait
[00:1360]...../SMPDU_Sock_wait
[00:1360].....SOCK_OP_ACCEPT event.error = 0, result = 0, context=listener
[00:1360].....\smpd_handle_op_accept
[00:1360]......\smpd_state_smpd_listening
[00:1360].......\SMPDU_Sock_accept
[00:1360]......./SMPDU_Sock_accept
[00:1360].......authenticating new connection
[00:1360].......\smpd_create_context
[00:1360]........\smpd_init_context
[00:1360].........\smpd_init_command
[00:1360]........./smpd_init_command
[00:1360].........\SMPDU_Sock_set_user_ptr
[00:1360]........./SMPDU_Sock_set_user_ptr
[00:1360]......../smpd_init_context
[00:1360]......./smpd_create_context
[00:1360].......\SMPDU_Sock_set_user_ptr
[00:1360]......./SMPDU_Sock_set_user_ptr
[00:1360].......\smpd_gen_authentication_strings
[00:1360]........\smpd_hash
[00:1360]......../smpd_hash
[00:1360]......./smpd_gen_authentication_strings
[00:1360].......posting a write of the challenge string: 1.4.1p1 4479
[00:1360].......\SMPDU_Sock_post_write
[00:1360]........\SMPDU_Sock_post_writev
[00:1360]......../SMPDU_Sock_post_writev
[00:1360]......./SMPDU_Sock_post_write
[00:1360]....../smpd_state_smpd_listening
[00:1360]...../smpd_handle_op_accept
[00:1360].....sock_waiting for the next event.
[00:1360].....\SMPDU_Sock_wait
[00:1360]...../SMPDU_Sock_wait
[00:1360].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:1360].....\smpd_handle_op_write
[00:1360]......\smpd_state_writing_challenge_string
[00:1360].......wrote challenge string: '1.4.1p1 4479'
[00:1360].......\SMPDU_Sock_post_read
[00:1360]........\SMPDU_Sock_post_readv
[00:1360]......../SMPDU_Sock_post_readv
[00:1360]......./SMPDU_Sock_post_read
[00:1360]....../smpd_state_writing_challenge_string
[00:1360]...../smpd_handle_op_write
[00:1360].....sock_waiting for the next event.
[00:1360].....\SMPDU_Sock_wait
[00:1360]...../SMPDU_Sock_wait
[00:1360].....SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[00:1360].....\smpd_handle_op_read
[00:1360]......\smpd_state_reading_challenge_response
[00:1360].......read challenge response: '38f1359a77fb2685480916aadb3ad993'
[00:1360].......\SMPDU_Sock_post_write
[00:1360]........\SMPDU_Sock_post_writev
[00:1360]......../SMPDU_Sock_post_writev
[00:1360]......./SMPDU_Sock_post_write
[00:1360]....../smpd_state_reading_challenge_response
[00:1360]...../smpd_handle_op_read
[00:1360].....sock_waiting for the next event.
[00:1360].....\SMPDU_Sock_wait
[00:1360]...../SMPDU_Sock_wait
[00:1360].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:1360].....\smpd_handle_op_write
[00:1360]......\smpd_state_writing_connect_result
[00:1360].......wrote connect result: 'SUCCESS'
[00:1360].......\SMPDU_Sock_post_read
[00:1360]........\SMPDU_Sock_post_readv
[00:1360]......../SMPDU_Sock_post_readv
[00:1360]......./SMPDU_Sock_post_read
[00:1360]....../smpd_state_writing_connect_result
[00:1360]...../smpd_handle_op_write
[00:1360].....sock_waiting for the next event.
[00:1360].....\SMPDU_Sock_wait
[00:1360]...../SMPDU_Sock_wait
[00:1360].....SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[00:1360].....\smpd_handle_op_read
[00:1360]......\smpd_state_reading_session_request
[00:1360].......read session request: 'process'
[00:1360].......\SMPDU_Sock_post_write
[00:1360]........\SMPDU_Sock_post_writev
[00:1360]......../SMPDU_Sock_post_writev
[00:1360]......./SMPDU_Sock_post_write
[00:1360]....../smpd_state_reading_session_request
[00:1360]...../smpd_handle_op_read
[00:1360].....sock_waiting for the next event.
[00:1360].....\SMPDU_Sock_wait
[00:1360]...../SMPDU_Sock_wait
[00:1360].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:1360].....\smpd_handle_op_write
[00:1360]......\smpd_state_writing_no_cred_request
[00:1360].......wrote no cred request: 'nocredentials'
[00:1360].......\smpd_start_win_mgr
[00:1360]........starting command: "C:\Program Files\MPICH2\bin\smpd.exe" -d 27 -mgr -read 00000000000002C0 -write 00000000000002BC
[00:1360]........CreateProcess
[00:1360]........smpd reading the port string from the manager
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt_int
[-1:2132].../smpd_get_opt_int
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt_int
[-1:2132].../smpd_get_opt_int
[-1:2132]...\smpd_get_opt_int
[-1:2132].../smpd_get_opt_int
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt
[-1:2132].../smpd_get_opt
[-1:2132]...\smpd_get_opt_string
[-1:2132].../smpd_get_opt_string
[-1:2132]...\smpd_get_opt_string
[-1:2132].../smpd_get_opt_string
[-1:2132]...manager creating listener and session sets.
[-1:2132]...\SMPDU_Sock_create_set
[-1:2132]....\SMPDU_Sock_get_sock_set_id
[-1:2132]..../SMPDU_Sock_get_sock_set_id
[-1:2132]....created set for manager listener, 768
[-1:2132]....\SMPDU_Sock_listen
[-1:2132]..../SMPDU_Sock_listen
[-1:2132]....smpd manager listening on port 54295
[-1:2132]....\smpd_create_context
[-1:2132].....\smpd_init_context
[-1:2132]......\smpd_init_command
[-1:2132]....../smpd_init_command
[-1:2132]......\SMPDU_Sock_set_user_ptr
[-1:2132]....../SMPDU_Sock_set_user_ptr
[-1:2132]...../smpd_init_context
[-1:2132]..../smpd_create_context
[-1:2132]....\SMPDU_Sock_set_user_ptr
[-1:2132]..../SMPDU_Sock_set_user_ptr
[-1:2132]....manager writing port back to smpd.
[00:1360]........smpd sending the account to the manager
[00:1360]........\smpd_hide_string_arg
[00:1360].........\first_token
[00:1360]........./first_token
[00:1360].........\compare_token
[00:1360]........./compare_token
[00:1360].........\next_token
[00:1360]..........\first_token
[00:1360]........../first_token
[00:1360]..........\first_token
[00:1360]........../first_token
[00:1360]........./next_token
[00:1360]......../smpd_hide_string_arg
[00:1360]......../smpd_hide_string_arg
[00:1360]........\smpd_hide_string_arg
[00:1360].........\first_token
[00:1360]........./first_token
[00:1360].........\compare_token
[00:1360]........./compare_token
[00:1360].........\next_token
[00:1360]..........\first_token
[00:1360]........../first_token
[00:1360]..........\first_token
[00:1360]........../first_token
[00:1360]........./next_token
[00:1360].........\compare_token
[00:1360]........./compare_token
[00:1360].........\compare_token
[00:1360]........./compare_token
[00:1360].........\next_token
[00:1360]..........\first_token
[00:1360]........../first_token
[00:1360]..........\first_token
[00:1360]........../first_token
[00:1360]........./next_token
[00:1360].........\compare_token
[00:1360]........./compare_token
[00:1360].........\next_token
[00:1360]..........\first_token
[00:1360]........../first_token
[00:1360]..........\first_token
[00:1360]........../first_token
[00:1360]........./next_token
[00:1360].........\compare_token
[00:1360]........./compare_token
[00:1360].........\next_token
[00:1360]..........\first_token
[00:1360]........../first_token
[00:1360]..........\first_token
[00:1360]........../first_token
[00:1360]........./next_token
[00:1360]......../smpd_hide_string_arg
[00:1360]......../smpd_hide_string_arg
[00:1360]........closing the pipe to the manager
[00:1360]........smpd writing reconnect request: port 54295
[00:1360]........\SMPDU_Sock_post_write
[00:1360].........\SMPDU_Sock_post_writev
[00:1360]........./SMPDU_Sock_post_writev
[00:1360]......../SMPDU_Sock_post_write
[00:1360]......./smpd_state_writing_no_cred_request
[00:1360]....../smpd_handle_op_write
[00:1360]......sock_waiting for the next event.
[00:1360]......\SMPDU_Sock_wait
[00:1360]....../SMPDU_Sock_wait
[00:1360]......SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:1360]......\smpd_handle_op_write
[00:1360].......\smpd_state_writing_reconnect_request
[00:1360]........wrote reconnect request: '54295'
[00:1360]........\SMPDU_Sock_post_close
[00:1360].........\SMPDU_Sock_post_read
[00:1360]..........\SMPDU_Sock_post_readv
[00:1360]........../SMPDU_Sock_post_readv
[00:1360]........./SMPDU_Sock_post_read
[00:1360]......../SMPDU_Sock_post_close
[00:1360]......./smpd_state_writing_reconnect_request
[00:1360]....../smpd_handle_op_write
[00:1360]......sock_waiting for the next event.
[00:1360]......\SMPDU_Sock_wait
[-1:2132]....\smpd_hide_string_arg
[-1:2132].....\first_token
[-1:2132]...../first_token
[-1:2132].....\compare_token
[-1:2132]...../compare_token
[-1:2132].....\next_token
[-1:2132]......\first_token
[-1:2132]....../first_token
[-1:2132]......\first_token
[-1:2132]....../first_token
[-1:2132]...../next_token
[-1:2132].....\compare_token
[-1:2132]...../compare_token
[-1:2132].....\compare_token
[-1:2132]...../compare_token
[-1:2132].....\next_token
[-1:2132]......\first_token
[-1:2132]....../first_token
[-1:2132]......\first_token
[-1:2132]....../first_token
[-1:2132]...../next_token
[-1:2132].....\compare_token
[-1:2132]...../compare_token
[-1:2132].....\next_token
[-1:2132]......\first_token
[-1:2132]....../first_token
[-1:2132]......\first_token
[-1:2132]....../first_token
[-1:2132]...../next_token
[-1:2132]..../smpd_hide_string_arg
[-1:2132]..../smpd_hide_string_arg
[00:1360]....../SMPDU_Sock_wait
[00:1360]......SOCK_OP_CLOSE event.error = 0, result = 0, context=undetermined
[00:1360]......\smpd_handle_op_close
[00:1360].......\smpd_get_state_string
[00:1360]......./smpd_get_state_string
[00:1360].......op_close received - SMPD_CLOSING state.
[00:1360].......Unaffiliated undetermined context closing.
[00:1360].......\smpd_free_context
[00:1360]........freeing undetermined context.
[00:1360]........\smpd_init_context
[00:1360].........\smpd_init_command
[00:1360]........./smpd_init_command
[00:1360]......../smpd_init_context
[00:1360]......./smpd_free_context
[00:1360]....../smpd_handle_op_close
[00:1360]......sock_waiting for the next event.
[00:1360]......\SMPDU_Sock_wait
[-1:2132]....\smpd_enter_at_state
[-1:2132].....sock_waiting for the next event.
[-1:2132].....\SMPDU_Sock_wait
[-1:2132]...../SMPDU_Sock_wait
[-1:2132].....SOCK_OP_ACCEPT event.error = 0, result = 0, context=listener
[-1:2132].....\smpd_handle_op_accept
[-1:2132]......\smpd_state_mgr_listening
[-1:2132].......\SMPDU_Sock_accept
[-1:2132]......./SMPDU_Sock_accept
[-1:2132].......accepted re-connection
[-1:2132].......\smpd_create_context
[-1:2132]........\smpd_init_context
[-1:2132].........\smpd_init_command
[-1:2132]........./smpd_init_command
[-1:2132].........\SMPDU_Sock_set_user_ptr
[-1:2132]........./SMPDU_Sock_set_user_ptr
[-1:2132]......../smpd_init_context
[-1:2132]......./smpd_create_context
[-1:2132].......\SMPDU_Sock_set_user_ptr
[-1:2132]......./SMPDU_Sock_set_user_ptr
[-1:2132].......\SMPDU_Sock_post_read
[-1:2132]........\SMPDU_Sock_post_readv
[-1:2132]......../SMPDU_Sock_post_readv
[-1:2132]......./SMPDU_Sock_post_read
[-1:2132].......closing the mgr listener.
[-1:2132].......\SMPDU_Sock_post_close
[-1:2132]......./SMPDU_Sock_post_close
[-1:2132]....../smpd_state_mgr_listening
[-1:2132]...../smpd_handle_op_accept
[-1:2132].....sock_waiting for the next event.
[-1:2132].....\SMPDU_Sock_wait
[-1:2132]...../SMPDU_Sock_wait
[-1:2132].....SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[-1:2132].....\smpd_handle_op_read
[-1:2132]......\smpd_state_reading_session_header
[-1:2132].......read session header: 'id=2 parent=1 level=1'
[-1:2132].......\smpd_interpret_session_header
[-1:2132]........interpreting session header: "id=2 parent=1 level=1"
[-1:2132]........ id = 2
[02:2132]........ parent = 1
[02:2132]........ level = 1
[02:2132]......./smpd_interpret_session_header
[02:2132].......\smpd_post_read_command
[02:2132]........\SMPDU_Sock_get_sock_id
[02:2132]......../SMPDU_Sock_get_sock_id
[02:2132]........posting a read for a command header on the parent context, sock 908
[02:2132]........\SMPDU_Sock_post_read
[02:2132].........\SMPDU_Sock_post_readv
[02:2132]........./SMPDU_Sock_post_readv
[02:2132]......../SMPDU_Sock_post_read
[02:2132]......./smpd_post_read_command
[02:2132]....../smpd_state_reading_session_header
[02:2132]...../smpd_handle_op_read
[02:2132].....sock_waiting for the next event.
[02:2132].....\SMPDU_Sock_wait
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]...../SMPDU_Sock_wait
[02:2132].....SOCK_OP_CLOSE event.error = 0, result = 0, context=listener
[02:2132].....\smpd_handle_op_close
[02:2132]......\smpd_get_state_string
[02:2132]....../smpd_get_state_string
[02:2132]......op_close received - SMPD_MGR_LISTENING state.
[02:2132]......\smpd_free_context
[02:2132].......freeing listener context.
[02:2132].......\smpd_init_context
[02:2132]........\smpd_init_command
[02:2132]......../smpd_init_command
[02:2132]......./smpd_init_context
[02:2132]....../smpd_free_context
[02:2132]...../smpd_handle_op_close
[02:2132].....sock_waiting for the next event.
[02:2132].....\SMPDU_Sock_wait
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]......GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]...../SMPDU_Sock_wait
[02:2132].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132].....\smpd_handle_op_read
[02:2132]......\smpd_state_reading_cmd_header
[02:2132].......read command header
[02:2132].......command header read, posting read for data: 241 bytes
[02:2132].......\SMPDU_Sock_post_read
[02:2132]........\SMPDU_Sock_post_readv
[02:2132]......../SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_read
[02:2132]....../smpd_state_reading_cmd_header
[02:2132]...../smpd_handle_op_read
[02:2132].....sock_waiting for the next event.
[02:2132].....\SMPDU_Sock_wait
[02:2132]...../SMPDU_Sock_wait
[02:2132].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132].....\smpd_handle_op_read
[02:2132]......\smpd_state_reading_cmd
[02:2132].......read command
[02:2132].......\smpd_parse_command
[02:2132]......./smpd_parse_command
[02:2132].......read command: "cmd=launch src=0 dest=2 tag=2 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=nemesis" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=1 i=1 n=2 k=F1703AE4-8AD8-436d-A69A-3C4915C46230 kd=4F32A33E-1C37-419b-ADFF-84C063ABF362 "
[02:2132].......\smpd_handle_command
[02:2132]........handling command:
[02:2132]........ src  = 0
[02:2132]........ dest = 2
[02:2132]........ cmd  = launch
[02:2132]........ tag  = 2
[02:2132]........ ctx  = parent
[02:2132]........ len  = 241
[02:2132]........ str  = cmd=launch src=0 dest=2 tag=2 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=nemesis" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=1 i=1 n=2 k=F1703AE4-8AD8-436d-A69A-3C4915C46230 kd=4F32A33E-1C37-419b-ADFF-84C063ABF362 
[02:2132]........\smpd_command_destination
[02:2132].........2 -> 2 : returning NULL context
[02:2132]......../smpd_command_destination
[02:2132]........\smpd_handle_launch_command
[02:2132].........\smpd_create_process_struct
[02:2132]..........\smpd_create_context
[02:2132]...........\smpd_init_context
[02:2132]............\smpd_init_command
[02:2132]............/smpd_init_command
[02:2132].........../smpd_init_context
[02:2132]........../smpd_create_context
[02:2132]..........\smpd_create_context
[02:2132]...........\smpd_init_context
[02:2132]............\smpd_init_command
[02:2132]............/smpd_init_command
[02:2132].........../smpd_init_context
[02:2132]........../smpd_create_context
[02:2132]..........\smpd_create_context
[02:2132]...........\smpd_init_context
[02:2132]............\smpd_init_command
[02:2132]............/smpd_init_command
[02:2132].........../smpd_init_context
[02:2132]........../smpd_create_context
[02:2132]..........\smpd_create_context
[02:2132]...........\smpd_init_context
[02:2132]............\smpd_init_command
[02:2132]............/smpd_init_command
[02:2132].........../smpd_init_context
[02:2132]........../smpd_create_context
[02:2132]........./smpd_create_process_struct
[02:2132].........launching: 'cpi.exe'
[02:2132].........\smpd_launch_process
[02:2132]..........searching for 'cpi.exe' in 'C:\Program Files\MPICH2\examples'
[02:2132]..........\smpd_search_path
[02:2132]........../smpd_search_path
[02:2132]..........\smpd_priority_class_to_win_class
[02:2132]........../smpd_priority_class_to_win_class
[02:2132]..........\smpd_priority_to_win_priority
[02:2132]........../smpd_priority_to_win_priority
[02:2132]..........\smpd_make_socket_loop_choose
[02:2132]...........\smpd_get_hostname
[02:2132].........../smpd_get_hostname
[02:2132]........../smpd_make_socket_loop_choose
[02:2132]..........\smpd_make_socket_loop_choose
[02:2132]...........\smpd_get_hostname
[02:2132].........../smpd_get_hostname
[02:2132]........../smpd_make_socket_loop_choose
[02:2132]..........\smpd_make_socket_loop_choose
[02:2132]...........\smpd_get_hostname
[02:2132].........../smpd_get_hostname
[02:2132]........../smpd_make_socket_loop_choose
[02:2132]..........\SMPDU_Sock_listen
[02:2132]........../SMPDU_Sock_listen
[02:2132]..........pmi listening on port 54302
[02:2132]..........\smpd_create_context
[02:2132]...........\smpd_init_context
[02:2132]............\smpd_init_command
[02:2132]............/smpd_init_command
[02:2132]............\SMPDU_Sock_set_user_ptr
[02:2132]............/SMPDU_Sock_set_user_ptr
[02:2132].........../smpd_init_context
[02:2132]........../smpd_create_context
[02:2132]..........\SMPDU_Sock_set_user_ptr
[02:2132]........../SMPDU_Sock_set_user_ptr
[02:2132]........./SMPDU_Sock_get_host_description
[02:2132].........\SMPDU_Sock_hostname_to_host_description
[02:2132]..........adding host: EC2AMAZ-BULC3HN to host list
[02:2132]..........adding host: EC2AMAZ-BULC3HN.compute-1.internal to host list
[02:2132]..........adding host: ::1 to host list
[02:2132]..........adding host: 10.97.51.118 to host list
[02:2132]........./SMPDU_Sock_hostname_to_host_description
[02:2132]......../SMPDU_Sock_get_host_description
[02:2132]........\SetEnvironmentVariables
[02:2132].........setting environment variable: <MPICH2_CHANNEL> = <nemesis>
[02:2132]......../SetEnvironmentVariables
[02:2132]........env: PMI_RANK=1
[02:2132]........env: PMI_SIZE=2
[02:2132]........env: PMI_KVS=F1703AE4-8AD8-436d-A69A-3C4915C46230
[02:2132]........env: PMI_DOMAIN=4F32A33E-1C37-419b-ADFF-84C063ABF362
[02:2132]........env: PMI_HOST=EC2AMAZ-BULC3HN EC2AMAZ-BULC3HN.compute-1.internal ::1 10.97.51.118 
[02:2132]........env: PMI_PORT=54302
[02:2132]........env: PMI_SMPD_ID=2
[02:2132]........env: PMI_SMPD_KEY=0
[02:2132]........env: PMI_CLIQUE=1
[02:2132]........env: PMI_SPAWN=0
[02:2132]........env: PMI_APPNUM=0
[02:2132]........CreateProcess(C:\Program Files\MPICH2\examples\cpi.exe)
[02:2132]........\RemoveEnvironmentVariables
[02:2132]......../RemoveEnvironmentVariables
[02:2132]........\SMPDU_Sock_native_to_sock
[02:2132]......../SMPDU_Sock_native_to_sock
[02:2132]........\SMPDU_Sock_native_to_sock
[02:2132]......../SMPDU_Sock_native_to_sock
[02:2132]........\SMPDU_Sock_native_to_sock
[02:2132]......../SMPDU_Sock_native_to_sock
[02:2132]........\SMPDU_Sock_set_user_ptr
[02:2132]......../SMPDU_Sock_set_user_ptr
[02:2132]........\SMPDU_Sock_set_user_ptr
[02:2132]......../SMPDU_Sock_set_user_ptr
[02:2132]........\SMPDU_Sock_set_user_ptr
[02:2132]......../SMPDU_Sock_set_user_ptr
[02:2132]........\SMPDU_Sock_post_read
[02:2132].........\SMPDU_Sock_post_readv
[02:2132]........./SMPDU_Sock_post_readv
[02:2132]......../SMPDU_Sock_post_read
[02:2132]........\SMPDU_Sock_post_read
[02:2132].........\SMPDU_Sock_post_readv
[02:2132]........./SMPDU_Sock_post_readv
[02:2132]......../SMPDU_Sock_post_read
[02:2132]........\smpd_process_to_registry
[02:2132]......../smpd_process_to_registry
[02:2132]......./smpd_launch_process
[02:2132].......\smpd_create_command
[02:2132]........\smpd_init_command
[02:2132]......../smpd_init_command
[02:2132]......./smpd_create_command
[02:2132].......\smpd_add_command_int_arg
[02:2132]......./smpd_add_command_int_arg
[02:2132].......\smpd_add_command_arg
[02:2132]......./smpd_add_command_arg
[02:2132].......\smpd_add_command_arg
[02:2132]......./smpd_add_command_arg
[02:2132].......\smpd_add_command_arg
[02:2132]......./smpd_add_command_arg
[02:2132].......\smpd_add_command_int_arg
[02:2132]......./smpd_add_command_int_arg
[02:2132].......\smpd_add_command_int_arg
[02:2132]......./smpd_add_command_int_arg
[02:2132].......\smpd_post_write_command
[02:2132]........\smpd_package_command
[02:2132]......../smpd_package_command
[02:2132]........\SMPDU_Sock_get_sock_id
[02:2132]......../SMPDU_Sock_get_sock_id
[02:2132]........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=F1703AE4-8AD8-436d-A69A-3C4915C46230 pg_rank=1 pg_ctx=0 "
[02:2132]........\SMPDU_Sock_post_writev
[02:2132]......../SMPDU_Sock_post_writev
[02:2132]......./smpd_post_write_command
[02:2132]....../smpd_handle_launch_command
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the parent context, sock 908
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to parent: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=F1703AE4-8AD8-436d-A69A-3C4915C46230 pg_rank=1 pg_ctx=0 "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132]....*** entering smpd_pinthread pid:2124 sock:924 ***
[02:2132]....*** entering smpd_piothread pid:2124 sock:928 ***
[02:2132]....*** entering smpd_piothread pid:2124 sock:936 ***
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_ACCEPT event.error = 0, result = 0, context=PMI_LISTENER
[02:2132]...\smpd_handle_op_accept
[02:2132]....\smpd_state_pmi_listening
[02:2132].....\SMPDU_Sock_accept
[02:2132]...../SMPDU_Sock_accept
[02:2132].....\SMPDU_Sock_post_close
[02:2132]...../SMPDU_Sock_post_close
[02:2132].....\SMPDU_Sock_set_user_ptr
[02:2132]...../SMPDU_Sock_set_user_ptr
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the pmi context, sock 1080
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_pmi_listening
[02:2132].../smpd_handle_op_accept
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_CLOSE event.error = 0, result = 0, context=PMI_LISTENER
[02:2132]...\smpd_handle_op_close
[02:2132]....\smpd_get_state_string
[02:2132]..../smpd_get_state_string
[02:2132]....op_close received - SMPD_CLOSING state.
[02:2132]....Unaffiliated PMI_LISTENER context closing.
[02:2132]....\smpd_free_context
[02:2132].....freeing PMI_LISTENER context.
[02:2132].....\smpd_init_context
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_init_context
[02:2132]..../smpd_free_context
[02:2132].../smpd_handle_op_close
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132]....GetQueuedCompletionStatus failed, GetLastError: 995
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 105 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=1 value=2 node_id=2 "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 2
[02:2132]...... dest = 0
[02:2132]...... cmd  = init
[02:2132]...... tag  = 0
[02:2132]...... ctx  = pmi
[02:2132]...... len  = 105
[02:2132]...... str  = cmd=init src=2 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=1 value=2 node_id=2 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 0 : returning parent_context: 0 < 2
[02:2132]....../smpd_command_destination
[02:2132]......forwarding command to 1
[02:2132]......\smpd_forward_command
[02:2132].......\smpd_create_command_copy
[02:2132]......./smpd_create_command_copy
[02:2132].......posting write of forwarded command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=1 value=2 node_id=2 "
[02:2132].......\smpd_post_write_command
[02:2132]........\smpd_package_command
[02:2132]......../smpd_package_command
[02:2132]........\SMPDU_Sock_get_sock_id
[02:2132]......../SMPDU_Sock_get_sock_id
[02:2132]........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=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=1 value=2 node_id=2 "
[02:2132]........\SMPDU_Sock_post_writev
[02:2132]......../SMPDU_Sock_post_writev
[02:2132]......./smpd_post_write_command
[02:2132]....../smpd_forward_command
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the pmi context, sock 1080
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to parent: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=1 value=2 node_id=2 "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 66 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 0
[02:2132]...... dest = 2
[02:2132]...... cmd  = result
[02:2132]...... tag  = 5
[02:2132]...... ctx  = parent
[02:2132]...... len  = 66
[02:2132]...... str  = cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 2 : returning NULL context
[02:2132]....../smpd_command_destination
[02:2132]......\smpd_handle_result
[02:2132].......forwarding the dbs result command to the pmi context 0.
[02:2132].......\smpd_forward_command
[02:2132]........\smpd_create_command_copy
[02:2132]......../smpd_create_command_copy
[02:2132]........posting write of forwarded command: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS "
[02:2132]........\smpd_post_write_command
[02:2132].........\smpd_package_command
[02:2132]........./smpd_package_command
[02:2132].........\SMPDU_Sock_get_sock_id
[02:2132]........./SMPDU_Sock_get_sock_id
[02:2132].........smpd_post_write_command on the pmi context sock 1080: 79 bytes for command: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS "
[02:2132].........\SMPDU_Sock_post_writev
[02:2132]........./SMPDU_Sock_post_writev
[02:2132]......../smpd_post_write_command
[02:2132]......./smpd_forward_command
[02:2132]....../smpd_handle_result
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the parent context, sock 908
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to pmi: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 106 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=dbget src=2 dest=1 tag=1 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=PMI_process_mapping "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 2
[02:2132]...... dest = 1
[02:2132]...... cmd  = dbget
[02:2132]...... tag  = 1
[02:2132]...... ctx  = pmi
[02:2132]...... len  = 106
[02:2132]...... str  = cmd=dbget src=2 dest=1 tag=1 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=PMI_process_mapping 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 1 : returning parent_context: 1 < 2
[02:2132]....../smpd_command_destination
[02:2132]......forwarding command to 1
[02:2132]......\smpd_forward_command
[02:2132].......\smpd_create_command_copy
[02:2132]......./smpd_create_command_copy
[02:2132].......posting write of forwarded command: "cmd=dbget src=2 dest=1 tag=1 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=PMI_process_mapping "
[02:2132].......\smpd_post_write_command
[02:2132]........\smpd_package_command
[02:2132]......../smpd_package_command
[02:2132]........\SMPDU_Sock_get_sock_id
[02:2132]......../SMPDU_Sock_get_sock_id
[02:2132]........smpd_post_write_command on the parent context sock 908: 119 bytes for command: "cmd=dbget src=2 dest=1 tag=1 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=PMI_process_mapping "
[02:2132]........\SMPDU_Sock_post_writev
[02:2132]......../SMPDU_Sock_post_writev
[02:2132]......./smpd_post_write_command
[02:2132]....../smpd_forward_command
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the pmi context, sock 1080
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to parent: "cmd=dbget src=2 dest=1 tag=1 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=PMI_process_mapping "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 82 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=result src=1 dest=2 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 1
[02:2132]...... dest = 2
[02:2132]...... cmd  = result
[02:2132]...... tag  = 5
[02:2132]...... ctx  = parent
[02:2132]...... len  = 82
[02:2132]...... str  = cmd=result src=1 dest=2 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 2 : returning NULL context
[02:2132]....../smpd_command_destination
[02:2132]......\smpd_handle_result
[02:2132].......forwarding the dbs result command to the pmi context 0.
[02:2132].......\smpd_forward_command
[02:2132]........\smpd_create_command_copy
[02:2132]......../smpd_create_command_copy
[02:2132]........posting write of forwarded command: "cmd=result src=1 dest=2 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[02:2132]........\smpd_post_write_command
[02:2132].........\smpd_package_command
[02:2132]........./smpd_package_command
[02:2132].........\SMPDU_Sock_get_sock_id
[02:2132]........./SMPDU_Sock_get_sock_id
[02:2132].........smpd_post_write_command on the pmi context sock 1080: 95 bytes for command: "cmd=result src=1 dest=2 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[02:2132].........\SMPDU_Sock_post_writev
[02:2132]........./SMPDU_Sock_post_writev
[02:2132]......../smpd_post_write_command
[02:2132]......./smpd_forward_command
[02:2132]....../smpd_handle_result
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the parent context, sock 908
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to pmi: "cmd=result src=1 dest=2 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 120 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=dbput src=2 dest=1 tag=2 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[1] value=EC2AMAZ-BULC3HN "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 2
[02:2132]...... dest = 1
[02:2132]...... cmd  = dbput
[02:2132]...... tag  = 2
[02:2132]...... ctx  = pmi
[02:2132]...... len  = 120
[02:2132]...... str  = cmd=dbput src=2 dest=1 tag=2 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[1] value=EC2AMAZ-BULC3HN 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 1 : returning parent_context: 1 < 2
[02:2132]....../smpd_command_destination
[02:2132]......forwarding command to 1
[02:2132]......\smpd_forward_command
[02:2132].......\smpd_create_command_copy
[02:2132]......./smpd_create_command_copy
[02:2132].......posting write of forwarded command: "cmd=dbput src=2 dest=1 tag=2 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[1] value=EC2AMAZ-BULC3HN "
[02:2132].......\smpd_post_write_command
[02:2132]........\smpd_package_command
[02:2132]......../smpd_package_command
[02:2132]........\SMPDU_Sock_get_sock_id
[02:2132]......../SMPDU_Sock_get_sock_id
[02:2132]........smpd_post_write_command on the parent context sock 908: 133 bytes for command: "cmd=dbput src=2 dest=1 tag=2 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[1] value=EC2AMAZ-BULC3HN "
[02:2132]........\SMPDU_Sock_post_writev
[02:2132]......../SMPDU_Sock_post_writev
[02:2132]......./smpd_post_write_command
[02:2132]....../smpd_forward_command
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the pmi context, sock 1080
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to parent: "cmd=dbput src=2 dest=1 tag=2 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[1] value=EC2AMAZ-BULC3HN "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 85 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=result src=1 dest=2 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 1
[02:2132]...... dest = 2
[02:2132]...... cmd  = result
[02:2132]...... tag  = 6
[02:2132]...... ctx  = parent
[02:2132]...... len  = 85
[02:2132]...... str  = cmd=result src=1 dest=2 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 2 : returning NULL context
[02:2132]....../smpd_command_destination
[02:2132]......\smpd_handle_result
[02:2132].......forwarding the dbs result command to the pmi context 0.
[02:2132].......\smpd_forward_command
[02:2132]........\smpd_create_command_copy
[02:2132]......../smpd_create_command_copy
[02:2132]........posting write of forwarded command: "cmd=result src=1 dest=2 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[02:2132]........\smpd_post_write_command
[02:2132].........\smpd_package_command
[02:2132]........./smpd_package_command
[02:2132].........\SMPDU_Sock_get_sock_id
[02:2132]........./SMPDU_Sock_get_sock_id
[02:2132].........smpd_post_write_command on the pmi context sock 1080: 98 bytes for command: "cmd=result src=1 dest=2 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[02:2132].........\SMPDU_Sock_post_writev
[02:2132]........./SMPDU_Sock_post_writev
[02:2132]......../smpd_post_write_command
[02:2132]......./smpd_forward_command
[02:2132]....../smpd_handle_result
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the parent context, sock 908
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to pmi: "cmd=result src=1 dest=2 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 92 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=barrier src=2 dest=1 tag=3 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 2
[02:2132]...... dest = 1
[02:2132]...... cmd  = barrier
[02:2132]...... tag  = 3
[02:2132]...... ctx  = pmi
[02:2132]...... len  = 92
[02:2132]...... str  = cmd=barrier src=2 dest=1 tag=3 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 1 : returning parent_context: 1 < 2
[02:2132]....../smpd_command_destination
[02:2132]......forwarding command to 1
[02:2132]......\smpd_forward_command
[02:2132].......\smpd_create_command_copy
[02:2132]......./smpd_create_command_copy
[02:2132].......posting write of forwarded command: "cmd=barrier src=2 dest=1 tag=3 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 "
[02:2132].......\smpd_post_write_command
[02:2132]........\smpd_package_command
[02:2132]......../smpd_package_command
[02:2132]........\SMPDU_Sock_get_sock_id
[02:2132]......../SMPDU_Sock_get_sock_id
[02:2132]........smpd_post_write_command on the parent context sock 908: 105 bytes for command: "cmd=barrier src=2 dest=1 tag=3 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 "
[02:2132]........\SMPDU_Sock_post_writev
[02:2132]......../SMPDU_Sock_post_writev
[02:2132]......./smpd_post_write_command
[02:2132]....../smpd_forward_command
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the pmi context, sock 1080
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to parent: "cmd=barrier src=2 dest=1 tag=3 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 87 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 1
[02:2132]...... dest = 2
[02:2132]...... cmd  = result
[02:2132]...... tag  = 8
[02:2132]...... ctx  = parent
[02:2132]...... len  = 87
[02:2132]...... str  = cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 2 : returning NULL context
[02:2132]....../smpd_command_destination
[02:2132]......\smpd_handle_result
[02:2132].......forwarding the dbs result command to the pmi context 0.
[02:2132].......\smpd_forward_command
[02:2132]........\smpd_create_command_copy
[02:2132]......../smpd_create_command_copy
[02:2132]........posting write of forwarded command: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:2132]........\smpd_post_write_command
[02:2132].........\smpd_package_command
[02:2132]........./smpd_package_command
[02:2132].........\SMPDU_Sock_get_sock_id
[02:2132]........./SMPDU_Sock_get_sock_id
[02:2132].........smpd_post_write_command on the pmi context sock 1080: 100 bytes for command: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:2132].........\SMPDU_Sock_post_writev
[02:2132]........./SMPDU_Sock_post_writev
[02:2132]......../smpd_post_write_command
[02:2132]......./smpd_forward_command
[02:2132]....../smpd_handle_result
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the parent context, sock 908
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to pmi: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 98 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=dbget src=2 dest=1 tag=4 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[0] "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 2
[02:2132]...... dest = 1
[02:2132]...... cmd  = dbget
[02:2132]...... tag  = 4
[02:2132]...... ctx  = pmi
[02:2132]...... len  = 98
[02:2132]...... str  = cmd=dbget src=2 dest=1 tag=4 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[0] 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 1 : returning parent_context: 1 < 2
[02:2132]....../smpd_command_destination
[02:2132]......forwarding command to 1
[02:2132]......\smpd_forward_command
[02:2132].......\smpd_create_command_copy
[02:2132]......./smpd_create_command_copy
[02:2132].......posting write of forwarded command: "cmd=dbget src=2 dest=1 tag=4 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[0] "
[02:2132].......\smpd_post_write_command
[02:2132]........\smpd_package_command
[02:2132]......../smpd_package_command
[02:2132]........\SMPDU_Sock_get_sock_id
[02:2132]......../SMPDU_Sock_get_sock_id
[02:2132]........smpd_post_write_command on the parent context sock 908: 111 bytes for command: "cmd=dbget src=2 dest=1 tag=4 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[0] "
[02:2132]........\SMPDU_Sock_post_writev
[02:2132]......../SMPDU_Sock_post_writev
[02:2132]......./smpd_post_write_command
[02:2132]....../smpd_forward_command
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the pmi context, sock 1080
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to parent: "cmd=dbget src=2 dest=1 tag=4 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[0] "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 108 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=result src=1 dest=2 tag=11 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 1
[02:2132]...... dest = 2
[02:2132]...... cmd  = result
[02:2132]...... tag  = 11
[02:2132]...... ctx  = parent
[02:2132]...... len  = 108
[02:2132]...... str  = cmd=result src=1 dest=2 tag=11 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 2 : returning NULL context
[02:2132]....../smpd_command_destination
[02:2132]......\smpd_handle_result
[02:2132].......forwarding the dbs result command to the pmi context 0.
[02:2132].......\smpd_forward_command
[02:2132]........\smpd_create_command_copy
[02:2132]......../smpd_create_command_copy
[02:2132]........posting write of forwarded command: "cmd=result src=1 dest=2 tag=11 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS "
[02:2132]........\smpd_post_write_command
[02:2132].........\smpd_package_command
[02:2132]........./smpd_package_command
[02:2132].........\SMPDU_Sock_get_sock_id
[02:2132]........./SMPDU_Sock_get_sock_id
[02:2132].........smpd_post_write_command on the pmi context sock 1080: 121 bytes for command: "cmd=result src=1 dest=2 tag=11 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS "
[02:2132].........\SMPDU_Sock_post_writev
[02:2132]........./SMPDU_Sock_post_writev
[02:2132]......../smpd_post_write_command
[02:2132]......./smpd_forward_command
[02:2132]....../smpd_handle_result
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the parent context, sock 908
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to pmi: "cmd=result src=1 dest=2 tag=11 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 92 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=barrier src=2 dest=1 tag=5 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 2
[02:2132]...... dest = 1
[02:2132]...... cmd  = barrier
[02:2132]...... tag  = 5
[02:2132]...... ctx  = pmi
[02:2132]...... len  = 92
[02:2132]...... str  = cmd=barrier src=2 dest=1 tag=5 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 1 : returning parent_context: 1 < 2
[02:2132]....../smpd_command_destination
[02:2132]......forwarding command to 1
[02:2132]......\smpd_forward_command
[02:2132].......\smpd_create_command_copy
[02:2132]......./smpd_create_command_copy
[02:2132].......posting write of forwarded command: "cmd=barrier src=2 dest=1 tag=5 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 "
[02:2132].......\smpd_post_write_command
[02:2132]........\smpd_package_command
[02:2132]......../smpd_package_command
[02:2132]........\SMPDU_Sock_get_sock_id
[02:2132]......../SMPDU_Sock_get_sock_id
[02:2132]........smpd_post_write_command on the parent context sock 908: 105 bytes for command: "cmd=barrier src=2 dest=1 tag=5 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 "
[02:2132]........\SMPDU_Sock_post_writev
[02:2132]......../SMPDU_Sock_post_writev
[02:2132]......./smpd_post_write_command
[02:2132]....../smpd_forward_command
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the pmi context, sock 1080
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to parent: "cmd=barrier src=2 dest=1 tag=5 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 88 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=result src=1 dest=2 tag=13 cmd_tag=5 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 1
[02:2132]...... dest = 2
[02:2132]...... cmd  = result
[02:2132]...... tag  = 13
[02:2132]...... ctx  = parent
[02:2132]...... len  = 88
[02:2132]...... str  = cmd=result src=1 dest=2 tag=13 cmd_tag=5 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 2 : returning NULL context
[02:2132]....../smpd_command_destination
[02:2132]......\smpd_handle_result
[02:2132].......forwarding the dbs result command to the pmi context 0.
[02:2132].......\smpd_forward_command
[02:2132]........\smpd_create_command_copy
[02:2132]......../smpd_create_command_copy
[02:2132]........posting write of forwarded command: "cmd=result src=1 dest=2 tag=13 cmd_tag=5 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:2132]........\smpd_post_write_command
[02:2132].........\smpd_package_command
[02:2132]........./smpd_package_command
[02:2132].........\SMPDU_Sock_get_sock_id
[02:2132]........./SMPDU_Sock_get_sock_id
[02:2132].........smpd_post_write_command on the pmi context sock 1080: 101 bytes for command: "cmd=result src=1 dest=2 tag=13 cmd_tag=5 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:2132].........\SMPDU_Sock_post_writev
[02:2132]........./SMPDU_Sock_post_writev
[02:2132]......../smpd_post_write_command
[02:2132]......./smpd_forward_command
[02:2132]....../smpd_handle_result
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the parent context, sock 908
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to pmi: "cmd=result src=1 dest=2 tag=13 cmd_tag=5 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 104 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=dbget src=2 dest=1 tag=6 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=sharedFilename[0] "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 2
[02:2132]...... dest = 1
[02:2132]...... cmd  = dbget
[02:2132]...... tag  = 6
[02:2132]...... ctx  = pmi
[02:2132]...... len  = 104
[02:2132]...... str  = cmd=dbget src=2 dest=1 tag=6 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=sharedFilename[0] 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 1 : returning parent_context: 1 < 2
[02:2132]....../smpd_command_destination
[02:2132]......forwarding command to 1
[02:2132]......\smpd_forward_command
[02:2132].......\smpd_create_command_copy
[02:2132]......./smpd_create_command_copy
[02:2132].......posting write of forwarded command: "cmd=dbget src=2 dest=1 tag=6 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=sharedFilename[0] "
[02:2132].......\smpd_post_write_command
[02:2132]........\smpd_package_command
[02:2132]......../smpd_package_command
[02:2132]........\SMPDU_Sock_get_sock_id
[02:2132]......../SMPDU_Sock_get_sock_id
[02:2132]........smpd_post_write_command on the parent context sock 908: 117 bytes for command: "cmd=dbget src=2 dest=1 tag=6 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=sharedFilename[0] "
[02:2132]........\SMPDU_Sock_post_writev
[02:2132]......../SMPDU_Sock_post_writev
[02:2132]......./smpd_post_write_command
[02:2132]....../smpd_forward_command
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the pmi context, sock 1080
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to parent: "cmd=dbget src=2 dest=1 tag=6 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=sharedFilename[0] "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd_header
[02:2132].....read command header
[02:2132].....command header read, posting read for data: 122 bytes
[02:2132].....\SMPDU_Sock_post_read
[02:2132]......\SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_read
[02:2132]..../smpd_state_reading_cmd_header
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132]...\smpd_handle_op_read
[02:2132]....\smpd_state_reading_cmd
[02:2132].....read command
[02:2132].....\smpd_parse_command
[02:2132]...../smpd_parse_command
[02:2132].....read command: "cmd=result src=1 dest=2 tag=14 cmd_tag=6 cmd_orig=dbget ctx_key=0 value=MPICH_NEM_2800_41417567968411 result=DBS_SUCCESS "
[02:2132].....\smpd_handle_command
[02:2132]......handling command:
[02:2132]...... src  = 1
[02:2132]...... dest = 2
[02:2132]...... cmd  = result
[02:2132]...... tag  = 14
[02:2132]...... ctx  = parent
[02:2132]...... len  = 122
[02:2132]...... str  = cmd=result src=1 dest=2 tag=14 cmd_tag=6 cmd_orig=dbget ctx_key=0 value=MPICH_NEM_2800_41417567968411 result=DBS_SUCCESS 
[02:2132]......\smpd_command_destination
[02:2132].......2 -> 2 : returning NULL context
[02:2132]....../smpd_command_destination
[02:2132]......\smpd_handle_result
[02:2132].......forwarding the dbs result command to the pmi context 0.
[02:2132].......\smpd_forward_command
[02:2132]........\smpd_create_command_copy
[02:2132]......../smpd_create_command_copy
[02:2132]........posting write of forwarded command: "cmd=result src=1 dest=2 tag=14 cmd_tag=6 cmd_orig=dbget ctx_key=0 value=MPICH_NEM_2800_41417567968411 result=DBS_SUCCESS "
[02:2132]........\smpd_post_write_command
[02:2132].........\smpd_package_command
[02:2132]........./smpd_package_command
[02:2132].........\SMPDU_Sock_get_sock_id
[02:2132]........./SMPDU_Sock_get_sock_id
[02:2132].........smpd_post_write_command on the pmi context sock 1080: 135 bytes for command: "cmd=result src=1 dest=2 tag=14 cmd_tag=6 cmd_orig=dbget ctx_key=0 value=MPICH_NEM_2800_41417567968411 result=DBS_SUCCESS "
[02:2132].........\SMPDU_Sock_post_writev
[02:2132]........./SMPDU_Sock_post_writev
[02:2132]......../smpd_post_write_command
[02:2132]......./smpd_forward_command
[02:2132]....../smpd_handle_result
[02:2132]...../smpd_handle_command
[02:2132].....\smpd_post_read_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......posting a read for a command header on the parent context, sock 908
[02:2132]......\SMPDU_Sock_post_read
[02:2132].......\SMPDU_Sock_post_readv
[02:2132]......./SMPDU_Sock_post_readv
[02:2132]....../SMPDU_Sock_post_read
[02:2132]...../smpd_post_read_command
[02:2132]..../smpd_state_reading_cmd
[02:2132].../smpd_handle_op_read
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../SMPDU_Sock_wait
[02:2132]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[02:2132]...\smpd_handle_op_write
[02:2132]....\smpd_state_writing_cmd
[02:2132].....wrote command
[02:2132].....command written to pmi: "cmd=result src=1 dest=2 tag=14 cmd_tag=6 cmd_orig=dbget ctx_key=0 value=MPICH_NEM_2800_41417567968411 result=DBS_SUCCESS "
[02:2132].....\smpd_free_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_free_command
[02:2132]..../smpd_state_writing_cmd
[02:2132].../smpd_handle_op_write
[02:2132]...sock_waiting for the next event.
[02:2132]...\SMPDU_Sock_wait
[02:2132].../smpd_easy_send
[02:2132]../smpd_easy_send
[02:2132]..ReadFile failed, error 109
[02:2132]..*** smpd_piothread finishing pid:2124 ***
[02:2132]..GetQueuedCompletionStatus failed, GetLastError: 64
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_READ event.error = -1, result = 0, context=pmi
[02:2132].\smpd_handle_op_read
[02:2132]..\smpd_state_reading_cmd_header
[02:2132]...ERROR:unable to read the cmd header on the pmi context, Error = -1
.
[02:2132]../smpd_state_reading_cmd_header
[02:2132]./smpd_handle_op_read
[02:2132].SOCK_OP_READ failed - result = -1, closing pmi context.
[02:2132].\SMPDU_Sock_post_close
[02:2132]..\SMPDU_Sock_post_read
[02:2132]...\SMPDU_Sock_post_readv
[02:2132]....ERROR:Error posting readv, An existing connection was forcibly closed by the remote host.(10054)
[02:2132].../SMPDU_Sock_post_readv
[02:2132]../SMPDU_Sock_post_read
[02:2132]./SMPDU_Sock_post_close
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_READ event.error = 0, result = 0, context=stderr
[02:2132].\smpd_handle_op_read
[02:2132]..\smpd_state_reading_stdouterr
[02:2132]...read from stderr
[02:2132]...\SMPDU_Sock_read
[02:2132]....\SMPDU_Sock_readv
[02:2132]..../SMPDU_Sock_readv
[02:2132].../SMPDU_Sock_read
[02:2132]...517 bytes read from stderr
[02:2132]...\smpd_create_command
[02:2132]....\smpd_init_command
[02:2132]..../smpd_init_command
[02:2132].../smpd_create_command
[02:2132]...\smpd_add_command_int_arg
[02:2132].../smpd_add_command_int_arg
[02:2132]...\smpd_add_command_int_arg
[02:2132].../smpd_add_command_int_arg
[02:2132]...\smpd_add_command_arg
[02:2132].../smpd_add_command_arg
[02:2132]...\smpd_post_write_command
[02:2132]....\smpd_package_command
[02:2132]..../smpd_package_command
[02:2132]....\SMPDU_Sock_get_sock_id
[02:2132]..../SMPDU_Sock_get_sock_id
[02:2132]....smpd_post_write_command on the parent context sock 908: 1099 bytes for command: "cmd=stderr src=2 dest=0 tag=1 rank=1 first=1 data=466174616C206572726F7220696E204D50495F496E69743A204F74686572204D5049206572726F722C206572726F7220737461636B3A0D0A4D5049525F496E69745F74687265616428333932292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F496E697428313339292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A206368616E6E656C20696E697469616C697A6174696F6E206661696C65640D0A4D504944495F4348335F496E6974283338292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F6E656D5F696E697428313936292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D504944495F434833495F5365675F636F6D6D697428333636292E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F486E645F646573657269616C697A6528333234292E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6F70656E28383633292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6372656174655F6174746163685F74656D706C28373633293A20756E61626C6520746F20616C6C6F6361746520736861726564206D656D6F7279202D204F70656E46696C654D617070696E67205468652073797374656D2063616E6E6F742066696E64207468652066696C65207370656369666965642E0D0D0A0D0A "
[02:2132]....\SMPDU_Sock_post_writev
[02:2132]..../SMPDU_Sock_post_writev
[02:2132].../smpd_post_write_command
[02:2132]...\SMPDU_Sock_post_read
[02:2132]....\SMPDU_Sock_post_readv
[02:2132]..../SMPDU_Sock_post_readv
[02:2132].../SMPDU_Sock_post_read
[02:2132]../smpd_state_reading_stdouterr
[02:2132]./smpd_handle_op_read
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_CLOSE event.error = 0, result = 0, context=pmi
[02:2132].\smpd_handle_op_close
[02:2132]..\smpd_get_state_string
[02:2132]../smpd_get_state_string
[02:2132]..op_close received - SMPD_CLOSING state.
[02:2132]..process refcount == 2, pmi closed.
[02:2132]..\smpd_free_context
[02:2132]...freeing pmi context.
[02:2132]...\smpd_init_context
[02:2132]....\smpd_init_command
[02:2132]..../smpd_init_command
[02:2132].../smpd_init_context
[02:2132]../smpd_free_context
[02:2132]./smpd_handle_op_close
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[02:2132].\smpd_handle_op_write
[02:2132]..\smpd_state_writing_cmd
[02:2132]...wrote command
[02:2132]...command written to parent: "cmd=stderr src=2 dest=0 tag=1 rank=1 first=1 data=466174616C206572726F7220696E204D50495F496E69743A204F74686572204D5049206572726F722C206572726F7220737461636B3A0D0A4D5049525F496E69745F74687265616428333932292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F496E697428313339292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A206368616E6E656C20696E697469616C697A6174696F6E206661696C65640D0A4D504944495F4348335F496E6974283338292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F6E656D5F696E697428313936292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D504944495F434833495F5365675F636F6D6D697428333636292E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F486E645F646573657269616C697A6528333234292E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6F70656E28383633292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6372656174655F6174746163685F74656D706C28373633293A20756E61626C6520746F20616C6C6F6361746520736861726564206D656D6F7279202D204F70656E46696C654D617070696E67205468652073797374656D2063616E6E6F742066696E64207468652066696C65207370656369666965642E0D0D0A0D0A "
[02:2132]...\smpd_free_command
[02:2132]....\smpd_init_command
[02:2132]..../smpd_init_command
[02:2132].../smpd_free_command
[02:2132]../smpd_state_writing_cmd
[02:2132]./smpd_handle_op_write
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_READ event.error = -1, result = 0, context=stderr
[02:2132].\smpd_handle_op_read
[02:2132]..\smpd_state_reading_stdouterr
[02:2132]../smpd_state_reading_stdouterr
[02:2132]./smpd_handle_op_read
[02:2132].SOCK_OP_READ failed - result = -1, closing stderr context.
[02:2132].\SMPDU_Sock_post_close
[02:2132]..\SMPDU_Sock_post_read
[02:2132]...\SMPDU_Sock_post_readv
[02:2132].../SMPDU_Sock_post_readv
[02:2132]../SMPDU_Sock_post_read
[02:2132]./SMPDU_Sock_post_close
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_CLOSE event.error = 0, result = 0, context=stderr
[02:2132].\smpd_handle_op_close
[02:2132]..\smpd_get_state_string
[02:2132]../smpd_get_state_string
[02:2132]..op_close received - SMPD_CLOSING state.
[02:2132]..process refcount == 1, stderr closed.
[02:2132]..\smpd_free_context
[02:2132]...freeing stderr context.
[02:2132]...\smpd_init_context
[02:2132]....\smpd_init_command
[02:2132]..../smpd_init_command
[02:2132].../smpd_init_context
[02:2132]../smpd_free_context
[02:2132]./smpd_handle_op_close
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]..ReadFile failed, error 109
[02:2132]..*** smpd_piothread finishing pid:2124 ***
[02:2132]..closing output socket took 0.000 seconds
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_READ event.error = -1, result = 0, context=stdout
[02:2132].\smpd_handle_op_read
[02:2132]..\smpd_state_reading_stdouterr
[02:2132]../smpd_state_reading_stdouterr
[02:2132]./smpd_handle_op_read
[02:2132].SOCK_OP_READ failed - result = -1, closing stdout context.
[02:2132].\SMPDU_Sock_post_close
[02:2132]..\SMPDU_Sock_post_read
[02:2132]...\SMPDU_Sock_post_readv
[02:2132].../SMPDU_Sock_post_readv
[02:2132]../SMPDU_Sock_post_read
[02:2132]./SMPDU_Sock_post_close
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_CLOSE event.error = 0, result = 0, context=stdout
[02:2132].\smpd_handle_op_close
[02:2132]..\smpd_get_state_string
[02:2132]../smpd_get_state_string
[02:2132]..op_close received - SMPD_CLOSING state.
[02:2132]..process refcount == 0, waiting for the process to finish exiting.
[02:2132]..\smpd_process_from_registry
[02:2132]../smpd_process_from_registry
[02:2132]..\smpd_wait_process
[02:2132]../smpd_wait_process
[02:2132]..\SMPDU_Sock_post_close
[02:2132]...\SMPDU_Sock_post_read
[02:2132]....\SMPDU_Sock_post_readv
[02:2132]..../SMPDU_Sock_post_readv
[02:2132].../SMPDU_Sock_post_read
[02:2132]../SMPDU_Sock_post_close
[02:2132]..\smpd_create_command
[02:2132]...\smpd_init_command
[02:2132].../smpd_init_command
[02:2132]../smpd_create_command
[02:2132]..\smpd_add_command_int_arg
[02:2132]../smpd_add_command_int_arg
[02:2132]..\smpd_add_command_int_arg
[02:2132]../smpd_add_command_int_arg
[02:2132]..\smpd_add_command_arg
[02:2132]../smpd_add_command_arg
[02:2132]..creating an exit command for rank 1, pid 2124, exit code 1.
[02:2132]..\smpd_post_write_command
[02:2132]...\smpd_package_command
[02:2132].../smpd_package_command
[02:2132]...\SMPDU_Sock_get_sock_id
[02:2132].../SMPDU_Sock_get_sock_id
[02:2132]...smpd_post_write_command on the parent context sock 908: 97 bytes for command: "cmd=exit src=2 dest=0 tag=2 rank=1 code=1 kvs=F1703AE4-8AD8-436d-A69A-3C4915C46230 "
[02:2132]...\SMPDU_Sock_post_writev
[02:2132].../SMPDU_Sock_post_writev
[02:2132]../smpd_post_write_command
[02:2132]..\smpd_free_process_struct
[02:2132]../smpd_free_process_struct
[02:2132]..\smpd_free_context
[02:2132]...freeing stdout context.
[02:2132]...\smpd_init_context
[02:2132]....\smpd_init_command
[02:2132]..../smpd_init_command
[02:2132].../smpd_init_context
[02:2132]../smpd_free_context
[02:2132]./smpd_handle_op_close
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[02:2132].\smpd_handle_op_write
[02:2132]..\smpd_state_writing_cmd
[02:2132]...wrote command
[02:2132]...command written to parent: "cmd=exit src=2 dest=0 tag=2 rank=1 code=1 kvs=F1703AE4-8AD8-436d-A69A-3C4915C46230 "
[02:2132]...\smpd_free_command
[02:2132]....\smpd_init_command
[02:2132]..../smpd_init_command
[02:2132].../smpd_free_command
[02:2132]../smpd_state_writing_cmd
[02:2132]./smpd_handle_op_write
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]..closing output socket took 0.000 seconds
[02:2132]..*** smpd_pinthread finishing pid:2124 ***
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_CLOSE event.error = 0, result = 0, context=stdin
[02:2132].\smpd_handle_op_close
[02:2132]..\smpd_get_state_string
[02:2132]../smpd_get_state_string
[02:2132]..op_close received - SMPD_CLOSING state.
[02:2132]..Unaffiliated stdin context closing.
[02:2132]..\smpd_free_context
[02:2132]...freeing stdin context.
[02:2132]...\smpd_init_context
[02:2132]....\smpd_init_command
[02:2132]..../smpd_init_command
[02:2132].../smpd_init_context
[02:2132]../smpd_free_context
[02:2132]./smpd_handle_op_close
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132].\smpd_handle_op_read
[02:2132]..\smpd_state_reading_cmd_header
[02:2132]...read command header
[02:2132]...command header read, posting read for data: 31 bytes
[02:2132]...\SMPDU_Sock_post_read
[02:2132]....\SMPDU_Sock_post_readv
[02:2132]..../SMPDU_Sock_post_readv
[02:2132].../SMPDU_Sock_post_read
[02:2132]../smpd_state_reading_cmd_header
[02:2132]./smpd_handle_op_read
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_READ event.error = 0, result = 0, context=parent
[02:2132].\smpd_handle_op_read
[02:2132]..\smpd_state_reading_cmd
[02:2132]...read command
[02:2132]...\smpd_parse_command
[02:2132].../smpd_parse_command
[02:2132]...read command: "cmd=close src=1 dest=2 tag=17 "
[02:2132]...\smpd_handle_command
[02:2132]....handling command:
[02:2132].... src  = 1
[02:2132].... dest = 2
[02:2132].... cmd  = close
[02:2132].... tag  = 17
[02:2132].... ctx  = parent
[02:2132].... len  = 31
[02:2132].... str  = cmd=close src=1 dest=2 tag=17 
[02:2132]....\smpd_command_destination
[02:2132].....2 -> 2 : returning NULL context
[02:2132]..../smpd_command_destination
[02:2132]....\smpd_handle_close_command
[02:2132].....\smpd_create_command
[02:2132]......\smpd_init_command
[02:2132]....../smpd_init_command
[02:2132]...../smpd_create_command
[02:2132].....sending closed command to parent: "cmd=closed src=2 dest=1 tag=3 "
[02:2132].....\smpd_post_write_command
[02:2132]......\smpd_package_command
[02:2132]....../smpd_package_command
[02:2132]......\SMPDU_Sock_get_sock_id
[02:2132]....../SMPDU_Sock_get_sock_id
[02:2132]......smpd_post_write_command on the parent context sock 908: 44 bytes for command: "cmd=closed src=2 dest=1 tag=3 "
[02:2132]......\SMPDU_Sock_post_writev
[02:2132]....../SMPDU_Sock_post_writev
[02:2132]...../smpd_post_write_command
[02:2132].....posted closed command.
[02:2132]..../smpd_handle_close_command
[02:2132].../smpd_handle_command
[02:2132]...not posting read for another command because SMPD_CLOSE returned
[02:2132]../smpd_state_reading_cmd
[02:2132]./smpd_handle_op_read
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[02:2132].\smpd_handle_op_write
[02:2132]..\smpd_state_writing_cmd
[02:2132]...wrote command
[02:2132]...command written to parent: "cmd=closed src=2 dest=1 tag=3 "
[02:2132]...closed command written, posting close of the sock.
[02:2132]...\SMPDU_Sock_get_sock_id
[02:2132].../SMPDU_Sock_get_sock_id
[02:2132]...SMPDU_Sock_post_close(908)
[02:2132]...\SMPDU_Sock_post_close
[02:2132]....\SMPDU_Sock_post_read
[02:2132].....\SMPDU_Sock_post_readv
[02:2132]...../SMPDU_Sock_post_readv
[02:2132]..../SMPDU_Sock_post_read
[02:2132].../SMPDU_Sock_post_close
[02:2132]...\smpd_free_command
[02:2132]....\smpd_init_command
[02:2132]..../smpd_init_command
[02:2132].../smpd_free_command
[02:2132]../smpd_state_writing_cmd
[02:2132]./smpd_handle_op_write
[02:2132].sock_waiting for the next event.
[02:2132].\SMPDU_Sock_wait
[02:2132]./SMPDU_Sock_wait
[02:2132].SOCK_OP_CLOSE event.error = 0, result = 0, context=parent
[02:2132].\smpd_handle_op_close
[02:2132]..\smpd_get_state_string
[02:2132]../smpd_get_state_string
[02:2132]..op_close received - SMPD_CLOSING state.
[02:2132]..Unaffiliated parent context closing.
[02:2132]..\smpd_free_context
[02:2132]...freeing parent context.
[02:2132]...\smpd_init_context
[02:2132]....\smpd_init_command
[02:2132]..../smpd_init_command
[02:2132].../smpd_init_context
[02:2132]../smpd_free_context
[02:2132]..all contexts closed, exiting state machine.
[02:2132]./smpd_handle_op_close
[02:2132]/smpd_enter_at_state
[02:2132]\smpd_exit
[02:2132].\smpd_kill_all_processes
[02:2132]./smpd_kill_all_processes
[02:2132].\smpd_finalize_drive_maps
[02:2132]./smpd_finalize_drive_maps
[02:2132].\smpd_dbs_finalize
[02:2132]./smpd_dbs_finalize
[02:2132].\SMPDU_Sock_finalize
[02:2132]./SMPDU_Sock_finalize