[-1:5844]...\smpd_get_opt_int
[-1:5844].../smpd_get_opt_int
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt_int
[-1:5844].../smpd_get_opt_int
[-1:5844]...\smpd_get_opt_int
[-1:5844].../smpd_get_opt_int
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt_string
[-1:5844].../smpd_get_opt_string
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt_string
[-1:5844].../smpd_get_opt_string
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt_string
[-1:5844].../smpd_get_opt_string
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt_string
[-1:5844].../smpd_get_opt_string
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt_string
[-1:5844].../smpd_get_opt_string
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt_string
[-1:5844].../smpd_get_opt_string
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]...\smpd_get_opt_string
[-1:5844].../smpd_get_opt_string
[-1:5844]...\smpd_get_opt_string
[-1:5844].../smpd_get_opt_string
[-1:5844]...\smpd_get_opt
[-1:5844].../smpd_get_opt
[-1:5844]../smpd_parse_command_args
[-1:5844]..\smpd_get_smpd_data
[-1:5844]...\smpd_get_smpd_data_from_environment
[-1:5844].../smpd_get_smpd_data_from_environment
[-1:5844]...\smpd_get_smpd_data_default
[-1:5844].../smpd_get_smpd_data_default
[-1:5844]../smpd_get_smpd_data
[-1:5844]..\smpd_entry_point
[00:5844]...\smpd_set_smpd_data
[00:5844].../smpd_set_smpd_data
[00:5844]...\SMPDU_Sock_create_set
[00:5844]....\SMPDU_Sock_get_sock_set_id
[00:5844]..../SMPDU_Sock_get_sock_set_id
[00:5844]....created a set for the listener: 568
[00:5844]....\SMPDU_Sock_listen
[00:5844]..../SMPDU_Sock_listen
[00:5844]....smpd listening on port 8676
[00:5844]....\smpd_create_context
[00:5844].....\smpd_init_context
[00:5844]......\smpd_init_command
[00:5844]....../smpd_init_command
[00:5844]......\SMPDU_Sock_set_user_ptr
[00:5844]....../SMPDU_Sock_set_user_ptr
[00:5844]...../smpd_init_context
[00:5844]..../smpd_create_context
[00:5844]....\SMPDU_Sock_set_user_ptr
[00:5844]..../SMPDU_Sock_set_user_ptr
[00:5844]....\smpd_option_on
[00:5844].....\smpd_get_smpd_data
[00:5844]......\smpd_get_smpd_data_from_environment
[00:5844]....../smpd_get_smpd_data_from_environment
[00:5844]......\smpd_get_smpd_data_default
[00:5844]....../smpd_get_smpd_data_default
[00:5844]......Unable to get the data for the key 'no_dynamic_hosts'
[00:5844]...../smpd_get_smpd_data
[00:5844]..../smpd_option_on
[00:5844]....\smpd_insert_into_dynamic_hosts
[00:5844]..../smpd_insert_into_dynamic_hosts
[00:5844]....\smpd_enter_at_state
[00:5844].....sock_waiting for the next event.
[00:5844].....\SMPDU_Sock_wait
[00:5844]...../SMPDU_Sock_wait
[00:5844].....SOCK_OP_ACCEPT event.error = 0, result = 0, context=listener
[00:5844].....\smpd_handle_op_accept
[00:5844]......\smpd_state_smpd_listening
[00:5844].......\SMPDU_Sock_accept
[00:5844]......./SMPDU_Sock_accept
[00:5844].......authenticating new connection
[00:5844].......\smpd_create_context
[00:5844]........\smpd_init_context
[00:5844].........\smpd_init_command
[00:5844]........./smpd_init_command
[00:5844].........\SMPDU_Sock_set_user_ptr
[00:5844]........./SMPDU_Sock_set_user_ptr
[00:5844]......../smpd_init_context
[00:5844]......./smpd_create_context
[00:5844].......\SMPDU_Sock_set_user_ptr
[00:5844]......./SMPDU_Sock_set_user_ptr
[00:5844].......\smpd_gen_authentication_strings
[00:5844]........\smpd_hash
[00:5844]......../smpd_hash
[00:5844]......./smpd_gen_authentication_strings
[00:5844].......posting a write of the challenge string: 1.2.1p1 19122
[00:5844].......\SMPDU_Sock_post_write
[00:5844]........\SMPDU_Sock_post_writev
[00:5844]......../SMPDU_Sock_post_writev
[00:5844]......./SMPDU_Sock_post_write
[00:5844]....../smpd_state_smpd_listening
[00:5844]...../smpd_handle_op_accept
[00:5844].....sock_waiting for the next event.
[00:5844].....\SMPDU_Sock_wait
[00:5844]...../SMPDU_Sock_wait
[00:5844].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:5844].....\smpd_handle_op_write
[00:5844]......\smpd_state_writing_challenge_string
[00:5844].......wrote challenge string: '1.2.1p1 19122'
[00:5844].......\SMPDU_Sock_post_read
[00:5844]........\SMPDU_Sock_post_readv
[00:5844]......../SMPDU_Sock_post_readv
[00:5844]......./SMPDU_Sock_post_read
[00:5844]....../smpd_state_writing_challenge_string
[00:5844]...../smpd_handle_op_write
[00:5844].....sock_waiting for the next event.
[00:5844].....\SMPDU_Sock_wait
[00:5844]...../SMPDU_Sock_wait
[00:5844].....SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[00:5844].....\smpd_handle_op_read
[00:5844]......\smpd_state_reading_challenge_response
[00:5844].......read challenge response: '36248b882811109a16f329ba23774e9b'
[00:5844].......\SMPDU_Sock_post_write
[00:5844]........\SMPDU_Sock_post_writev
[00:5844]......../SMPDU_Sock_post_writev
[00:5844]......./SMPDU_Sock_post_write
[00:5844]....../smpd_state_reading_challenge_response
[00:5844]...../smpd_handle_op_read
[00:5844].....sock_waiting for the next event.
[00:5844].....\SMPDU_Sock_wait
[00:5844]...../SMPDU_Sock_wait
[00:5844].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:5844].....\smpd_handle_op_write
[00:5844]......\smpd_state_writing_connect_result
[00:5844].......wrote connect result: 'SUCCESS'
[00:5844].......\SMPDU_Sock_post_read
[00:5844]........\SMPDU_Sock_post_readv
[00:5844]......../SMPDU_Sock_post_readv
[00:5844]......./SMPDU_Sock_post_read
[00:5844]....../smpd_state_writing_connect_result
[00:5844]...../smpd_handle_op_write
[00:5844].....sock_waiting for the next event.
[00:5844].....\SMPDU_Sock_wait
[00:5844]...../SMPDU_Sock_wait
[00:5844].....SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[00:5844].....\smpd_handle_op_read
[00:5844]......\smpd_state_reading_session_request
[00:5844].......read session request: 'process'
[00:5844].......\SMPDU_Sock_post_write
[00:5844]........\SMPDU_Sock_post_writev
[00:5844]......../SMPDU_Sock_post_writev
[00:5844]......./SMPDU_Sock_post_write
[00:5844]....../smpd_state_reading_session_request
[00:5844]...../smpd_handle_op_read
[00:5844].....sock_waiting for the next event.
[00:5844].....\SMPDU_Sock_wait
[00:5844]...../SMPDU_Sock_wait
[00:5844].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:5844].....\smpd_handle_op_write
[00:5844]......\smpd_state_writing_no_cred_request
[00:5844].......wrote no cred request: 'nocredentials'
[00:5844].......\smpd_start_win_mgr
[00:5844]........starting command: "C:\Program Files\MPICH2\bin\smpd.exe" -d 27 -mgr -read 0000000000000300 -write 00000000000002FC
[00:5844]........CreateProcess
[00:5844]........smpd reading the port string from the manager
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt_int
[-1:3904].../smpd_get_opt_int
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt_int
[-1:3904].../smpd_get_opt_int
[-1:3904]...\smpd_get_opt_int
[-1:3904].../smpd_get_opt_int
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt
[-1:3904].../smpd_get_opt
[-1:3904]...\smpd_get_opt_string
[-1:3904].../smpd_get_opt_string
[-1:3904]...\smpd_get_opt_string
[-1:3904].../smpd_get_opt_string
[-1:3904]...manager creating listener and session sets.
[-1:3904]...\SMPDU_Sock_create_set
[-1:3904]....\SMPDU_Sock_get_sock_set_id
[-1:3904]..../SMPDU_Sock_get_sock_set_id
[-1:3904]....created set for manager listener, 832
[-1:3904]....\SMPDU_Sock_listen
[-1:3904]..../SMPDU_Sock_listen
[-1:3904]....smpd manager listening on port 54809
[-1:3904]....\smpd_create_context
[-1:3904].....\smpd_init_context
[-1:3904]......\smpd_init_command
[-1:3904]....../smpd_init_command
[-1:3904]......\SMPDU_Sock_set_user_ptr
[-1:3904]....../SMPDU_Sock_set_user_ptr
[-1:3904]...../smpd_init_context
[-1:3904]..../smpd_create_context
[-1:3904]....\SMPDU_Sock_set_user_ptr
[-1:3904]..../SMPDU_Sock_set_user_ptr
[-1:3904]....manager writing port back to smpd.
[-1:3904]....\smpd_hide_string_arg
[-1:3904].....\first_token
[-1:3904]...../first_token
[-1:3904].....\compare_token
[-1:3904]...../compare_token
[-1:3904].....\next_token
[-1:3904]......\first_token
[-1:3904]....../first_token
[-1:3904]......\first_token
[-1:3904]....../first_token
[-1:3904]...../next_token
[-1:3904].....\compare_token
[-1:3904]...../compare_token
[-1:3904].....\compare_token
[-1:3904]...../compare_token
[-1:3904].....\next_token
[-1:3904]......\first_token
[-1:3904]....../first_token
[-1:3904]......\first_token
[-1:3904]....../first_token
[-1:3904]...../next_token
[-1:3904].....\compare_token
[-1:3904]...../compare_token
[-1:3904].....\next_token
[-1:3904]......\first_token
[-1:3904]....../first_token
[-1:3904]......\first_token
[-1:3904]....../first_token
[-1:3904]...../next_token
[-1:3904]..../smpd_hide_string_arg
[-1:3904]..../smpd_hide_string_arg
[00:5844]........smpd sending the account to the manager
[00:5844]........\smpd_hide_string_arg
[00:5844].........\first_token
[00:5844]........./first_token
[00:5844].........\compare_token
[00:5844]........./compare_token
[00:5844].........\next_token
[00:5844]..........\first_token
[00:5844]........../first_token
[00:5844]..........\first_token
[00:5844]........../first_token
[00:5844]........./next_token
[00:5844]......../smpd_hide_string_arg
[00:5844]......../smpd_hide_string_arg
[00:5844]........\smpd_hide_string_arg
[00:5844].........\first_token
[00:5844]........./first_token
[00:5844].........\compare_token
[00:5844]........./compare_token
[00:5844].........\next_token
[00:5844]..........\first_token
[00:5844]........../first_token
[00:5844]..........\first_token
[00:5844]........../first_token
[00:5844]........./next_token
[00:5844].........\compare_token
[00:5844]........./compare_token
[00:5844].........\compare_token
[00:5844]........./compare_token
[00:5844].........\next_token
[00:5844]..........\first_token
[00:5844]........../first_token
[00:5844]..........\first_token
[00:5844]........../first_token
[00:5844]........./next_token
[00:5844].........\compare_token
[00:5844]........./compare_token
[00:5844].........\next_token
[00:5844]..........\first_token
[00:5844]........../first_token
[00:5844]..........\first_token
[00:5844]........../first_token
[00:5844]........./next_token
[00:5844].........\compare_token
[00:5844]........./compare_token
[00:5844].........\next_token
[00:5844]..........\first_token
[00:5844]........../first_token
[00:5844]..........\first_token
[00:5844]........../first_token
[00:5844]........./next_token
[00:5844]......../smpd_hide_string_arg
[00:5844]......../smpd_hide_string_arg
[00:5844]........closing the pipe to the manager
[-1:3904]....\smpd_enter_at_state
[00:5844]........smpd writing reconnect request: port 54809
[-1:3904].....sock_waiting for the next event.
[00:5844]........\SMPDU_Sock_post_write
[-1:3904].....\SMPDU_Sock_wait
[00:5844].........\SMPDU_Sock_post_writev
[00:5844]........./SMPDU_Sock_post_writev
[00:5844]......../SMPDU_Sock_post_write
[00:5844]......./smpd_state_writing_no_cred_request
[00:5844]....../smpd_handle_op_write
[00:5844]......sock_waiting for the next event.
[00:5844]......\SMPDU_Sock_wait
[00:5844]....../SMPDU_Sock_wait
[00:5844]......SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:5844]......\smpd_handle_op_write
[00:5844].......\smpd_state_writing_reconnect_request
[00:5844]........wrote reconnect request: '54809'
[00:5844]........\SMPDU_Sock_post_close
[00:5844].........\SMPDU_Sock_post_read
[00:5844]..........\SMPDU_Sock_post_readv
[00:5844]........../SMPDU_Sock_post_readv
[00:5844]........./SMPDU_Sock_post_read
[00:5844]......../SMPDU_Sock_post_close
[00:5844]......./smpd_state_writing_reconnect_request
[00:5844]....../smpd_handle_op_write
[00:5844]......sock_waiting for the next event.
[00:5844]......\SMPDU_Sock_wait
[00:5844]....../SMPDU_Sock_wait
[00:5844]......SOCK_OP_CLOSE event.error = 0, result = 0, context=undetermined
[00:5844]......\smpd_handle_op_close
[00:5844].......\smpd_get_state_string
[00:5844]......./smpd_get_state_string
[00:5844].......op_close received - SMPD_CLOSING state.
[00:5844].......Unaffiliated undetermined context closing.
[00:5844].......\smpd_free_context
[00:5844]........freeing undetermined context.
[00:5844]........\smpd_init_context
[00:5844].........\smpd_init_command
[00:5844]........./smpd_init_command
[00:5844]......../smpd_init_context
[00:5844]......./smpd_free_context
[00:5844]....../smpd_handle_op_close
[00:5844]......sock_waiting for the next event.
[00:5844]......\SMPDU_Sock_wait
[-1:3904]...../SMPDU_Sock_wait
[-1:3904].....SOCK_OP_ACCEPT event.error = 0, result = 0, context=listener
[-1:3904].....\smpd_handle_op_accept
[-1:3904]......\smpd_state_mgr_listening
[-1:3904].......\SMPDU_Sock_accept
[-1:3904]......./SMPDU_Sock_accept
[-1:3904].......accepted re-connection
[-1:3904].......\smpd_create_context
[-1:3904]........\smpd_init_context
[-1:3904].........\smpd_init_command
[-1:3904]........./smpd_init_command
[-1:3904].........\SMPDU_Sock_set_user_ptr
[-1:3904]........./SMPDU_Sock_set_user_ptr
[-1:3904]......../smpd_init_context
[-1:3904]......./smpd_create_context
[-1:3904].......\SMPDU_Sock_set_user_ptr
[-1:3904]......./SMPDU_Sock_set_user_ptr
[-1:3904].......\SMPDU_Sock_post_read
[-1:3904]........\SMPDU_Sock_post_readv
[-1:3904]......../SMPDU_Sock_post_readv
[-1:3904]......./SMPDU_Sock_post_read
[-1:3904].......closing the mgr listener.
[-1:3904].......\SMPDU_Sock_post_close
[-1:3904]......./SMPDU_Sock_post_close
[-1:3904]....../smpd_state_mgr_listening
[-1:3904]...../smpd_handle_op_accept
[-1:3904].....sock_waiting for the next event.
[-1:3904].....\SMPDU_Sock_wait
[-1:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[-1:3904]...../SMPDU_Sock_wait
[-1:3904].....SOCK_OP_CLOSE event.error = 0, result = 0, context=listener
[-1:3904].....\smpd_handle_op_close
[-1:3904]......\smpd_get_state_string
[-1:3904]....../smpd_get_state_string
[-1:3904]......op_close received - SMPD_MGR_LISTENING state.
[-1:3904]......\smpd_free_context
[-1:3904].......freeing listener context.
[-1:3904].......\smpd_init_context
[-1:3904]........\smpd_init_command
[-1:3904]......../smpd_init_command
[-1:3904]......./smpd_init_context
[-1:3904]....../smpd_free_context
[-1:3904]...../smpd_handle_op_close
[-1:3904].....sock_waiting for the next event.
[-1:3904].....\SMPDU_Sock_wait
[-1:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[-1:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[-1:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[-1:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[-1:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[-1:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[-1:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[-1:3904]...../SMPDU_Sock_wait
[-1:3904].....SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[-1:3904].....\smpd_handle_op_read
[-1:3904]......\smpd_state_reading_session_header
[-1:3904].......read session header: 'id=1 parent=0 level=0'
[-1:3904].......\smpd_interpret_session_header
[-1:3904]........interpreting session header: "id=1 parent=0 level=0"
[-1:3904]........ id = 1
[01:3904]........ parent = 0
[01:3904]........ level = 0
[01:3904]......./smpd_interpret_session_header
[01:3904].......\smpd_post_read_command
[01:3904]........\SMPDU_Sock_get_sock_id
[01:3904]......../SMPDU_Sock_get_sock_id
[01:3904]........posting a read for a command header on the parent context, sock 972
[01:3904]........\SMPDU_Sock_post_read
[01:3904].........\SMPDU_Sock_post_readv
[01:3904]........./SMPDU_Sock_post_readv
[01:3904]......../SMPDU_Sock_post_read
[01:3904]......./smpd_post_read_command
[01:3904]....../smpd_state_reading_session_header
[01:3904]...../smpd_handle_op_read
[01:3904].....sock_waiting for the next event.
[01:3904].....\SMPDU_Sock_wait
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]......GetQueuedCompletionStatus failed, GetLastError: 995
[01:3904]...../SMPDU_Sock_wait
[01:3904].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:3904].....\smpd_handle_op_read
[01:3904]......\smpd_state_reading_cmd_header
[01:3904].......read command header
[01:3904].......command header read, posting read for data: 34 bytes
[01:3904].......\SMPDU_Sock_post_read
[01:3904]........\SMPDU_Sock_post_readv
[01:3904]......../SMPDU_Sock_post_readv
[01:3904]......./SMPDU_Sock_post_read
[01:3904]....../smpd_state_reading_cmd_header
[01:3904]...../smpd_handle_op_read
[01:3904].....sock_waiting for the next event.
[01:3904].....\SMPDU_Sock_wait
[01:3904]...../SMPDU_Sock_wait
[01:3904].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:3904].....\smpd_handle_op_read
[01:3904]......\smpd_state_reading_cmd
[01:3904].......read command
[01:3904].......\smpd_parse_command
[01:3904]......./smpd_parse_command
[01:3904].......read command: "cmd=start_dbs src=0 dest=1 tag=0 "
[01:3904].......\smpd_handle_command
[01:3904]........handling command:
[01:3904]........ src  = 0
[01:3904]........ dest = 1
[01:3904]........ cmd  = start_dbs
[01:3904]........ tag  = 0
[01:3904]........ ctx  = parent
[01:3904]........ len  = 34
[01:3904]........ str  = cmd=start_dbs src=0 dest=1 tag=0 
[01:3904]........\smpd_command_destination
[01:3904].........1 -> 1 : returning NULL context
[01:3904]......../smpd_command_destination
[01:3904]........\smpd_handle_start_dbs_command
[01:3904].........\smpd_dbs_init
[01:3904]........./smpd_dbs_init
[01:3904].........\smpd_create_command
[01:3904]..........\smpd_init_command
[01:3904]........../smpd_init_command
[01:3904]........./smpd_create_command
[01:3904].........\smpd_add_command_int_arg
[01:3904]........./smpd_add_command_int_arg
[01:3904].........\smpd_add_command_arg
[01:3904]........./smpd_add_command_arg
[01:3904].........\smpd_dbs_create
[01:3904]........./smpd_dbs_create
[01:3904].........\smpd_add_command_arg
[01:3904]........./smpd_add_command_arg
[01:3904].........\smpd_add_command_arg
[01:3904]........./smpd_add_command_arg
[01:3904].........\smpd_add_command_arg
[01:3904]........./smpd_add_command_arg
[01:3904].........sending result command to parent context: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=start_dbs kvs_name=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 domain_name=DE3FD9EF-D603-4370-A90E-B90DD80FDFB2 result=SUCCESS "
[01:3904].........\smpd_post_write_command
[01:3904]..........\smpd_package_command
[01:3904]........../smpd_package_command
[01:3904]..........\SMPDU_Sock_get_sock_id
[01:3904]........../SMPDU_Sock_get_sock_id
[01:3904]..........smpd_post_write_command on the parent context sock 972: 183 bytes for command: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=start_dbs kvs_name=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 domain_name=DE3FD9EF-D603-4370-A90E-B90DD80FDFB2 result=SUCCESS "
[01:3904]..........\SMPDU_Sock_post_writev
[01:3904]........../SMPDU_Sock_post_writev
[01:3904]........./smpd_post_write_command
[01:3904]......../smpd_handle_start_dbs_command
[01:3904]......./smpd_handle_command
[01:3904].......\smpd_post_read_command
[01:3904]........\SMPDU_Sock_get_sock_id
[01:3904]......../SMPDU_Sock_get_sock_id
[01:3904]........posting a read for a command header on the parent context, sock 972
[01:3904]........\SMPDU_Sock_post_read
[01:3904].........\SMPDU_Sock_post_readv
[01:3904]........./SMPDU_Sock_post_readv
[01:3904]......../SMPDU_Sock_post_read
[01:3904]......./smpd_post_read_command
[01:3904]....../smpd_state_reading_cmd
[01:3904]...../smpd_handle_op_read
[01:3904].....sock_waiting for the next event.
[01:3904].....\SMPDU_Sock_wait
[01:3904]...../SMPDU_Sock_wait
[01:3904].....SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:3904].....\smpd_handle_op_write
[01:3904]......\smpd_state_writing_cmd
[01:3904].......wrote command
[01:3904].......command written to parent: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=start_dbs kvs_name=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 domain_name=DE3FD9EF-D603-4370-A90E-B90DD80FDFB2 result=SUCCESS "
[01:3904].......\smpd_free_command
[01:3904]........\smpd_init_command
[01:3904]......../smpd_init_command
[01:3904]......./smpd_free_command
[01:3904]....../smpd_state_writing_cmd
[01:3904]...../smpd_handle_op_write
[01:3904].....sock_waiting for the next event.
[01:3904].....\SMPDU_Sock_wait
[01:3904]...../SMPDU_Sock_wait
[01:3904].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:3904].....\smpd_handle_op_read
[01:3904]......\smpd_state_reading_cmd_header
[01:3904].......read command header
[01:3904].......command header read, posting read for data: 202 bytes
[01:3904].......\SMPDU_Sock_post_read
[01:3904]........\SMPDU_Sock_post_readv
[01:3904]......../SMPDU_Sock_post_readv
[01:3904]......./SMPDU_Sock_post_read
[01:3904]....../smpd_state_reading_cmd_header
[01:3904]...../smpd_handle_op_read
[01:3904].....sock_waiting for the next event.
[01:3904].....\SMPDU_Sock_wait
[01:3904]...../SMPDU_Sock_wait
[01:3904].....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:3904].....\smpd_handle_op_read
[01:3904]......\smpd_state_reading_cmd
[01:3904].......read command
[01:3904].......\smpd_parse_command
[01:3904]......./smpd_parse_command
[01:3904].......read command: "cmd=launch src=0 dest=1 tag=1 c=hostname.exe s=0 a=0 d="C:\Program Files\MPICH2\bin" p=C:\Windows\system32 q=0..1 i=1 n=2 k=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 kd=DE3FD9EF-D603-4370-A90E-B90DD80FDFB2 "
[01:3904].......\smpd_handle_command
[01:3904]........handling command:
[01:3904]........ src  = 0
[01:3904]........ dest = 1
[01:3904]........ cmd  = launch
[01:3904]........ tag  = 1
[01:3904]........ ctx  = parent
[01:3904]........ len  = 202
[01:3904]........ str  = cmd=launch src=0 dest=1 tag=1 c=hostname.exe s=0 a=0 d="C:\Program Files\MPICH2\bin" p=C:\Windows\system32 q=0..1 i=1 n=2 k=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 kd=DE3FD9EF-D603-4370-A90E-B90DD80FDFB2 
[01:3904]........\smpd_command_destination
[01:3904].........1 -> 1 : returning NULL context
[01:3904]......../smpd_command_destination
[01:3904]........\smpd_handle_launch_command
[01:3904].........\smpd_create_process_struct
[01:3904]..........\smpd_create_context
[01:3904]...........\smpd_init_context
[01:3904]............\smpd_init_command
[01:3904]............/smpd_init_command
[01:3904].........../smpd_init_context
[01:3904]........../smpd_create_context
[01:3904]..........\smpd_create_context
[01:3904]...........\smpd_init_context
[01:3904]............\smpd_init_command
[01:3904]............/smpd_init_command
[01:3904].........../smpd_init_context
[01:3904]........../smpd_create_context
[01:3904]..........\smpd_create_context
[01:3904]...........\smpd_init_context
[01:3904]............\smpd_init_command
[01:3904]............/smpd_init_command
[01:3904].........../smpd_init_context
[01:3904]........../smpd_create_context
[01:3904]..........\smpd_create_context
[01:3904]...........\smpd_init_context
[01:3904]............\smpd_init_command
[01:3904]............/smpd_init_command
[01:3904].........../smpd_init_context
[01:3904]........../smpd_create_context
[01:3904]........./smpd_create_process_struct
[01:3904].........launching: 'hostname.exe'
[01:3904].........\smpd_launch_process
[01:3904]..........searching for 'hostname.exe' in 'C:\Windows\system32'
[01:3904]..........\smpd_search_path
[01:3904]........../smpd_search_path
[01:3904]..........\smpd_priority_class_to_win_class
[01:3904]........../smpd_priority_class_to_win_class
[01:3904]..........\smpd_priority_to_win_priority
[01:3904]........../smpd_priority_to_win_priority
[01:3904]..........\smpd_make_socket_loop_choose
[01:3904]...........\smpd_get_hostname
[01:3904].........../smpd_get_hostname
[01:3904]........../smpd_make_socket_loop_choose
[01:3904]..........\smpd_make_socket_loop_choose
[01:3904]...........\smpd_get_hostname
[01:3904].........../smpd_get_hostname
[01:3904]........../smpd_make_socket_loop_choose
[01:3904]..........\smpd_make_socket_loop_choose
[01:3904]...........\smpd_get_hostname
[01:3904].........../smpd_get_hostname
[01:3904]........../smpd_make_socket_loop_choose
[01:3904]..........\SMPDU_Sock_listen
[01:3904]........../SMPDU_Sock_listen
[01:3904]..........pmi listening on port 54817
[01:3904]..........\smpd_create_context
[01:3904]...........\smpd_init_context
[01:3904]............\smpd_init_command
[01:3904]............/smpd_init_command
[01:3904]............\SMPDU_Sock_set_user_ptr
[01:3904]............/SMPDU_Sock_set_user_ptr
[01:3904].........../smpd_init_context
[01:3904]........../smpd_create_context
[01:3904]..........\SMPDU_Sock_set_user_ptr
[01:3904]........../SMPDU_Sock_set_user_ptr
[01:3904]........./SMPDU_Sock_get_host_description
[01:3904].........\SMPDU_Sock_hostname_to_host_description
[01:3904]..........adding host: SECS8 to host list
[01:3904]..........adding host: fe80::9453:3e71:6c81:5222%10 to host list
[01:3904]..........adding host: 192.168.1.3 to host list
[01:3904]........./SMPDU_Sock_hostname_to_host_description
[01:3904]......../SMPDU_Sock_get_host_description
[01:3904]........\SetEnvironmentVariables
[01:3904]......../SetEnvironmentVariables
[01:3904]........env: PMI_RANK=1
[01:3904]........env: PMI_SIZE=2
[01:3904]........env: PMI_KVS=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09
[01:3904]........env: PMI_DOMAIN=DE3FD9EF-D603-4370-A90E-B90DD80FDFB2
[01:3904]........env: PMI_HOST=SECS8 fe80::9453:3e71:6c81:5222%10 192.168.1.3 
[01:3904]........env: PMI_PORT=54817
[01:3904]........env: PMI_SMPD_ID=1
[01:3904]........env: PMI_SMPD_KEY=0
[01:3904]........env: PMI_CLIQUE=0..1
[01:3904]........env: PMI_SPAWN=0
[01:3904]........env: PMI_APPNUM=0
[01:3904]........CreateProcess(C:\Windows\system32\hostname.exe)
[01:3904]........\RemoveEnvironmentVariables
[01:3904]......../RemoveEnvironmentVariables
[01:3904]........\SMPDU_Sock_native_to_sock
[01:3904]......../SMPDU_Sock_native_to_sock
[01:3904]........\SMPDU_Sock_native_to_sock
[01:3904]......../SMPDU_Sock_native_to_sock
[01:3904]........\SMPDU_Sock_native_to_sock
[01:3904]......../SMPDU_Sock_native_to_sock
[01:3904]........\SMPDU_Sock_set_user_ptr
[01:3904]......../SMPDU_Sock_set_user_ptr
[01:3904]........\SMPDU_Sock_set_user_ptr
[01:3904]......../SMPDU_Sock_set_user_ptr
[01:3904]........\SMPDU_Sock_set_user_ptr
[01:3904]......../SMPDU_Sock_set_user_ptr
[01:3904]........\SMPDU_Sock_post_read
[01:3904].........\SMPDU_Sock_post_readv
[01:3904]........./SMPDU_Sock_post_readv
[01:3904]......../SMPDU_Sock_post_read
[01:3904]........\SMPDU_Sock_post_read
[01:3904].........\SMPDU_Sock_post_readv
[01:3904]........./SMPDU_Sock_post_readv
[01:3904]......../SMPDU_Sock_post_read
[01:3904]........\smpd_process_to_registry
[01:3904]......../smpd_process_to_registry
[01:3904]......./smpd_launch_process
[01:3904].......\smpd_create_command
[01:3904]........\smpd_init_command
[01:3904]......../smpd_init_command
[01:3904]......./smpd_create_command
[01:3904].......\smpd_add_command_int_arg
[01:3904]......./smpd_add_command_int_arg
[01:3904].......\smpd_add_command_arg
[01:3904]......./smpd_add_command_arg
[01:3904].......\smpd_add_command_arg
[01:3904]......./smpd_add_command_arg
[01:3904].......\smpd_add_command_arg
[01:3904]......./smpd_add_command_arg
[01:3904].......\smpd_add_command_int_arg
[01:3904]......./smpd_add_command_int_arg
[01:3904].......\smpd_add_command_int_arg
[01:3904]......./smpd_add_command_int_arg
[01:3904].......\smpd_post_write_command
[01:3904]........\smpd_package_command
[01:3904]......../smpd_package_command
[01:3904]........\SMPDU_Sock_get_sock_id
[01:3904]......../SMPDU_Sock_get_sock_id
[01:3904]........smpd_post_write_command on the parent context sock 972: 147 bytes for command: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=launch result=SUCCESS pg_id=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 pg_rank=1 pg_ctx=0 "
[01:3904]........\SMPDU_Sock_post_writev
[01:3904]......../SMPDU_Sock_post_writev
[01:3904]......./smpd_post_write_command
[01:3904]....../smpd_handle_launch_command
[01:3904]...../smpd_handle_command
[01:3904].....\smpd_post_read_command
[01:3904]......\SMPDU_Sock_get_sock_id
[01:3904]....../SMPDU_Sock_get_sock_id
[01:3904]......posting a read for a command header on the parent context, sock 972
[01:3904]......\SMPDU_Sock_post_read
[01:3904].......\SMPDU_Sock_post_readv
[01:3904]......./SMPDU_Sock_post_readv
[01:3904]....../SMPDU_Sock_post_read
[01:3904]...../smpd_post_read_command
[01:3904]..../smpd_state_reading_cmd
[01:3904].../smpd_handle_op_read
[01:3904]...sock_waiting for the next event.
[01:3904]...\SMPDU_Sock_wait
[01:3904].../SMPDU_Sock_wait
[01:3904]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:3904]...\smpd_handle_op_write
[01:3904]....\smpd_state_writing_cmd
[01:3904].....wrote command
[01:3904].....command written to parent: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=launch result=SUCCESS pg_id=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 pg_rank=1 pg_ctx=0 "
[01:3904].....\smpd_free_command
[01:3904]......\smpd_init_command
[01:3904]....../smpd_init_command
[01:3904]...../smpd_free_command
[01:3904]..../smpd_state_writing_cmd
[01:3904].../smpd_handle_op_write
[01:3904]...sock_waiting for the next event.
[01:3904]...\SMPDU_Sock_wait
[01:3904].../SMPDU_Sock_wait
[01:3904]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:3904]...\smpd_handle_op_read
[01:3904]....\smpd_state_reading_cmd_header
[01:3904].....read command header
[01:3904].....command header read, posting read for data: 202 bytes
[01:3904].....\SMPDU_Sock_post_read
[01:3904]......\SMPDU_Sock_post_readv
[01:3904]....../SMPDU_Sock_post_readv
[01:3904]...../SMPDU_Sock_post_read
[01:3904]..../smpd_state_reading_cmd_header
[01:3904].../smpd_handle_op_read
[01:3904]...sock_waiting for the next event.
[01:3904]...\SMPDU_Sock_wait
[01:3904].../SMPDU_Sock_wait
[01:3904]...SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:3904]...\smpd_handle_op_read
[01:3904]....\smpd_state_reading_cmd
[01:3904].....read command
[01:3904].....\smpd_parse_command
[01:3904]...../smpd_parse_command
[01:3904].....read command: "cmd=launch src=0 dest=1 tag=2 c=hostname.exe s=0 a=0 d="C:\Program Files\MPICH2\bin" p=C:\Windows\system32 q=0..1 i=0 n=2 k=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 kd=DE3FD9EF-D603-4370-A90E-B90DD80FDFB2 "
[01:3904].....\smpd_handle_command
[01:3904]......handling command:
[01:3904]...... src  = 0
[01:3904]...... dest = 1
[01:3904]...... cmd  = launch
[01:3904]...... tag  = 2
[01:3904]...... ctx  = parent
[01:3904]...... len  = 202
[01:3904]...... str  = cmd=launch src=0 dest=1 tag=2 c=hostname.exe s=0 a=0 d="C:\Program Files\MPICH2\bin" p=C:\Windows\system32 q=0..1 i=0 n=2 k=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 kd=DE3FD9EF-D603-4370-A90E-B90DD80FDFB2 
[01:3904]......\smpd_command_destination
[01:3904].......1 -> 1 : returning NULL context
[01:3904]....../smpd_command_destination
[01:3904]......\smpd_handle_launch_command
[01:3904].......\smpd_create_process_struct
[01:3904]........\smpd_create_context
[01:3904].........\smpd_init_context
[01:3904]..........\smpd_init_command
[01:3904]........../smpd_init_command
[01:3904]........./smpd_init_context
[01:3904]......../smpd_create_context
[01:3904]........\smpd_create_context
[01:3904].........\smpd_init_context
[01:3904]..........\smpd_init_command
[01:3904]........../smpd_init_command
[01:3904]........./smpd_init_context
[01:3904]......../smpd_create_context
[01:3904]........\smpd_create_context
[01:3904].........\smpd_init_context
[01:3904]..........\smpd_init_command
[01:3904]........../smpd_init_command
[01:3904]........./smpd_init_context
[01:3904]......../smpd_create_context
[01:3904]........\smpd_create_context
[01:3904].........\smpd_init_context
[01:3904]..........\smpd_init_command
[01:3904]........../smpd_init_command
[01:3904]........./smpd_init_context
[01:3904]......../smpd_create_context
[01:3904]......./smpd_create_process_struct
[01:3904].......launching: 'hostname.exe'
[01:3904].......\smpd_launch_process
[01:3904]........searching for 'hostname.exe' in 'C:\Windows\system32'
[01:3904]........\smpd_search_path
[01:3904]......../smpd_search_path
[01:3904]........\smpd_priority_class_to_win_class
[01:3904]......../smpd_priority_class_to_win_class
[01:3904]........\smpd_priority_to_win_priority
[01:3904]......../smpd_priority_to_win_priority
[01:3904]........\smpd_make_socket_loop_choose
[01:3904].........\smpd_get_hostname
[01:3904]........./smpd_get_hostname
[01:3904].........*** entering smpd_pinthread pid:5548 sock:988 ***
[01:3904].........*** entering smpd_piothread pid:5548 sock:1000 ***
[01:3904]......../smpd_make_socket_loop_choose
[01:3904]........\smpd_make_socket_loop_choose
[01:3904].........\smpd_get_hostname
[01:3904]........./smpd_get_hostname
[01:3904]......../smpd_make_socket_loop_choose
[01:3904]........\smpd_make_socket_loop_choose
[01:3904].........\smpd_get_hostname
[01:3904]........./smpd_get_hostname
[01:3904]......../smpd_make_socket_loop_choose
[01:3904]........\SMPDU_Sock_listen
[01:3904].........*** entering smpd_piothread pid:5548 sock:992 ***
[01:3904]......../SMPDU_Sock_listen
[01:3904]........pmi listening on port 54824
[01:3904]........\smpd_create_context
[01:3904].........\smpd_init_context
[01:3904]..........\smpd_init_command
[01:3904]........../smpd_init_command
[01:3904]..........\SMPDU_Sock_set_user_ptr
[01:3904]........../SMPDU_Sock_set_user_ptr
[01:3904]........./smpd_init_context
[01:3904]......../smpd_create_context
[01:3904]........\SMPDU_Sock_set_user_ptr
[01:3904]......../SMPDU_Sock_set_user_ptr
[01:3904]......./SMPDU_Sock_get_host_description
[01:3904].......\SMPDU_Sock_hostname_to_host_description
[01:3904]........adding host: SECS8 to host list
[01:3904]........adding host: fe80::9453:3e71:6c81:5222%10 to host list
[01:3904]........adding host: 192.168.1.3 to host list
[01:3904]......./SMPDU_Sock_hostname_to_host_description
[01:3904]....../SMPDU_Sock_get_host_description
[01:3904]......\SetEnvironmentVariables
[01:3904]....../SetEnvironmentVariables
[01:3904]......env: PMI_RANK=0
[01:3904]......env: PMI_SIZE=2
[01:3904]......env: PMI_KVS=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09
[01:3904]......env: PMI_DOMAIN=DE3FD9EF-D603-4370-A90E-B90DD80FDFB2
[01:3904]......env: PMI_HOST=SECS8 fe80::9453:3e71:6c81:5222%10 192.168.1.3 
[01:3904]......env: PMI_PORT=54824
[01:3904]......env: PMI_SMPD_ID=1
[01:3904]......env: PMI_SMPD_KEY=1
[01:3904]......env: PMI_CLIQUE=0..1
[01:3904]......env: PMI_SPAWN=0
[01:3904]......env: PMI_APPNUM=0
[01:3904]......CreateProcess(C:\Windows\system32\hostname.exe)
[01:3904]......\RemoveEnvironmentVariables
[01:3904]....../RemoveEnvironmentVariables
[01:3904]......\SMPDU_Sock_native_to_sock
[01:3904]....../SMPDU_Sock_native_to_sock
[01:3904]......\SMPDU_Sock_native_to_sock
[01:3904]....../SMPDU_Sock_native_to_sock
[01:3904]......\SMPDU_Sock_native_to_sock
[01:3904]....../SMPDU_Sock_native_to_sock
[01:3904]......\SMPDU_Sock_set_user_ptr
[01:3904]....../SMPDU_Sock_set_user_ptr
[01:3904]......\SMPDU_Sock_set_user_ptr
[01:3904]....../SMPDU_Sock_set_user_ptr
[01:3904]......\SMPDU_Sock_set_user_ptr
[01:3904]....../SMPDU_Sock_set_user_ptr
[01:3904]......\SMPDU_Sock_post_read
[01:3904].......\SMPDU_Sock_post_readv
[01:3904]......./SMPDU_Sock_post_readv
[01:3904]....../SMPDU_Sock_post_read
[01:3904]......\SMPDU_Sock_post_read
[01:3904].......\SMPDU_Sock_post_readv
[01:3904]......./SMPDU_Sock_post_readv
[01:3904]....../SMPDU_Sock_post_read
[01:3904]......\smpd_process_to_registry
[01:3904]....../smpd_process_to_registry
[01:3904]...../smpd_launch_process
[01:3904].....\smpd_create_command
[01:3904]......\smpd_init_command
[01:3904]....../smpd_init_command
[01:3904]...../smpd_create_command
[01:3904].....\smpd_add_command_int_arg
[01:3904]...../smpd_add_command_int_arg
[01:3904].....\smpd_add_command_arg
[01:3904]...../smpd_add_command_arg
[01:3904].....\smpd_add_command_arg
[01:3904]...../smpd_add_command_arg
[01:3904].....\smpd_add_command_arg
[01:3904]...../smpd_add_command_arg
[01:3904].....\smpd_add_command_int_arg
[01:3904]...../smpd_add_command_int_arg
[01:3904].....\smpd_add_command_int_arg
[01:3904]...../smpd_add_command_int_arg
[01:3904].....\smpd_post_write_command
[01:3904]......\smpd_package_command
[01:3904]....../smpd_package_command
[01:3904]......\SMPDU_Sock_get_sock_id
[01:3904]....../SMPDU_Sock_get_sock_id
[01:3904]......smpd_post_write_command on the parent context sock 972: 147 bytes for command: "cmd=result src=1 dest=0 tag=2 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 pg_rank=0 pg_ctx=1 "
[01:3904]......\SMPDU_Sock_post_writev
[01:3904]....../SMPDU_Sock_post_writev
[01:3904]...../smpd_post_write_command
[01:3904]..../smpd_handle_launch_command
[01:3904].../smpd_handle_command
[01:3904]...\smpd_post_read_command
[01:3904]....\SMPDU_Sock_get_sock_id
[01:3904]..../SMPDU_Sock_get_sock_id
[01:3904]....posting a read for a command header on the parent context, sock 972
[01:3904]....\SMPDU_Sock_post_read
[01:3904].....\SMPDU_Sock_post_readv
[01:3904]...../SMPDU_Sock_post_readv
[01:3904]..../SMPDU_Sock_post_read
[01:3904].../smpd_post_read_command
[01:3904]../smpd_state_reading_cmd
[01:3904]./smpd_handle_op_read
[01:3904].sock_waiting for the next event.
[01:3904].\SMPDU_Sock_wait
[01:3904]./SMPDU_Sock_wait
[01:3904].SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:3904].\smpd_handle_op_write
[01:3904]..\smpd_state_writing_cmd
[01:3904]...wrote command
[01:3904]...command written to parent: "cmd=result src=1 dest=0 tag=2 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 pg_rank=0 pg_ctx=1 "
[01:3904]...\smpd_free_command
[01:3904]....\smpd_init_command
[01:3904]..../smpd_init_command
[01:3904].../smpd_free_command
[01:3904]../smpd_state_writing_cmd
[01:3904]./smpd_handle_op_write
[01:3904].sock_waiting for the next event.
[01:3904].\SMPDU_Sock_wait
[01:3904]..*** entering smpd_pinthread pid:1132 sock:1216 ***
[01:3904]..*** entering smpd_piothread pid:1132 sock:1228 ***
[01:3904]..*** entering smpd_piothread pid:1132 sock:1220 ***
[01:3904]./smpd_easy_send
[01:3904]/smpd_easy_send
[01:3904]ReadFile failed, error 109
[01:3904]*** smpd_piothread finishing pid:5548 ***
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_READ event.error = 0, result = 0, context=stdout
[01:3904]\smpd_handle_op_read
[01:3904]\smpd_state_reading_stdouterr
[01:3904].read from stdout
[01:3904].\SMPDU_Sock_read
[01:3904]..\SMPDU_Sock_readv
[01:3904]../SMPDU_Sock_readv
[01:3904]./SMPDU_Sock_read
[01:3904].7 bytes read from stdout
[01:3904].\smpd_create_command
[01:3904]..\smpd_init_command
[01:3904]../smpd_init_command
[01:3904].ReadFile failed, error 109
[01:3904]/smpd_create_command
[01:3904]*** smpd_piothread finishing pid:5548 ***
[01:3904]/smpd_easy_send
[01:3904]ReadFile failed, error 109
[01:3904]/smpd_easy_send
[01:3904]*** smpd_piothread finishing pid:1132 ***
[01:3904]\smpd_add_command_int_arg
[01:3904]ReadFile failed, error 109
[01:3904]/smpd_add_command_int_arg
[01:3904]*** smpd_piothread finishing pid:1132 ***
[01:3904]\smpd_add_command_int_arg
[01:3904]/smpd_add_command_int_arg
[01:3904]\smpd_add_command_arg
[01:3904]/smpd_add_command_arg
[01:3904]\smpd_post_write_command
[01:3904]\smpd_package_command
[01:3904]/smpd_package_command
[01:3904]\SMPDU_Sock_get_sock_id
[01:3904]/SMPDU_Sock_get_sock_id
[01:3904]smpd_post_write_command on the parent context sock 972: 79 bytes for command: "cmd=stdout src=1 dest=0 tag=3 rank=1 first=1 data=53454353380D0A "
[01:3904]\SMPDU_Sock_post_writev
[01:3904]/SMPDU_Sock_post_writev
[01:3904]/smpd_post_write_command
[01:3904]\SMPDU_Sock_post_read
[01:3904]\SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_read
[01:3904]/smpd_state_reading_stdouterr
[01:3904]/smpd_handle_op_read
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_READ event.error = -1, result = 0, context=stderr
[01:3904]\smpd_handle_op_read
[01:3904]\smpd_state_reading_stdouterr
[01:3904]/smpd_state_reading_stdouterr
[01:3904]/smpd_handle_op_read
[01:3904]SOCK_OP_READ failed - result = -1, closing stderr context.
[01:3904]\SMPDU_Sock_post_close
[01:3904]\SMPDU_Sock_post_read
[01:3904]\SMPDU_Sock_post_readv
[01:3904]closing output socket took 0.000 seconds
[01:3904]/SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_read
[01:3904]/SMPDU_Sock_post_close
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_READ event.error = 0, result = 0, context=stdout
[01:3904]\smpd_handle_op_read
[01:3904]\smpd_state_reading_stdouterr
[01:3904]read from stdout
[01:3904]\SMPDU_Sock_read
[01:3904]\SMPDU_Sock_readv
[01:3904]/SMPDU_Sock_readv
[01:3904]/SMPDU_Sock_read
[01:3904]7 bytes read from stdout
[01:3904]\smpd_create_command
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_create_command
[01:3904]\smpd_add_command_int_arg
[01:3904]/smpd_add_command_int_arg
[01:3904]\smpd_add_command_int_arg
[01:3904]/smpd_add_command_int_arg
[01:3904]\smpd_add_command_arg
[01:3904]/smpd_add_command_arg
[01:3904]\smpd_post_write_command
[01:3904]\smpd_package_command
[01:3904]/smpd_package_command
[01:3904]enqueueing write at the end of the list.
[01:3904]/smpd_post_write_command
[01:3904]\SMPDU_Sock_post_read
[01:3904]\SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_read
[01:3904]/smpd_state_reading_stdouterr
[01:3904]/smpd_handle_op_read
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_READ event.error = -1, result = 0, context=stderr
[01:3904]\smpd_handle_op_read
[01:3904]\smpd_state_reading_stdouterr
[01:3904]/smpd_state_reading_stdouterr
[01:3904]/smpd_handle_op_read
[01:3904]SOCK_OP_READ failed - result = -1, closing stderr context.
[01:3904]\SMPDU_Sock_post_close
[01:3904]\SMPDU_Sock_post_read
[01:3904]\SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_read
[01:3904]/SMPDU_Sock_post_close
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:3904]\smpd_handle_op_write
[01:3904]\smpd_state_writing_cmd
[01:3904]wrote command
[01:3904]command written to parent: "cmd=stdout src=1 dest=0 tag=3 rank=1 first=1 data=53454353380D0A "
[01:3904]\smpd_free_command
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_free_command
[01:3904]smpd_handle_written: posting write(79 bytes) for command: "cmd=stdout src=1 dest=0 tag=4 rank=0 first=1 data=53454353380D0A "
[01:3904]\SMPDU_Sock_post_writev
[01:3904]/SMPDU_Sock_post_writev
[01:3904]closing output socket took 0.000 seconds
[01:3904]/smpd_state_writing_cmd
[01:3904]/smpd_handle_op_write
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_READ event.error = -1, result = 0, context=stdout
[01:3904]\smpd_handle_op_read
[01:3904]\smpd_state_reading_stdouterr
[01:3904]/smpd_state_reading_stdouterr
[01:3904]/smpd_handle_op_read
[01:3904]SOCK_OP_READ failed - result = -1, closing stdout context.
[01:3904]\SMPDU_Sock_post_close
[01:3904]\SMPDU_Sock_post_read
[01:3904]closing output socket took 0.000 seconds
[01:3904]\SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_read
[01:3904]/SMPDU_Sock_post_close
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_CLOSE event.error = 0, result = 0, context=stderr
[01:3904]\smpd_handle_op_close
[01:3904]\smpd_get_state_string
[01:3904]/smpd_get_state_string
[01:3904]op_close received - SMPD_CLOSING state.
[01:3904]process refcount == 1, stderr closed.
[01:3904]\smpd_free_context
[01:3904]freeing stderr context.
[01:3904]\smpd_init_context
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_init_context
[01:3904]/smpd_free_context
[01:3904]/smpd_handle_op_close
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_READ event.error = -1, result = 0, context=stdout
[01:3904]\smpd_handle_op_read
[01:3904]\smpd_state_reading_stdouterr
[01:3904]/smpd_state_reading_stdouterr
[01:3904]/smpd_handle_op_read
[01:3904]SOCK_OP_READ failed - result = -1, closing stdout context.
[01:3904]\SMPDU_Sock_post_close
[01:3904]\SMPDU_Sock_post_read
[01:3904]closing output socket took 0.000 seconds
[01:3904]\SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_read
[01:3904]/SMPDU_Sock_post_close
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_CLOSE event.error = 0, result = 0, context=stderr
[01:3904]\smpd_handle_op_close
[01:3904]\smpd_get_state_string
[01:3904]/smpd_get_state_string
[01:3904]op_close received - SMPD_CLOSING state.
[01:3904]process refcount == 1, stderr closed.
[01:3904]\smpd_free_context
[01:3904]freeing stderr context.
[01:3904]\smpd_init_context
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_init_context
[01:3904]/smpd_free_context
[01:3904]/smpd_handle_op_close
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:3904]\smpd_handle_op_write
[01:3904]\smpd_state_writing_cmd
[01:3904]wrote command
[01:3904]command written to parent: "cmd=stdout src=1 dest=0 tag=4 rank=0 first=1 data=53454353380D0A "
[01:3904]\smpd_free_command
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_free_command
[01:3904]/smpd_state_writing_cmd
[01:3904]/smpd_handle_op_write
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_CLOSE event.error = 0, result = 0, context=stdout
[01:3904]\smpd_handle_op_close
[01:3904]\smpd_get_state_string
[01:3904]/smpd_get_state_string
[01:3904]op_close received - SMPD_CLOSING state.
[01:3904]process refcount == 0, waiting for the process to finish exiting.
[01:3904]\smpd_process_from_registry
[01:3904]/smpd_process_from_registry
[01:3904]\smpd_wait_process
[01:3904]/smpd_wait_process
[01:3904]\SMPDU_Sock_post_close
[01:3904]\SMPDU_Sock_post_read
[01:3904]\SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_read
[01:3904]/SMPDU_Sock_post_close
[01:3904]\smpd_create_command
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_create_command
[01:3904]\smpd_add_command_int_arg
[01:3904]/smpd_add_command_int_arg
[01:3904]\smpd_add_command_int_arg
[01:3904]/smpd_add_command_int_arg
[01:3904]\smpd_add_command_arg
[01:3904]/smpd_add_command_arg
[01:3904]creating an exit command for rank 1, pid 5548, exit code 0.
[01:3904]\smpd_post_write_command
[01:3904]\smpd_package_command
[01:3904]/smpd_package_command
[01:3904]\SMPDU_Sock_get_sock_id
[01:3904]/SMPDU_Sock_get_sock_id
[01:3904]smpd_post_write_command on the parent context sock 972: 97 bytes for command: "cmd=exit src=1 dest=0 tag=5 rank=1 code=0 kvs=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 "
[01:3904]\SMPDU_Sock_post_writev
[01:3904]/SMPDU_Sock_post_writev
[01:3904]/smpd_post_write_command
[01:3904]\smpd_free_process_struct
[01:3904]\smpd_free_context
[01:3904]freeing pmi context.
[01:3904]\smpd_init_context
[01:3904].\smpd_init_command
[01:3904]./smpd_init_command
[01:3904]/smpd_init_context
[01:3904]/smpd_free_context
[01:3904]/smpd_free_process_struct
[01:3904]\smpd_free_context
[01:3904]freeing stdout context.
[01:3904]\smpd_init_context
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_init_context
[01:3904]/smpd_free_context
[01:3904]/smpd_handle_op_close
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_CLOSE event.error = 0, result = 0, context=stdout
[01:3904]\smpd_handle_op_close
[01:3904]\smpd_get_state_string
[01:3904]/smpd_get_state_string
[01:3904]op_close received - SMPD_CLOSING state.
[01:3904]process refcount == 0, waiting for the process to finish exiting.
[01:3904]\smpd_process_from_registry
[01:3904]/smpd_process_from_registry
[01:3904]\smpd_wait_process
[01:3904]/smpd_wait_process
[01:3904]\SMPDU_Sock_post_close
[01:3904]\SMPDU_Sock_post_read
[01:3904]\SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_read
[01:3904]/SMPDU_Sock_post_close
[01:3904]\smpd_create_command
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_create_command
[01:3904]\smpd_add_command_int_arg
[01:3904]/smpd_add_command_int_arg
[01:3904]\smpd_add_command_int_arg
[01:3904]/smpd_add_command_int_arg
[01:3904]\smpd_add_command_arg
[01:3904]/smpd_add_command_arg
[01:3904]creating an exit command for rank 0, pid 1132, exit code 0.
[01:3904]\smpd_post_write_command
[01:3904]\smpd_package_command
[01:3904]/smpd_package_command
[01:3904]enqueueing write at the end of the list.
[01:3904]/smpd_post_write_command
[01:3904]\smpd_free_process_struct
[01:3904]\smpd_free_context
[01:3904]freeing pmi context.
[01:3904]\smpd_init_context
[01:3904].\smpd_init_command
[01:3904]./smpd_init_command
[01:3904]/smpd_init_context
[01:3904]/smpd_free_context
[01:3904]/smpd_free_process_struct
[01:3904]\smpd_free_context
[01:3904]freeing stdout context.
[01:3904]\smpd_init_context
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_init_context
[01:3904]/smpd_free_context
[01:3904]/smpd_handle_op_close
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:3904]\smpd_handle_op_write
[01:3904]\smpd_state_writing_cmd
[01:3904]wrote command
[01:3904]command written to parent: "cmd=exit src=1 dest=0 tag=5 rank=1 code=0 kvs=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 "
[01:3904]\smpd_free_command
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_free_command
[01:3904]smpd_handle_written: posting write(97 bytes) for command: "cmd=exit src=1 dest=0 tag=6 rank=0 code=0 kvs=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 "
[01:3904]\SMPDU_Sock_post_writev
[01:3904]/SMPDU_Sock_post_writev
[01:3904]/smpd_state_writing_cmd
[01:3904]/smpd_handle_op_write
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:3904]\smpd_handle_op_write
[01:3904]\smpd_state_writing_cmd
[01:3904]wrote command
[01:3904]command written to parent: "cmd=exit src=1 dest=0 tag=6 rank=0 code=0 kvs=04FBBBFB-9CE9-4704-9B6D-6AF0506C8C09 "
[01:3904]\smpd_free_command
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_free_command
[01:3904]/smpd_state_writing_cmd
[01:3904]/smpd_handle_op_write
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]*** smpd_pinthread finishing pid:5548 ***
[01:3904]*** smpd_pinthread finishing pid:1132 ***
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_CLOSE event.error = 0, result = 0, context=stdin
[01:3904]\smpd_handle_op_close
[01:3904]\smpd_get_state_string
[01:3904]/smpd_get_state_string
[01:3904]op_close received - SMPD_CLOSING state.
[01:3904]Unaffiliated stdin context closing.
[01:3904]\smpd_free_context
[01:3904]freeing stdin context.
[01:3904]\smpd_init_context
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_init_context
[01:3904]/smpd_free_context
[01:3904]/smpd_handle_op_close
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:3904]\smpd_handle_op_read
[01:3904]\smpd_state_reading_cmd_header
[01:3904]read command header
[01:3904]command header read, posting read for data: 30 bytes
[01:3904]\SMPDU_Sock_post_read
[01:3904]\SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_read
[01:3904]/smpd_state_reading_cmd_header
[01:3904]/smpd_handle_op_read
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_CLOSE event.error = 0, result = 0, context=stdin
[01:3904]\smpd_handle_op_close
[01:3904]\smpd_get_state_string
[01:3904]/smpd_get_state_string
[01:3904]op_close received - SMPD_CLOSING state.
[01:3904]Unaffiliated stdin context closing.
[01:3904]\smpd_free_context
[01:3904]freeing stdin context.
[01:3904]\smpd_init_context
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_init_context
[01:3904]/smpd_free_context
[01:3904]/smpd_handle_op_close
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:3904]\smpd_handle_op_read
[01:3904]\smpd_state_reading_cmd
[01:3904]read command
[01:3904]\smpd_parse_command
[01:3904]/smpd_parse_command
[01:3904]read command: "cmd=close src=0 dest=1 tag=3 "
[01:3904]\smpd_handle_command
[01:3904]handling command:
[01:3904] src  = 0
[01:3904] dest = 1
[01:3904] cmd  = close
[01:3904] tag  = 3
[01:3904] ctx  = parent
[01:3904] len  = 30
[01:3904] str  = cmd=close src=0 dest=1 tag=3 
[01:3904]\smpd_command_destination
[01:3904].1 -> 1 : returning NULL context
[01:3904]/smpd_command_destination
[01:3904]\smpd_handle_close_command
[01:3904].\smpd_create_command
[01:3904]..\smpd_init_command
[01:3904]../smpd_init_command
[01:3904]./smpd_create_command
[01:3904].sending closed command to parent: "cmd=closed src=1 dest=0 tag=7 "
[01:3904].\smpd_post_write_command
[01:3904]..\smpd_package_command
[01:3904]../smpd_package_command
[01:3904]..\SMPDU_Sock_get_sock_id
[01:3904]../SMPDU_Sock_get_sock_id
[01:3904]..smpd_post_write_command on the parent context sock 972: 44 bytes for command: "cmd=closed src=1 dest=0 tag=7 "
[01:3904]..\SMPDU_Sock_post_writev
[01:3904]../SMPDU_Sock_post_writev
[01:3904]./smpd_post_write_command
[01:3904].posted closed command.
[01:3904]/smpd_handle_close_command
[01:3904]/smpd_handle_command
[01:3904]not posting read for another command because SMPD_CLOSE returned
[01:3904]/smpd_state_reading_cmd
[01:3904]/smpd_handle_op_read
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:3904]\smpd_handle_op_write
[01:3904]\smpd_state_writing_cmd
[01:3904]wrote command
[01:3904]command written to parent: "cmd=closed src=1 dest=0 tag=7 "
[01:3904]closed command written, posting close of the sock.
[01:3904]\SMPDU_Sock_get_sock_id
[01:3904]/SMPDU_Sock_get_sock_id
[01:3904]SMPDU_Sock_post_close(972)
[01:3904]\SMPDU_Sock_post_close
[01:3904]\SMPDU_Sock_post_read
[01:3904].\SMPDU_Sock_post_readv
[01:3904]./SMPDU_Sock_post_readv
[01:3904]/SMPDU_Sock_post_read
[01:3904]/SMPDU_Sock_post_close
[01:3904]\smpd_free_command
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_free_command
[01:3904]/smpd_state_writing_cmd
[01:3904]/smpd_handle_op_write
[01:3904]sock_waiting for the next event.
[01:3904]\SMPDU_Sock_wait
[01:3904]/SMPDU_Sock_wait
[01:3904]SOCK_OP_CLOSE event.error = 0, result = 0, context=parent
[01:3904]\smpd_handle_op_close
[01:3904]\smpd_get_state_string
[01:3904]/smpd_get_state_string
[01:3904]op_close received - SMPD_CLOSING state.
[01:3904]Unaffiliated parent context closing.
[01:3904]\smpd_free_context
[01:3904]freeing parent context.
[01:3904]\smpd_init_context
[01:3904]\smpd_init_command
[01:3904]/smpd_init_command
[01:3904]/smpd_init_context
[01:3904]/smpd_free_context
[01:3904]all contexts closed, exiting state machine.
[01:3904]/smpd_handle_op_close
[01:3904]/smpd_enter_at_state
[01:3904]\smpd_exit
[01:3904]\smpd_kill_all_processes
[01:3904]/smpd_kill_all_processes
[01:3904]\smpd_finalize_drive_maps
[01:3904]/smpd_finalize_drive_maps
[01:3904]\smpd_dbs_finalize
[01:3904]/smpd_dbs_finalize
[01:3904]\SMPDU_Sock_finalize
[01:3904]/SMPDU_Sock_finalize