[-1:3284]..\smpd_get_opt_int
[-1:3284]../smpd_get_opt_int
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt_int
[-1:3284]../smpd_get_opt_int
[-1:3284]..\smpd_get_opt_int
[-1:3284]../smpd_get_opt_int
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt_string
[-1:3284]../smpd_get_opt_string
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt_string
[-1:3284]../smpd_get_opt_string
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt_string
[-1:3284]../smpd_get_opt_string
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt_string
[-1:3284]../smpd_get_opt_string
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt_string
[-1:3284]../smpd_get_opt_string
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt_string
[-1:3284]../smpd_get_opt_string
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]..\smpd_get_opt_string
[-1:3284]../smpd_get_opt_string
[-1:3284]..\smpd_get_opt_string
[-1:3284]../smpd_get_opt_string
[-1:3284]..\smpd_get_opt
[-1:3284]../smpd_get_opt
[-1:3284]./smpd_parse_command_args
[-1:3284].\smpd_get_smpd_data
[-1:3284]..\smpd_get_smpd_data_from_environment
[-1:3284]../smpd_get_smpd_data_from_environment
[-1:3284]./smpd_get_smpd_data
[-1:3284].\smpd_entry_point
[00:3284]..\smpd_set_smpd_data
[00:3284]../smpd_set_smpd_data
[00:3284]..\SMPDU_Sock_create_set
[00:3284]...\SMPDU_Sock_get_sock_set_id
[00:3284].../SMPDU_Sock_get_sock_set_id
[00:3284]...created a set for the listener: 1620
[00:3284]...\SMPDU_Sock_listen
[00:3284].../SMPDU_Sock_listen
[00:3284]...smpd listening on port 8676
[00:3284]...\smpd_create_context
[00:3284]....\smpd_init_context
[00:3284].....\smpd_init_command
[00:3284]...../smpd_init_command
[00:3284].....\SMPDU_Sock_set_user_ptr
[00:3284]...../SMPDU_Sock_set_user_ptr
[00:3284]..../smpd_init_context
[00:3284].../smpd_create_context
[00:3284]...\SMPDU_Sock_set_user_ptr
[00:3284].../SMPDU_Sock_set_user_ptr
[00:3284]...\smpd_option_on
[00:3284]....\smpd_get_smpd_data
[00:3284].....\smpd_get_smpd_data_from_environment
[00:3284]...../smpd_get_smpd_data_from_environment
[00:3284].....\smpd_get_smpd_data_default
[00:3284]...../smpd_get_smpd_data_default
[00:3284].....Unable to get the data for the key 'no_dynamic_hosts'
[00:3284]..../smpd_get_smpd_data
[00:3284].../smpd_option_on
[00:3284]...\smpd_insert_into_dynamic_hosts
[00:3284].../smpd_insert_into_dynamic_hosts
[00:3284]...\smpd_enter_at_state
[00:3284]....sock_waiting for the next event.
[00:3284]....\SMPDU_Sock_wait
[00:3284]..../SMPDU_Sock_wait
[00:3284]....SOCK_OP_ACCEPT event.error = 0, result = 0, context=listener
[00:3284]....\smpd_handle_op_accept
[00:3284].....\smpd_state_smpd_listening
[00:3284]......\SMPDU_Sock_accept
[00:3284]....../SMPDU_Sock_accept
[00:3284]......authenticating new connection
[00:3284]......\smpd_create_context
[00:3284].......\smpd_init_context
[00:3284]........\smpd_init_command
[00:3284]......../smpd_init_command
[00:3284]........\SMPDU_Sock_set_user_ptr
[00:3284]......../SMPDU_Sock_set_user_ptr
[00:3284]......./smpd_init_context
[00:3284]....../smpd_create_context
[00:3284]......\SMPDU_Sock_set_user_ptr
[00:3284]....../SMPDU_Sock_set_user_ptr
[00:3284]......\smpd_gen_authentication_strings
[00:3284].......\smpd_hash
[00:3284]......./smpd_hash
[00:3284]....../smpd_gen_authentication_strings
[00:3284]......posting a write of the challenge string: 1.3a1 10762
[00:3284]......\SMPDU_Sock_post_write
[00:3284].......\SMPDU_Sock_post_writev
[00:3284]......./SMPDU_Sock_post_writev
[00:3284]....../SMPDU_Sock_post_write
[00:3284]...../smpd_state_smpd_listening
[00:3284]..../smpd_handle_op_accept
[00:3284]....sock_waiting for the next event.
[00:3284]....\SMPDU_Sock_wait
[00:3284]..../SMPDU_Sock_wait
[00:3284]....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:3284]....\smpd_handle_op_write
[00:3284].....\smpd_state_writing_challenge_string
[00:3284]......wrote challenge string: '1.3a1 10762'
[00:3284]......\SMPDU_Sock_post_read
[00:3284].......\SMPDU_Sock_post_readv
[00:3284]......./SMPDU_Sock_post_readv
[00:3284]....../SMPDU_Sock_post_read
[00:3284]...../smpd_state_writing_challenge_string
[00:3284]..../smpd_handle_op_write
[00:3284]....sock_waiting for the next event.
[00:3284]....\SMPDU_Sock_wait
[00:3284]..../SMPDU_Sock_wait
[00:3284]....SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[00:3284]....\smpd_handle_op_read
[00:3284].....\smpd_state_reading_challenge_response
[00:3284]......read challenge response: '770555af36abea704df8e9dbd922b0bf'
[00:3284]......\SMPDU_Sock_post_write
[00:3284].......\SMPDU_Sock_post_writev
[00:3284]......./SMPDU_Sock_post_writev
[00:3284]....../SMPDU_Sock_post_write
[00:3284]...../smpd_state_reading_challenge_response
[00:3284]..../smpd_handle_op_read
[00:3284]....sock_waiting for the next event.
[00:3284]....\SMPDU_Sock_wait
[00:3284]..../SMPDU_Sock_wait
[00:3284]....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:3284]....\smpd_handle_op_write
[00:3284].....\smpd_state_writing_connect_result
[00:3284]......wrote connect result: 'SUCCESS'
[00:3284]......\SMPDU_Sock_post_read
[00:3284].......\SMPDU_Sock_post_readv
[00:3284]......./SMPDU_Sock_post_readv
[00:3284]....../SMPDU_Sock_post_read
[00:3284]...../smpd_state_writing_connect_result
[00:3284]..../smpd_handle_op_write
[00:3284]....sock_waiting for the next event.
[00:3284]....\SMPDU_Sock_wait
[00:3284]..../SMPDU_Sock_wait
[00:3284]....SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[00:3284]....\smpd_handle_op_read
[00:3284].....\smpd_state_reading_session_request
[00:3284]......read session request: 'process'
[00:3284]......\SMPDU_Sock_post_write
[00:3284].......\SMPDU_Sock_post_writev
[00:3284]......./SMPDU_Sock_post_writev
[00:3284]....../SMPDU_Sock_post_write
[00:3284]...../smpd_state_reading_session_request
[00:3284]..../smpd_handle_op_read
[00:3284]....sock_waiting for the next event.
[00:3284]....\SMPDU_Sock_wait
[00:3284]..../SMPDU_Sock_wait
[00:3284]....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:3284]....\smpd_handle_op_write
[00:3284].....\smpd_state_writing_no_cred_request
[00:3284]......wrote no cred request: 'nocredentials'
[00:3284]......\smpd_start_win_mgr
[00:3284].......starting command: "C:\Program Files\MPICH2\bin\smpd.exe" -d 27 -mgr -read 000005A8 -write 000005AC
[00:3284].......CreateProcess
[00:3284].......smpd reading the port string from the manager
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt_int
[-1:1048]../smpd_get_opt_int
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt_int
[-1:1048]../smpd_get_opt_int
[-1:1048]..\smpd_get_opt_int
[-1:1048]../smpd_get_opt_int
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt
[-1:1048]../smpd_get_opt
[-1:1048]..\smpd_get_opt_string
[-1:1048]../smpd_get_opt_string
[-1:1048]..\smpd_get_opt_string
[-1:1048]../smpd_get_opt_string
[-1:1048]..manager creating listener and session sets.
[-1:1048]..\SMPDU_Sock_create_set
[-1:1048]...\SMPDU_Sock_get_sock_set_id
[-1:1048].../SMPDU_Sock_get_sock_set_id
[-1:1048]...created set for manager listener, 1356
[-1:1048]...\SMPDU_Sock_listen
[-1:1048].../SMPDU_Sock_listen
[-1:1048]...smpd manager listening on port 3626
[-1:1048]...\smpd_create_context
[-1:1048]....\smpd_init_context
[-1:1048].....\smpd_init_command
[-1:1048]...../smpd_init_command
[-1:1048].....\SMPDU_Sock_set_user_ptr
[-1:1048]...../SMPDU_Sock_set_user_ptr
[-1:1048]..../smpd_init_context
[-1:1048].../smpd_create_context
[-1:1048]...\SMPDU_Sock_set_user_ptr
[-1:1048].../SMPDU_Sock_set_user_ptr
[-1:1048]...manager writing port back to smpd.
[-1:1048]...\smpd_hide_string_arg
[-1:1048]....\first_token
[-1:1048]..../first_token
[-1:1048]....\compare_token
[-1:1048]..../compare_token
[-1:1048]....\next_token
[-1:1048].....\first_token
[-1:1048]...../first_token
[-1:1048].....\first_token
[-1:1048]...../first_token
[-1:1048]..../next_token
[-1:1048]....\compare_token
[-1:1048]..../compare_token
[-1:1048]....\compare_token
[-1:1048]..../compare_token
[-1:1048]....\next_token
[-1:1048].....\first_token
[-1:1048]...../first_token
[-1:1048].....\first_token
[-1:1048]...../first_token
[-1:1048]..../next_token
[-1:1048]....\compare_token
[-1:1048]..../compare_token
[-1:1048]....\next_token
[-1:1048].....\first_token
[-1:1048]...../first_token
[-1:1048].....\first_token
[-1:1048]...../first_token
[-1:1048]..../next_token
[-1:1048].../smpd_hide_string_arg
[-1:1048].../smpd_hide_string_arg
[00:3284].......smpd sending the account to the manager
[00:3284].......\smpd_hide_string_arg
[00:3284]........\first_token
[00:3284]......../first_token
[00:3284]........\compare_token
[00:3284]......../compare_token
[00:3284]........\next_token
[00:3284].........\first_token
[00:3284]........./first_token
[00:3284].........\first_token
[00:3284]........./first_token
[00:3284]......../next_token
[00:3284]......./smpd_hide_string_arg
[00:3284]......./smpd_hide_string_arg
[00:3284].......\smpd_hide_string_arg
[00:3284]........\first_token
[00:3284]......../first_token
[00:3284]........\compare_token
[00:3284]......../compare_token
[00:3284]........\next_token
[00:3284].........\first_token
[00:3284]........./first_token
[00:3284].........\first_token
[00:3284]........./first_token
[00:3284]......../next_token
[00:3284]........\compare_token
[00:3284]......../compare_token
[00:3284]........\compare_token
[00:3284]......../compare_token
[00:3284]........\next_token
[00:3284].........\first_token
[00:3284]........./first_token
[00:3284].........\first_token
[00:3284]........./first_token
[00:3284]......../next_token
[00:3284]........\compare_token
[00:3284]......../compare_token
[00:3284]........\next_token
[00:3284].........\first_token
[00:3284]........./first_token
[00:3284].........\first_token
[00:3284]........./first_token
[00:3284]......../next_token
[00:3284]........\compare_token
[00:3284]......../compare_token
[00:3284]........\next_token
[00:3284].........\first_token
[00:3284]........./first_token
[00:3284].........\first_token
[00:3284]........./first_token
[00:3284]......../next_token
[00:3284]......./smpd_hide_string_arg
[00:3284]......./smpd_hide_string_arg
[00:3284].......closing the pipe to the manager
[-1:1048]...\smpd_enter_at_state
[00:3284].......smpd writing reconnect request: port 3626
[-1:1048]....sock_waiting for the next event.
[00:3284].......\SMPDU_Sock_post_write
[-1:1048]....\SMPDU_Sock_wait
[00:3284]........\SMPDU_Sock_post_writev
[00:3284]......../SMPDU_Sock_post_writev
[00:3284]......./SMPDU_Sock_post_write
[00:3284]....../smpd_state_writing_no_cred_request
[00:3284]...../smpd_handle_op_write
[00:3284].....sock_waiting for the next event.
[00:3284].....\SMPDU_Sock_wait
[00:3284]...../SMPDU_Sock_wait
[00:3284].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined
[00:3284].....\smpd_handle_op_write
[00:3284]......\smpd_state_writing_reconnect_request
[00:3284].......wrote reconnect request: '3626'
[00:3284].......\SMPDU_Sock_post_close
[00:3284]........\SMPDU_Sock_post_read
[00:3284].........\SMPDU_Sock_post_readv
[00:3284]........./SMPDU_Sock_post_readv
[00:3284]......../SMPDU_Sock_post_read
[00:3284]......./SMPDU_Sock_post_close
[00:3284]....../smpd_state_writing_reconnect_request
[00:3284]...../smpd_handle_op_write
[00:3284].....sock_waiting for the next event.
[00:3284].....\SMPDU_Sock_wait
[00:3284]...../SMPDU_Sock_wait
[00:3284].....SOCK_OP_CLOSE event.error = 0, result = 0, context=undetermined
[00:3284].....\smpd_handle_op_close
[00:3284]......\smpd_get_state_string
[00:3284]....../smpd_get_state_string
[00:3284]......op_close received - SMPD_CLOSING state.
[00:3284]......Unaffiliated undetermined context closing.
[00:3284]......\smpd_free_context
[00:3284].......freeing undetermined context.
[00:3284].......\smpd_init_context
[00:3284]........\smpd_init_command
[00:3284]......../smpd_init_command
[00:3284]......./smpd_init_context
[00:3284]....../smpd_free_context
[00:3284]...../smpd_handle_op_close
[00:3284].....sock_waiting for the next event.
[00:3284].....\SMPDU_Sock_wait
[-1:1048]..../SMPDU_Sock_wait
[-1:1048]....SOCK_OP_ACCEPT event.error = 0, result = 0, context=listener
[-1:1048]....\smpd_handle_op_accept
[-1:1048].....\smpd_state_mgr_listening
[-1:1048]......\SMPDU_Sock_accept
[-1:1048]....../SMPDU_Sock_accept
[-1:1048]......accepted re-connection
[-1:1048]......\smpd_create_context
[-1:1048].......\smpd_init_context
[-1:1048]........\smpd_init_command
[-1:1048]......../smpd_init_command
[-1:1048]........\SMPDU_Sock_set_user_ptr
[-1:1048]......../SMPDU_Sock_set_user_ptr
[-1:1048]......./smpd_init_context
[-1:1048]....../smpd_create_context
[-1:1048]......\SMPDU_Sock_set_user_ptr
[-1:1048]....../SMPDU_Sock_set_user_ptr
[-1:1048]......\SMPDU_Sock_post_read
[-1:1048].......\SMPDU_Sock_post_readv
[-1:1048]......./SMPDU_Sock_post_readv
[-1:1048]....../SMPDU_Sock_post_read
[-1:1048]......closing the mgr listener.
[-1:1048]......\SMPDU_Sock_post_close
[-1:1048]....../SMPDU_Sock_post_close
[-1:1048]...../smpd_state_mgr_listening
[-1:1048]..../smpd_handle_op_accept
[-1:1048]....sock_waiting for the next event.
[-1:1048]....\SMPDU_Sock_wait
[-1:1048]..../SMPDU_Sock_wait
[-1:1048]....SOCK_OP_READ event.error = 0, result = 0, context=undetermined
[-1:1048]....\smpd_handle_op_read
[-1:1048].....\smpd_state_reading_session_header
[-1:1048]......read session header: 'id=1 parent=0 level=0'
[-1:1048]......\smpd_interpret_session_header
[-1:1048].......interpreting session header: "id=1 parent=0 level=0"
[-1:1048]....... id = 1
[01:1048]....... parent = 0
[01:1048]....... level = 0
[01:1048]....../smpd_interpret_session_header
[01:1048]......\smpd_post_read_command
[01:1048].......\SMPDU_Sock_get_sock_id
[01:1048]......./SMPDU_Sock_get_sock_id
[01:1048].......posting a read for a command header on the parent context, sock 1212
[01:1048].......\SMPDU_Sock_post_read
[01:1048]........\SMPDU_Sock_post_readv
[01:1048]......../SMPDU_Sock_post_readv
[01:1048]......./SMPDU_Sock_post_read
[01:1048]....../smpd_post_read_command
[01:1048]...../smpd_state_reading_session_header
[01:1048]..../smpd_handle_op_read
[01:1048]....sock_waiting for the next event.
[01:1048]....\SMPDU_Sock_wait
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048]..../SMPDU_Sock_wait
[01:1048]....SOCK_OP_CLOSE event.error = 0, result = 0, context=listener
[01:1048]....\smpd_handle_op_close
[01:1048].....\smpd_get_state_string
[01:1048]...../smpd_get_state_string
[01:1048].....op_close received - SMPD_MGR_LISTENING state.
[01:1048].....\smpd_free_context
[01:1048]......freeing listener context.
[01:1048]......\smpd_init_context
[01:1048].......\smpd_init_command
[01:1048]......./smpd_init_command
[01:1048]....../smpd_init_context
[01:1048]...../smpd_free_context
[01:1048]..../smpd_handle_op_close
[01:1048]....sock_waiting for the next event.
[01:1048]....\SMPDU_Sock_wait
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].....GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048]..../SMPDU_Sock_wait
[01:1048]....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]....\smpd_handle_op_read
[01:1048].....\smpd_state_reading_cmd_header
[01:1048]......read command header
[01:1048]......command header read, posting read for data: 34 bytes
[01:1048]......\SMPDU_Sock_post_read
[01:1048].......\SMPDU_Sock_post_readv
[01:1048]......./SMPDU_Sock_post_readv
[01:1048]....../SMPDU_Sock_post_read
[01:1048]...../smpd_state_reading_cmd_header
[01:1048]..../smpd_handle_op_read
[01:1048]....sock_waiting for the next event.
[01:1048]....\SMPDU_Sock_wait
[01:1048]..../SMPDU_Sock_wait
[01:1048]....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]....\smpd_handle_op_read
[01:1048].....\smpd_state_reading_cmd
[01:1048]......read command
[01:1048]......\smpd_parse_command
[01:1048]....../smpd_parse_command
[01:1048]......read command: "cmd=start_dbs src=0 dest=1 tag=0 "
[01:1048]......\smpd_handle_command
[01:1048].......handling command:
[01:1048]....... src  = 0
[01:1048]....... dest = 1
[01:1048]....... cmd  = start_dbs
[01:1048]....... tag  = 0
[01:1048]....... ctx  = parent
[01:1048]....... len  = 34
[01:1048]....... str  = cmd=start_dbs src=0 dest=1 tag=0 
[01:1048].......\smpd_command_destination
[01:1048]........1 -> 1 : returning NULL context
[01:1048]......./smpd_command_destination
[01:1048].......\smpd_handle_start_dbs_command
[01:1048]........\smpd_dbs_init
[01:1048].........\smpd_dbs_create
[01:1048]........./smpd_dbs_create
[01:1048]......../smpd_dbs_init
[01:1048]........\smpd_create_command
[01:1048].........\smpd_init_command
[01:1048]........./smpd_init_command
[01:1048]......../smpd_create_command
[01:1048]........\smpd_add_command_int_arg
[01:1048]......../smpd_add_command_int_arg
[01:1048]........\smpd_add_command_arg
[01:1048]......../smpd_add_command_arg
[01:1048]........\smpd_dbs_create
[01:1048]......../smpd_dbs_create
[01:1048]........\smpd_add_command_arg
[01:1048]......../smpd_add_command_arg
[01:1048]........\smpd_add_command_arg
[01:1048]......../smpd_add_command_arg
[01:1048]........\smpd_add_command_arg
[01:1048]......../smpd_add_command_arg
[01:1048]........sending result command to parent context: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=start_dbs kvs_name=F2E6A159-5401-4b19-8966-5CC999BFAE91 domain_name=81CDE767-7418-44b6-8DDC-C30D4216EB2B result=SUCCESS "
[01:1048]........\smpd_post_write_command
[01:1048].........\smpd_package_command
[01:1048]........./smpd_package_command
[01:1048].........\SMPDU_Sock_get_sock_id
[01:1048]........./SMPDU_Sock_get_sock_id
[01:1048].........smpd_post_write_command on the parent context sock 1212: 183 bytes for command: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=start_dbs kvs_name=F2E6A159-5401-4b19-8966-5CC999BFAE91 domain_name=81CDE767-7418-44b6-8DDC-C30D4216EB2B result=SUCCESS "
[01:1048].........\SMPDU_Sock_post_writev
[01:1048]........./SMPDU_Sock_post_writev
[01:1048]......../smpd_post_write_command
[01:1048]......./smpd_handle_start_dbs_command
[01:1048]....../smpd_handle_command
[01:1048]......\smpd_post_read_command
[01:1048].......\SMPDU_Sock_get_sock_id
[01:1048]......./SMPDU_Sock_get_sock_id
[01:1048].......posting a read for a command header on the parent context, sock 1212
[01:1048].......\SMPDU_Sock_post_read
[01:1048]........\SMPDU_Sock_post_readv
[01:1048]......../SMPDU_Sock_post_readv
[01:1048]......./SMPDU_Sock_post_read
[01:1048]....../smpd_post_read_command
[01:1048]...../smpd_state_reading_cmd
[01:1048]..../smpd_handle_op_read
[01:1048]....sock_waiting for the next event.
[01:1048]....\SMPDU_Sock_wait
[01:1048]..../SMPDU_Sock_wait
[01:1048]....SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1048]....\smpd_handle_op_write
[01:1048].....\smpd_state_writing_cmd
[01:1048]......wrote command
[01:1048]......command written to parent: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=start_dbs kvs_name=F2E6A159-5401-4b19-8966-5CC999BFAE91 domain_name=81CDE767-7418-44b6-8DDC-C30D4216EB2B result=SUCCESS "
[01:1048]......\smpd_free_command
[01:1048].......\smpd_init_command
[01:1048]......./smpd_init_command
[01:1048]....../smpd_free_command
[01:1048]...../smpd_state_writing_cmd
[01:1048]..../smpd_handle_op_write
[01:1048]....sock_waiting for the next event.
[01:1048]....\SMPDU_Sock_wait
[01:1048]..../SMPDU_Sock_wait
[01:1048]....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]....\smpd_handle_op_read
[01:1048].....\smpd_state_reading_cmd_header
[01:1048]......read command header
[01:1048]......command header read, posting read for data: 203 bytes
[01:1048]......\SMPDU_Sock_post_read
[01:1048].......\SMPDU_Sock_post_readv
[01:1048]......./SMPDU_Sock_post_readv
[01:1048]....../SMPDU_Sock_post_read
[01:1048]...../smpd_state_reading_cmd_header
[01:1048]..../smpd_handle_op_read
[01:1048]....sock_waiting for the next event.
[01:1048]....\SMPDU_Sock_wait
[01:1048]..../SMPDU_Sock_wait
[01:1048]....SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]....\smpd_handle_op_read
[01:1048].....\smpd_state_reading_cmd
[01:1048]......read command
[01:1048]......\smpd_parse_command
[01:1048]....../smpd_parse_command
[01:1048]......read command: "cmd=launch src=0 dest=1 tag=1 c=cpi s=0 a=0 d="C:\Program Files\MPICH2\bin" p="C:\Program Files\MPICH2\bin" q=0..1 i=1 n=2 k=F2E6A159-5401-4b19-8966-5CC999BFAE91 kd=81CDE767-7418-44b6-8DDC-C30D4216EB2B "
[01:1048]......\smpd_handle_command
[01:1048].......handling command:
[01:1048]....... src  = 0
[01:1048]....... dest = 1
[01:1048]....... cmd  = launch
[01:1048]....... tag  = 1
[01:1048]....... ctx  = parent
[01:1048]....... len  = 203
[01:1048]....... str  = cmd=launch src=0 dest=1 tag=1 c=cpi s=0 a=0 d="C:\Program Files\MPICH2\bin" p="C:\Program Files\MPICH2\bin" q=0..1 i=1 n=2 k=F2E6A159-5401-4b19-8966-5CC999BFAE91 kd=81CDE767-7418-44b6-8DDC-C30D4216EB2B 
[01:1048].......\smpd_command_destination
[01:1048]........1 -> 1 : returning NULL context
[01:1048]......./smpd_command_destination
[01:1048].......\smpd_handle_launch_command
[01:1048]........\smpd_create_process_struct
[01:1048].........\smpd_create_context
[01:1048]..........\smpd_init_context
[01:1048]...........\smpd_init_command
[01:1048].........../smpd_init_command
[01:1048]........../smpd_init_context
[01:1048]........./smpd_create_context
[01:1048].........\smpd_create_context
[01:1048]..........\smpd_init_context
[01:1048]...........\smpd_init_command
[01:1048].........../smpd_init_command
[01:1048]........../smpd_init_context
[01:1048]........./smpd_create_context
[01:1048].........\smpd_create_context
[01:1048]..........\smpd_init_context
[01:1048]...........\smpd_init_command
[01:1048].........../smpd_init_command
[01:1048]........../smpd_init_context
[01:1048]........./smpd_create_context
[01:1048].........\smpd_create_context
[01:1048]..........\smpd_init_context
[01:1048]...........\smpd_init_command
[01:1048].........../smpd_init_command
[01:1048]........../smpd_init_context
[01:1048]........./smpd_create_context
[01:1048]......../smpd_create_process_struct
[01:1048]........launching: 'cpi'
[01:1048]........\smpd_launch_process
[01:1048].........searching for 'cpi' in 'C:\Program Files\MPICH2\bin'
[01:1048].........\smpd_search_path
[01:1048]........./smpd_search_path
[01:1048].........\smpd_priority_class_to_win_class
[01:1048]........./smpd_priority_class_to_win_class
[01:1048].........\smpd_priority_to_win_priority
[01:1048]........./smpd_priority_to_win_priority
[01:1048].........\smpd_make_socket_loop_choose
[01:1048]..........\smpd_get_hostname
[01:1048]........../smpd_get_hostname
[01:1048]........./smpd_make_socket_loop_choose
[01:1048].........\smpd_make_socket_loop_choose
[01:1048]..........\smpd_get_hostname
[01:1048]........../smpd_get_hostname
[01:1048]........./smpd_make_socket_loop_choose
[01:1048].........\smpd_make_socket_loop_choose
[01:1048]..........\smpd_get_hostname
[01:1048]........../smpd_get_hostname
[01:1048]........./smpd_make_socket_loop_choose
[01:1048].........\SMPDU_Sock_listen
[01:1048]........./SMPDU_Sock_listen
[01:1048].........pmi listening on port 3634
[01:1048].........\smpd_create_context
[01:1048]..........\smpd_init_context
[01:1048]...........\smpd_init_command
[01:1048].........../smpd_init_command
[01:1048]...........\SMPDU_Sock_set_user_ptr
[01:1048].........../SMPDU_Sock_set_user_ptr
[01:1048]........../smpd_init_context
[01:1048]........./smpd_create_context
[01:1048].........\SMPDU_Sock_set_user_ptr
[01:1048]........./SMPDU_Sock_set_user_ptr
[01:1048]......../SMPDU_Sock_get_host_description
[01:1048]........\SMPDU_Sock_hostname_to_host_description
[01:1048].........adding host: AsusNetbook to host list
[01:1048].........adding host: 192.168.1.23 to host list
[01:1048]......../SMPDU_Sock_hostname_to_host_description
[01:1048]......./SMPDU_Sock_get_host_description
[01:1048].......\SetEnvironmentVariables
[01:1048]......./SetEnvironmentVariables
[01:1048].......env: PMI_RANK=1
[01:1048].......env: PMI_SIZE=2
[01:1048].......env: PMI_KVS=F2E6A159-5401-4b19-8966-5CC999BFAE91
[01:1048].......env: PMI_DOMAIN=81CDE767-7418-44b6-8DDC-C30D4216EB2B
[01:1048].......env: PMI_HOST=AsusNetbook 192.168.1.23 
[01:1048].......env: PMI_PORT=3634
[01:1048].......env: PMI_SMPD_ID=1
[01:1048].......env: PMI_SMPD_KEY=0
[01:1048].......env: PMI_CLIQUE=0..1
[01:1048].......env: PMI_SPAWN=0
[01:1048].......env: PMI_APPNUM=0
[01:1048].......CreateProcess(C:\Program Files\MPICH2\bin\cpi.exe)
[01:1048].......\RemoveEnvironmentVariables
[01:1048]......./RemoveEnvironmentVariables
[01:1048].......\SMPDU_Sock_native_to_sock
[01:1048]......./SMPDU_Sock_native_to_sock
[01:1048].......\SMPDU_Sock_native_to_sock
[01:1048]......./SMPDU_Sock_native_to_sock
[01:1048].......\SMPDU_Sock_native_to_sock
[01:1048]......./SMPDU_Sock_native_to_sock
[01:1048].......\SMPDU_Sock_set_user_ptr
[01:1048]......./SMPDU_Sock_set_user_ptr
[01:1048].......\SMPDU_Sock_set_user_ptr
[01:1048]......./SMPDU_Sock_set_user_ptr
[01:1048].......\SMPDU_Sock_set_user_ptr
[01:1048]......./SMPDU_Sock_set_user_ptr
[01:1048].......*** entering smpd_pinthread pid:3188 sock:1180 ***
[01:1048].......\SMPDU_Sock_post_read
[01:1048]........*** entering smpd_piothread pid:3188 sock:1168 ***
[01:1048]........*** entering smpd_piothread pid:3188 sock:1144 ***
[01:1048]........\SMPDU_Sock_post_readv
[01:1048]......../SMPDU_Sock_post_readv
[01:1048]......./SMPDU_Sock_post_read
[01:1048].......\SMPDU_Sock_post_read
[01:1048]........\SMPDU_Sock_post_readv
[01:1048]......../SMPDU_Sock_post_readv
[01:1048]......./SMPDU_Sock_post_read
[01:1048].......\smpd_process_to_registry
[01:1048]......./smpd_process_to_registry
[01:1048]....../smpd_launch_process
[01:1048]......\smpd_create_command
[01:1048].......\smpd_init_command
[01:1048]......./smpd_init_command
[01:1048]....../smpd_create_command
[01:1048]......\smpd_add_command_int_arg
[01:1048]....../smpd_add_command_int_arg
[01:1048]......\smpd_add_command_arg
[01:1048]....../smpd_add_command_arg
[01:1048]......\smpd_add_command_arg
[01:1048]....../smpd_add_command_arg
[01:1048]......\smpd_add_command_arg
[01:1048]....../smpd_add_command_arg
[01:1048]......\smpd_add_command_int_arg
[01:1048]....../smpd_add_command_int_arg
[01:1048]......\smpd_add_command_int_arg
[01:1048]....../smpd_add_command_int_arg
[01:1048]......\smpd_post_write_command
[01:1048].......\smpd_package_command
[01:1048]......./smpd_package_command
[01:1048].......\SMPDU_Sock_get_sock_id
[01:1048]......./SMPDU_Sock_get_sock_id
[01:1048].......smpd_post_write_command on the parent context sock 1212: 147 bytes for command: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=launch result=SUCCESS pg_id=F2E6A159-5401-4b19-8966-5CC999BFAE91 pg_rank=1 pg_ctx=0 "
[01:1048].......\SMPDU_Sock_post_writev
[01:1048]......./SMPDU_Sock_post_writev
[01:1048]....../smpd_post_write_command
[01:1048]...../smpd_handle_launch_command
[01:1048]..../smpd_handle_command
[01:1048]....\smpd_post_read_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....posting a read for a command header on the parent context, sock 1212
[01:1048].....\SMPDU_Sock_post_read
[01:1048]......\SMPDU_Sock_post_readv
[01:1048]....../SMPDU_Sock_post_readv
[01:1048]...../SMPDU_Sock_post_read
[01:1048]..../smpd_post_read_command
[01:1048].../smpd_state_reading_cmd
[01:1048]../smpd_handle_op_read
[01:1048]..sock_waiting for the next event.
[01:1048]..\SMPDU_Sock_wait
[01:1048]../SMPDU_Sock_wait
[01:1048]..SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1048]..\smpd_handle_op_write
[01:1048]...\smpd_state_writing_cmd
[01:1048]....wrote command
[01:1048]....command written to parent: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=launch result=SUCCESS pg_id=F2E6A159-5401-4b19-8966-5CC999BFAE91 pg_rank=1 pg_ctx=0 "
[01:1048]....\smpd_free_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_free_command
[01:1048].../smpd_state_writing_cmd
[01:1048]../smpd_handle_op_write
[01:1048]..sock_waiting for the next event.
[01:1048]..\SMPDU_Sock_wait
[01:1048]../SMPDU_Sock_wait
[01:1048]..SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]..\smpd_handle_op_read
[01:1048]...\smpd_state_reading_cmd_header
[01:1048]....read command header
[01:1048]....command header read, posting read for data: 203 bytes
[01:1048]....\SMPDU_Sock_post_read
[01:1048].....\SMPDU_Sock_post_readv
[01:1048]...../SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_read
[01:1048].../smpd_state_reading_cmd_header
[01:1048]../smpd_handle_op_read
[01:1048]..sock_waiting for the next event.
[01:1048]..\SMPDU_Sock_wait
[01:1048]../SMPDU_Sock_wait
[01:1048]..SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]..\smpd_handle_op_read
[01:1048]...\smpd_state_reading_cmd
[01:1048]....read command
[01:1048]....\smpd_parse_command
[01:1048]..../smpd_parse_command
[01:1048]....read command: "cmd=launch src=0 dest=1 tag=2 c=cpi s=0 a=0 d="C:\Program Files\MPICH2\bin" p="C:\Program Files\MPICH2\bin" q=0..1 i=0 n=2 k=F2E6A159-5401-4b19-8966-5CC999BFAE91 kd=81CDE767-7418-44b6-8DDC-C30D4216EB2B "
[01:1048]....\smpd_handle_command
[01:1048].....handling command:
[01:1048]..... src  = 0
[01:1048]..... dest = 1
[01:1048]..... cmd  = launch
[01:1048]..... tag  = 2
[01:1048]..... ctx  = parent
[01:1048]..... len  = 203
[01:1048]..... str  = cmd=launch src=0 dest=1 tag=2 c=cpi s=0 a=0 d="C:\Program Files\MPICH2\bin" p="C:\Program Files\MPICH2\bin" q=0..1 i=0 n=2 k=F2E6A159-5401-4b19-8966-5CC999BFAE91 kd=81CDE767-7418-44b6-8DDC-C30D4216EB2B 
[01:1048].....\smpd_command_destination
[01:1048]......1 -> 1 : returning NULL context
[01:1048]...../smpd_command_destination
[01:1048].....\smpd_handle_launch_command
[01:1048]......\smpd_create_process_struct
[01:1048].......\smpd_create_context
[01:1048]........\smpd_init_context
[01:1048].........\smpd_init_command
[01:1048]........./smpd_init_command
[01:1048]......../smpd_init_context
[01:1048]......./smpd_create_context
[01:1048].......\smpd_create_context
[01:1048]........\smpd_init_context
[01:1048].........\smpd_init_command
[01:1048]........./smpd_init_command
[01:1048]......../smpd_init_context
[01:1048]......./smpd_create_context
[01:1048].......\smpd_create_context
[01:1048]........\smpd_init_context
[01:1048].........\smpd_init_command
[01:1048]........./smpd_init_command
[01:1048]......../smpd_init_context
[01:1048]......./smpd_create_context
[01:1048].......\smpd_create_context
[01:1048]........\smpd_init_context
[01:1048].........\smpd_init_command
[01:1048]........./smpd_init_command
[01:1048]......../smpd_init_context
[01:1048]......./smpd_create_context
[01:1048]....../smpd_create_process_struct
[01:1048]......launching: 'cpi'
[01:1048]......\smpd_launch_process
[01:1048].......searching for 'cpi' in 'C:\Program Files\MPICH2\bin'
[01:1048].......\smpd_search_path
[01:1048]......./smpd_search_path
[01:1048].......\smpd_priority_class_to_win_class
[01:1048]......./smpd_priority_class_to_win_class
[01:1048].......\smpd_priority_to_win_priority
[01:1048]......./smpd_priority_to_win_priority
[01:1048].......\smpd_make_socket_loop_choose
[01:1048]........\smpd_get_hostname
[01:1048]......../smpd_get_hostname
[01:1048]......./smpd_make_socket_loop_choose
[01:1048].......\smpd_make_socket_loop_choose
[01:1048]........\smpd_get_hostname
[01:1048]......../smpd_get_hostname
[01:1048]......./smpd_make_socket_loop_choose
[01:1048].......\smpd_make_socket_loop_choose
[01:1048]........\smpd_get_hostname
[01:1048]......../smpd_get_hostname
[01:1048]......./smpd_make_socket_loop_choose
[01:1048].......\SMPDU_Sock_listen
[01:1048]......./SMPDU_Sock_listen
[01:1048].......pmi listening on port 3641
[01:1048].......\smpd_create_context
[01:1048]........\smpd_init_context
[01:1048].........\smpd_init_command
[01:1048]........./smpd_init_command
[01:1048].........\SMPDU_Sock_set_user_ptr
[01:1048]........./SMPDU_Sock_set_user_ptr
[01:1048]......../smpd_init_context
[01:1048]......./smpd_create_context
[01:1048].......\SMPDU_Sock_set_user_ptr
[01:1048]......./SMPDU_Sock_set_user_ptr
[01:1048]....../SMPDU_Sock_get_host_description
[01:1048]......\SMPDU_Sock_hostname_to_host_description
[01:1048].......adding host: AsusNetbook to host list
[01:1048].......adding host: 192.168.1.23 to host list
[01:1048]....../SMPDU_Sock_hostname_to_host_description
[01:1048]...../SMPDU_Sock_get_host_description
[01:1048].....\SetEnvironmentVariables
[01:1048]...../SetEnvironmentVariables
[01:1048].....env: PMI_RANK=0
[01:1048].....env: PMI_SIZE=2
[01:1048].....env: PMI_KVS=F2E6A159-5401-4b19-8966-5CC999BFAE91
[01:1048].....env: PMI_DOMAIN=81CDE767-7418-44b6-8DDC-C30D4216EB2B
[01:1048].....env: PMI_HOST=AsusNetbook 192.168.1.23 
[01:1048].....env: PMI_PORT=3641
[01:1048].....env: PMI_SMPD_ID=1
[01:1048].....env: PMI_SMPD_KEY=1
[01:1048].....env: PMI_CLIQUE=0..1
[01:1048].....env: PMI_SPAWN=0
[01:1048].....env: PMI_APPNUM=0
[01:1048].....CreateProcess(C:\Program Files\MPICH2\bin\cpi.exe)
[01:1048].....\RemoveEnvironmentVariables
[01:1048]...../RemoveEnvironmentVariables
[01:1048].....\SMPDU_Sock_native_to_sock
[01:1048]...../SMPDU_Sock_native_to_sock
[01:1048].....\SMPDU_Sock_native_to_sock
[01:1048]...../SMPDU_Sock_native_to_sock
[01:1048].....\SMPDU_Sock_native_to_sock
[01:1048]...../SMPDU_Sock_native_to_sock
[01:1048].....\SMPDU_Sock_set_user_ptr
[01:1048]...../SMPDU_Sock_set_user_ptr
[01:1048].....\SMPDU_Sock_set_user_ptr
[01:1048]...../SMPDU_Sock_set_user_ptr
[01:1048].....\SMPDU_Sock_set_user_ptr
[01:1048]...../SMPDU_Sock_set_user_ptr
[01:1048].....*** entering smpd_pinthread pid:3912 sock:908 ***
[01:1048].....\SMPDU_Sock_post_read
[01:1048]......*** entering smpd_piothread pid:3912 sock:896 ***
[01:1048]......*** entering smpd_piothread pid:3912 sock:872 ***
[01:1048]......\SMPDU_Sock_post_readv
[01:1048]....../SMPDU_Sock_post_readv
[01:1048]...../SMPDU_Sock_post_read
[01:1048].....\SMPDU_Sock_post_read
[01:1048]......\SMPDU_Sock_post_readv
[01:1048]....../SMPDU_Sock_post_readv
[01:1048]...../SMPDU_Sock_post_read
[01:1048].....\smpd_process_to_registry
[01:1048]...../smpd_process_to_registry
[01:1048]..../smpd_launch_process
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the parent context sock 1212: 147 bytes for command: "cmd=result src=1 dest=0 tag=2 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=F2E6A159-5401-4b19-8966-5CC999BFAE91 pg_rank=0 pg_ctx=1 "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_handle_launch_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the parent context, sock 1212
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_ACCEPT event.error = 0, result = 0, context=PMI_LISTENER
[01:1048]\smpd_handle_op_accept
[01:1048].\smpd_state_pmi_listening
[01:1048]..\SMPDU_Sock_accept
[01:1048]../SMPDU_Sock_accept
[01:1048]..\SMPDU_Sock_post_close
[01:1048]../SMPDU_Sock_post_close
[01:1048]..\SMPDU_Sock_set_user_ptr
[01:1048]../SMPDU_Sock_set_user_ptr
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 988
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_pmi_listening
[01:1048]/smpd_handle_op_accept
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to parent: "cmd=result src=1 dest=0 tag=2 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=F2E6A159-5401-4b19-8966-5CC999BFAE91 pg_rank=0 pg_ctx=1 "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_CLOSE event.error = 0, result = 0, context=PMI_LISTENER
[01:1048]\smpd_handle_op_close
[01:1048].\smpd_get_state_string
[01:1048]./smpd_get_state_string
[01:1048].op_close received - SMPD_CLOSING state.
[01:1048].Unaffiliated PMI_LISTENER context closing.
[01:1048].\smpd_free_context
[01:1048]..freeing PMI_LISTENER context.
[01:1048]..\smpd_init_context
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_init_context
[01:1048]./smpd_free_context
[01:1048]/smpd_handle_op_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 105 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=1 value=2 node_id=1 "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 0
[01:1048]... cmd  = init
[01:1048]... tag  = 0
[01:1048]... ctx  = pmi
[01:1048]... len  = 105
[01:1048]... str  = cmd=init src=1 dest=0 tag=0 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=1 value=2 node_id=1 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 0 : returning parent_context: 0 < 1
[01:1048].../smpd_command_destination
[01:1048]...forwarding command to 0
[01:1048]...\smpd_forward_command
[01:1048]....\smpd_create_command_copy
[01:1048]..../smpd_create_command_copy
[01:1048]....posting write of forwarded command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=1 value=2 node_id=1 "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the parent context sock 1212: 118 bytes for command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=1 value=2 node_id=1 "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_forward_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 988
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to parent: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=1 value=2 node_id=1 "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 66 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=result src=0 dest=1 tag=3 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 0
[01:1048]... dest = 1
[01:1048]... cmd  = result
[01:1048]... tag  = 3
[01:1048]... ctx  = parent
[01:1048]... len  = 66
[01:1048]... str  = cmd=result src=0 dest=1 tag=3 cmd_tag=0 ctx_key=0 result=SUCCESS 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_result
[01:1048]....forwarding the dbs result command to the pmi context 0.
[01:1048]....\smpd_forward_command
[01:1048].....\smpd_create_command_copy
[01:1048]...../smpd_create_command_copy
[01:1048].....posting write of forwarded command: "cmd=result src=0 dest=1 tag=3 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:1048].....\smpd_post_write_command
[01:1048]......\smpd_package_command
[01:1048]....../smpd_package_command
[01:1048]......\SMPDU_Sock_get_sock_id
[01:1048]....../SMPDU_Sock_get_sock_id
[01:1048]......smpd_post_write_command on the pmi context sock 988: 79 bytes for command: "cmd=result src=0 dest=1 tag=3 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:1048]......\SMPDU_Sock_post_writev
[01:1048]....../SMPDU_Sock_post_writev
[01:1048]...../smpd_post_write_command
[01:1048]..../smpd_forward_command
[01:1048].../smpd_handle_result
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the parent context, sock 1212
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to pmi: "cmd=result src=0 dest=1 tag=3 cmd_tag=0 ctx_key=0 result=SUCCESS "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 106 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=dbget src=1 dest=1 tag=1 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=PMI_process_mapping "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 1
[01:1048]... cmd  = dbget
[01:1048]... tag  = 1
[01:1048]... ctx  = pmi
[01:1048]... len  = 106
[01:1048]... str  = cmd=dbget src=1 dest=1 tag=1 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=PMI_process_mapping 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_dbs_command
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\get_name_key_value
[01:1048]..../get_name_key_value
[01:1048]....\smpd_dbs_get
[01:1048]..../smpd_dbs_get
[01:1048]....sending reply to dbs command 'cmd=dbget src=1 dest=1 tag=1 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=PMI_process_mapping '.
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending result command to pmi context: "cmd=result src=1 dest=1 tag=3 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the pmi context sock 988: 95 bytes for command: "cmd=result src=1 dest=1 tag=3 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_handle_dbs_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 988
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to pmi: "cmd=result src=1 dest=1 tag=3 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 116 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=dbput src=1 dest=1 tag=2 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=hostname[1] value=AsusNetbook "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 1
[01:1048]... cmd  = dbput
[01:1048]... tag  = 2
[01:1048]... ctx  = pmi
[01:1048]... len  = 116
[01:1048]... str  = cmd=dbput src=1 dest=1 tag=2 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=hostname[1] value=AsusNetbook 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_dbs_command
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\get_name_key_value
[01:1048]..../get_name_key_value
[01:1048]....\smpd_dbs_put
[01:1048]..../smpd_dbs_put
[01:1048]....sending reply to dbs command 'cmd=dbput src=1 dest=1 tag=2 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=hostname[1] value=AsusNetbook '.
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending result command to pmi context: "cmd=result src=1 dest=1 tag=4 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the pmi context sock 988: 98 bytes for command: "cmd=result src=1 dest=1 tag=4 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_handle_dbs_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 988
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to pmi: "cmd=result src=1 dest=1 tag=4 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_ACCEPT event.error = 0, result = 0, context=PMI_LISTENER
[01:1048]\smpd_handle_op_accept
[01:1048].\smpd_state_pmi_listening
[01:1048]..\SMPDU_Sock_accept
[01:1048]../SMPDU_Sock_accept
[01:1048]..\SMPDU_Sock_post_close
[01:1048]../SMPDU_Sock_post_close
[01:1048]..\SMPDU_Sock_set_user_ptr
[01:1048]../SMPDU_Sock_set_user_ptr
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 716
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_pmi_listening
[01:1048]/smpd_handle_op_accept
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 92 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_CLOSE event.error = 0, result = 0, context=PMI_LISTENER
[01:1048]\smpd_handle_op_close
[01:1048].\smpd_get_state_string
[01:1048]./smpd_get_state_string
[01:1048].op_close received - SMPD_CLOSING state.
[01:1048].Unaffiliated PMI_LISTENER context closing.
[01:1048].\smpd_free_context
[01:1048]..freeing PMI_LISTENER context.
[01:1048]..\smpd_init_context
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_init_context
[01:1048]./smpd_free_context
[01:1048]/smpd_handle_op_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048].GetQueuedCompletionStatus failed, GetLastError: 995
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 105 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=barrier src=1 dest=1 tag=3 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 value=2 "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 1
[01:1048]... cmd  = barrier
[01:1048]... tag  = 3
[01:1048]... ctx  = pmi
[01:1048]... len  = 92
[01:1048]... str  = cmd=barrier src=1 dest=1 tag=3 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 value=2 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_barrier_command
[01:1048]....initializing barrier(F2E6A159-5401-4b19-8966-5CC999BFAE91): in=1 size=2
[01:1048].../smpd_handle_barrier_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 988
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=init src=1 dest=0 tag=0 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=0 value=2 node_id=1 "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 0
[01:1048]... cmd  = init
[01:1048]... tag  = 0
[01:1048]... ctx  = pmi
[01:1048]... len  = 105
[01:1048]... str  = cmd=init src=1 dest=0 tag=0 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=0 value=2 node_id=1 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 0 : returning parent_context: 0 < 1
[01:1048].../smpd_command_destination
[01:1048]...forwarding command to 0
[01:1048]...\smpd_forward_command
[01:1048]....\smpd_create_command_copy
[01:1048]..../smpd_create_command_copy
[01:1048]....posting write of forwarded command: "cmd=init src=1 dest=0 tag=0 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=0 value=2 node_id=1 "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the parent context sock 1212: 118 bytes for command: "cmd=init src=1 dest=0 tag=0 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=0 value=2 node_id=1 "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_forward_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 716
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to parent: "cmd=init src=1 dest=0 tag=0 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=0 value=2 node_id=1 "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 66 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=1 result=SUCCESS "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 0
[01:1048]... dest = 1
[01:1048]... cmd  = result
[01:1048]... tag  = 4
[01:1048]... ctx  = parent
[01:1048]... len  = 66
[01:1048]... str  = cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=1 result=SUCCESS 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_result
[01:1048]....forwarding the dbs result command to the pmi context 1.
[01:1048]....\smpd_forward_command
[01:1048].....\smpd_create_command_copy
[01:1048]...../smpd_create_command_copy
[01:1048].....posting write of forwarded command: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=1 result=SUCCESS "
[01:1048].....\smpd_post_write_command
[01:1048]......\smpd_package_command
[01:1048]....../smpd_package_command
[01:1048]......\SMPDU_Sock_get_sock_id
[01:1048]....../SMPDU_Sock_get_sock_id
[01:1048]......smpd_post_write_command on the pmi context sock 716: 79 bytes for command: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=1 result=SUCCESS "
[01:1048]......\SMPDU_Sock_post_writev
[01:1048]....../SMPDU_Sock_post_writev
[01:1048]...../smpd_post_write_command
[01:1048]..../smpd_forward_command
[01:1048].../smpd_handle_result
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the parent context, sock 1212
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to pmi: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=1 result=SUCCESS "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 106 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=dbget src=1 dest=1 tag=1 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=PMI_process_mapping "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 1
[01:1048]... cmd  = dbget
[01:1048]... tag  = 1
[01:1048]... ctx  = pmi
[01:1048]... len  = 106
[01:1048]... str  = cmd=dbget src=1 dest=1 tag=1 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=PMI_process_mapping 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_dbs_command
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\get_name_key_value
[01:1048]..../get_name_key_value
[01:1048]....\smpd_dbs_get
[01:1048]..../smpd_dbs_get
[01:1048]....sending reply to dbs command 'cmd=dbget src=1 dest=1 tag=1 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=PMI_process_mapping '.
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending result command to pmi context: "cmd=result src=1 dest=1 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=1 result=DBS_FAIL "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the pmi context sock 716: 95 bytes for command: "cmd=result src=1 dest=1 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=1 result=DBS_FAIL "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_handle_dbs_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 716
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to pmi: "cmd=result src=1 dest=1 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=1 result=DBS_FAIL "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 116 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=dbput src=1 dest=1 tag=2 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=hostname[0] value=AsusNetbook "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 1
[01:1048]... cmd  = dbput
[01:1048]... tag  = 2
[01:1048]... ctx  = pmi
[01:1048]... len  = 116
[01:1048]... str  = cmd=dbput src=1 dest=1 tag=2 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=hostname[0] value=AsusNetbook 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_dbs_command
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\get_name_key_value
[01:1048]..../get_name_key_value
[01:1048]....\smpd_dbs_put
[01:1048]..../smpd_dbs_put
[01:1048]....sending reply to dbs command 'cmd=dbput src=1 dest=1 tag=2 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=hostname[0] value=AsusNetbook '.
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending result command to pmi context: "cmd=result src=1 dest=1 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=1 result=DBS_SUCCESS "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the pmi context sock 716: 98 bytes for command: "cmd=result src=1 dest=1 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=1 result=DBS_SUCCESS "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_handle_dbs_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 716
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to pmi: "cmd=result src=1 dest=1 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=1 result=DBS_SUCCESS "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 92 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=barrier src=1 dest=1 tag=3 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 value=2 "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 1
[01:1048]... cmd  = barrier
[01:1048]... tag  = 3
[01:1048]... ctx  = pmi
[01:1048]... len  = 92
[01:1048]... str  = cmd=barrier src=1 dest=1 tag=3 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 value=2 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_barrier_command
[01:1048]....incrementing barrier(F2E6A159-5401-4b19-8966-5CC999BFAE91) incount from 1 to 2 out of 2
[01:1048]....all in barrier, sending result back to all participators.
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending reply to barrier command 'F2E6A159-5401-4b19-8966-5CC999BFAE91'.
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending result command to pmi context: "cmd=result src=1 dest=1 tag=7 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the pmi context sock 988: 100 bytes for command: "cmd=result src=1 dest=1 tag=7 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending reply to barrier command 'F2E6A159-5401-4b19-8966-5CC999BFAE91'.
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending result command to pmi context: "cmd=result src=1 dest=1 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=1 result=DBS_SUCCESS "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the pmi context sock 716: 100 bytes for command: "cmd=result src=1 dest=1 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=1 result=DBS_SUCCESS "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_handle_barrier_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 716
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to pmi: "cmd=result src=1 dest=1 tag=7 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 98 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to pmi: "cmd=result src=1 dest=1 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=1 result=DBS_SUCCESS "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 98 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=dbget src=1 dest=1 tag=4 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=hostname[0] "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 1
[01:1048]... cmd  = dbget
[01:1048]... tag  = 4
[01:1048]... ctx  = pmi
[01:1048]... len  = 98
[01:1048]... str  = cmd=dbget src=1 dest=1 tag=4 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=hostname[0] 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_dbs_command
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\get_name_key_value
[01:1048]..../get_name_key_value
[01:1048]....\smpd_dbs_get
[01:1048]..../smpd_dbs_get
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending reply to dbs command 'cmd=dbget src=1 dest=1 tag=4 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=hostname[0] '.
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending result command to pmi context: "cmd=result src=1 dest=1 tag=9 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=AsusNetbook result=DBS_SUCCESS "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the pmi context sock 988: 116 bytes for command: "cmd=result src=1 dest=1 tag=9 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=AsusNetbook result=DBS_SUCCESS "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_handle_dbs_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 988
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=dbget src=1 dest=1 tag=4 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=hostname[1] "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 1
[01:1048]... cmd  = dbget
[01:1048]... tag  = 4
[01:1048]... ctx  = pmi
[01:1048]... len  = 98
[01:1048]... str  = cmd=dbget src=1 dest=1 tag=4 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=hostname[1] 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_dbs_command
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\get_name_key_value
[01:1048]..../get_name_key_value
[01:1048]....\smpd_dbs_get
[01:1048]..../smpd_dbs_get
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending reply to dbs command 'cmd=dbget src=1 dest=1 tag=4 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=hostname[1] '.
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending result command to pmi context: "cmd=result src=1 dest=1 tag=10 cmd_tag=4 cmd_orig=dbget ctx_key=1 value=AsusNetbook result=DBS_SUCCESS "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the pmi context sock 716: 117 bytes for command: "cmd=result src=1 dest=1 tag=10 cmd_tag=4 cmd_orig=dbget ctx_key=1 value=AsusNetbook result=DBS_SUCCESS "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_handle_dbs_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 716
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to pmi: "cmd=result src=1 dest=1 tag=9 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=AsusNetbook result=DBS_SUCCESS "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to pmi: "cmd=result src=1 dest=1 tag=10 cmd_tag=4 cmd_orig=dbget ctx_key=1 value=AsusNetbook result=DBS_SUCCESS "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 165 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=dbput src=1 dest=1 tag=5 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=P1-businesscard value="port=3646 description=AsusNetbook ifname=192.168.1.23 " "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 1
[01:1048]... cmd  = dbput
[01:1048]... tag  = 5
[01:1048]... ctx  = pmi
[01:1048]... len  = 165
[01:1048]... str  = cmd=dbput src=1 dest=1 tag=5 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=P1-businesscard value="port=3646 description=AsusNetbook ifname=192.168.1.23 " 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_dbs_command
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\get_name_key_value
[01:1048]..../get_name_key_value
[01:1048]....\smpd_dbs_put
[01:1048]..../smpd_dbs_put
[01:1048]....sending reply to dbs command 'cmd=dbput src=1 dest=1 tag=5 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=P1-businesscard value="port=3646 description=AsusNetbook ifname=192.168.1.23 " '.
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending result command to pmi context: "cmd=result src=1 dest=1 tag=11 cmd_tag=5 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the pmi context sock 988: 99 bytes for command: "cmd=result src=1 dest=1 tag=11 cmd_tag=5 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_handle_dbs_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 988
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to pmi: "cmd=result src=1 dest=1 tag=11 cmd_tag=5 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 92 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 165 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=barrier src=1 dest=1 tag=6 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 value=2 "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 1
[01:1048]... cmd  = barrier
[01:1048]... tag  = 6
[01:1048]... ctx  = pmi
[01:1048]... len  = 92
[01:1048]... str  = cmd=barrier src=1 dest=1 tag=6 ctx_key=0 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 value=2 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_barrier_command
[01:1048]....initializing barrier(F2E6A159-5401-4b19-8966-5CC999BFAE91): in=1 size=2
[01:1048].../smpd_handle_barrier_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 988
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=dbput src=1 dest=1 tag=5 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=P0-businesscard value="port=3647 description=AsusNetbook ifname=192.168.1.23 " "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 1
[01:1048]... cmd  = dbput
[01:1048]... tag  = 5
[01:1048]... ctx  = pmi
[01:1048]... len  = 165
[01:1048]... str  = cmd=dbput src=1 dest=1 tag=5 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=P0-businesscard value="port=3647 description=AsusNetbook ifname=192.168.1.23 " 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_dbs_command
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\get_name_key_value
[01:1048]..../get_name_key_value
[01:1048]....\smpd_dbs_put
[01:1048]..../smpd_dbs_put
[01:1048]....sending reply to dbs command 'cmd=dbput src=1 dest=1 tag=5 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 key=P0-businesscard value="port=3647 description=AsusNetbook ifname=192.168.1.23 " '.
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending result command to pmi context: "cmd=result src=1 dest=1 tag=12 cmd_tag=5 cmd_orig=dbput ctx_key=1 result=DBS_SUCCESS "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the pmi context sock 716: 99 bytes for command: "cmd=result src=1 dest=1 tag=12 cmd_tag=5 cmd_orig=dbput ctx_key=1 result=DBS_SUCCESS "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_handle_dbs_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 716
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../smpd_post_read_command
[01:1048]./smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048].\smpd_state_writing_cmd
[01:1048]..wrote command
[01:1048]..command written to pmi: "cmd=result src=1 dest=1 tag=12 cmd_tag=5 cmd_orig=dbput ctx_key=1 result=DBS_SUCCESS "
[01:1048]..\smpd_free_command
[01:1048]...\smpd_init_command
[01:1048].../smpd_init_command
[01:1048]../smpd_free_command
[01:1048]./smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd_header
[01:1048]..read command header
[01:1048]..command header read, posting read for data: 92 bytes
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048].\smpd_state_reading_cmd
[01:1048]..read command
[01:1048]..\smpd_parse_command
[01:1048]../smpd_parse_command
[01:1048]..read command: "cmd=barrier src=1 dest=1 tag=6 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 value=2 "
[01:1048]..\smpd_handle_command
[01:1048]...handling command:
[01:1048]... src  = 1
[01:1048]... dest = 1
[01:1048]... cmd  = barrier
[01:1048]... tag  = 6
[01:1048]... ctx  = pmi
[01:1048]... len  = 92
[01:1048]... str  = cmd=barrier src=1 dest=1 tag=6 ctx_key=1 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 value=2 
[01:1048]...\smpd_command_destination
[01:1048]....1 -> 1 : returning NULL context
[01:1048].../smpd_command_destination
[01:1048]...\smpd_handle_barrier_command
[01:1048]....incrementing barrier(F2E6A159-5401-4b19-8966-5CC999BFAE91) incount from 1 to 2 out of 2
[01:1048]....all in barrier, sending result back to all participators.
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending reply to barrier command 'F2E6A159-5401-4b19-8966-5CC999BFAE91'.
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending result command to pmi context: "cmd=result src=1 dest=1 tag=13 cmd_tag=6 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the pmi context sock 988: 101 bytes for command: "cmd=result src=1 dest=1 tag=13 cmd_tag=6 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048]....\smpd_create_command
[01:1048].....\smpd_init_command
[01:1048]...../smpd_init_command
[01:1048]..../smpd_create_command
[01:1048]....\smpd_add_command_int_arg
[01:1048]..../smpd_add_command_int_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending reply to barrier command 'F2E6A159-5401-4b19-8966-5CC999BFAE91'.
[01:1048]....\smpd_add_command_arg
[01:1048]..../smpd_add_command_arg
[01:1048]....sending result command to pmi context: "cmd=result src=1 dest=1 tag=14 cmd_tag=6 cmd_orig=barrier ctx_key=1 result=DBS_SUCCESS "
[01:1048]....\smpd_post_write_command
[01:1048].....\smpd_package_command
[01:1048]...../smpd_package_command
[01:1048].....\SMPDU_Sock_get_sock_id
[01:1048]...../SMPDU_Sock_get_sock_id
[01:1048].....smpd_post_write_command on the pmi context sock 716: 101 bytes for command: "cmd=result src=1 dest=1 tag=14 cmd_tag=6 cmd_orig=barrier ctx_key=1 result=DBS_SUCCESS "
[01:1048].....\SMPDU_Sock_post_writev
[01:1048]...../SMPDU_Sock_post_writev
[01:1048]..../smpd_post_write_command
[01:1048].../smpd_handle_barrier_command
[01:1048]../smpd_handle_command
[01:1048]..\smpd_post_read_command
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...posting a read for a command header on the pmi context, sock 716
[01:1048]../smpd_easy_send
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]./smpd_easy_send
[01:1048]./SMPDU_Sock_post_read
[01:1048]/smpd_post_read_command
[01:1048]/smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048]\smpd_state_writing_cmd
[01:1048]wrote command
[01:1048]command written to pmi: "cmd=result src=1 dest=1 tag=13 cmd_tag=6 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[01:1048]\smpd_free_command
[01:1048].\smpd_init_command
[01:1048]./smpd_init_command
[01:1048]/smpd_free_command
[01:1048]/smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_write
[01:1048]\smpd_state_writing_cmd
[01:1048]wrote command
[01:1048]command written to pmi: "cmd=result src=1 dest=1 tag=14 cmd_tag=6 cmd_orig=barrier ctx_key=1 result=DBS_SUCCESS "
[01:1048]\smpd_free_command
[01:1048].\smpd_init_command
[01:1048]./smpd_init_command
[01:1048]/smpd_free_command
[01:1048]/smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=stdout
[01:1048]\smpd_handle_op_read
[01:1048]\smpd_state_reading_stdouterr
[01:1048]read from stdout
[01:1048]\SMPDU_Sock_read
[01:1048].\SMPDU_Sock_readv
[01:1048]./SMPDU_Sock_readv
[01:1048]/SMPDU_Sock_read
[01:1048]41 bytes read from stdout
[01:1048]\smpd_create_command
[01:1048].\smpd_init_command
[01:1048]./smpd_init_command
[01:1048]/smpd_create_command
[01:1048]\smpd_add_command_int_arg
[01:1048]/smpd_add_command_int_arg
[01:1048]\smpd_add_command_int_arg
[01:1048]/smpd_add_command_int_arg
[01:1048]\smpd_add_command_arg
[01:1048]/smpd_add_command_arg
[01:1048]\smpd_post_write_command
[01:1048].\smpd_package_command
[01:1048]./smpd_package_command
[01:1048].\SMPDU_Sock_get_sock_id
[01:1048]./SMPDU_Sock_get_sock_id
[01:1048].smpd_post_write_command on the parent context sock 1212: 148 bytes for command: "cmd=stdout src=1 dest=0 tag=15 rank=0 first=1 data=456E74657220746865206E756D626572206F6620696E74657276616C733A2028302071756974732920 "
[01:1048].\SMPDU_Sock_post_writev
[01:1048]./SMPDU_Sock_post_writev
[01:1048]/smpd_post_write_command
[01:1048]\SMPDU_Sock_post_read
[01:1048].\SMPDU_Sock_post_readv
[01:1048]./SMPDU_Sock_post_readv
[01:1048]/SMPDU_Sock_post_read
[01:1048]/smpd_state_reading_stdouterr
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_write
[01:1048]\smpd_state_writing_cmd
[01:1048]wrote command
[01:1048]command written to parent: "cmd=stdout src=1 dest=0 tag=15 rank=0 first=1 data=456E74657220746865206E756D626572206F6620696E74657276616C733A2028302071756974732920 "
[01:1048]\smpd_free_command
[01:1048].\smpd_init_command
[01:1048]./smpd_init_command
[01:1048]/smpd_free_command
[01:1048]/smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]\smpd_kill_all_processes
[01:1048]\smpd_process_from_registry
[01:1048]/smpd_process_from_registry
[01:1048]\smpd_process_from_registry
[01:1048]GetQueuedCompletionStatus failed, GetLastError: 64
[01:1048]ReadFile failed, error 109
[01:1048]ReadFile failed, error 109
[01:1048]/smpd_process_from_registry
[01:1048]*** smpd_piothread finishing pid:3912 ***
[01:1048]*** smpd_piothread finishing pid:3912 ***
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = -1, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048]\smpd_state_reading_cmd_header
[01:1048].ERROR:unable to read the cmd header on the pmi context, Error = -1
.
[01:1048]/smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]SOCK_OP_READ failed - result = -1, closing pmi context.
[01:1048]\SMPDU_Sock_post_close
[01:1048]\SMPDU_Sock_post_read
[01:1048].\SMPDU_Sock_post_readv
[01:1048]..ERROR:Error posting readv, An existing connection was forcibly closed by the remote host.(10054)
[01:1048]./SMPDU_Sock_post_readv
[01:1048]/SMPDU_Sock_post_read
[01:1048]/SMPDU_Sock_post_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = -1, result = 0, context=stdout
[01:1048]\smpd_handle_op_read
[01:1048]\smpd_state_reading_stdouterr
[01:1048]/smpd_state_reading_stdouterr
[01:1048]/smpd_handle_op_read
[01:1048]SOCK_OP_READ failed - result = -1, closing stdout context.
[01:1048]\SMPDU_Sock_post_close
[01:1048]\SMPDU_Sock_post_read
[01:1048].\SMPDU_Sock_post_readv
[01:1048]./SMPDU_Sock_post_readv
[01:1048]/SMPDU_Sock_post_read
[01:1048]/SMPDU_Sock_post_close
[01:1048]closing output socket took 0.000 seconds
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_CLOSE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_close
[01:1048]\smpd_get_state_string
[01:1048]/smpd_get_state_string
[01:1048]op_close received - SMPD_CLOSING state.
[01:1048]process refcount == 2, pmi closed.
[01:1048]\smpd_free_context
[01:1048].freeing pmi context.
[01:1048].\smpd_init_context
[01:1048]..\smpd_init_command
[01:1048]../smpd_init_command
[01:1048]./smpd_init_context
[01:1048]/smpd_free_context
[01:1048]/smpd_handle_op_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = -1, result = 0, context=stderr
[01:1048]\smpd_handle_op_read
[01:1048]\smpd_state_reading_stdouterr
[01:1048]/smpd_state_reading_stdouterr
[01:1048]/smpd_handle_op_read
[01:1048]SOCK_OP_READ failed - result = -1, closing stderr context.
[01:1048]\SMPDU_Sock_post_close
[01:1048]\SMPDU_Sock_post_read
[01:1048].\SMPDU_Sock_post_readv
[01:1048]..closing output socket took 0.000 seconds
[01:1048]./SMPDU_Sock_post_readv
[01:1048]/SMPDU_Sock_post_read
[01:1048]/SMPDU_Sock_post_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_CLOSE event.error = 0, result = 0, context=stdout
[01:1048]\smpd_handle_op_close
[01:1048]\smpd_get_state_string
[01:1048]/smpd_get_state_string
[01:1048]op_close received - SMPD_CLOSING state.
[01:1048]process refcount == 1, stdout closed.
[01:1048]\smpd_free_context
[01:1048].freeing stdout context.
[01:1048].\smpd_init_context
[01:1048]..\smpd_init_command
[01:1048]../smpd_init_command
[01:1048]./smpd_init_context
[01:1048]/smpd_free_context
[01:1048]/smpd_handle_op_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_CLOSE event.error = 0, result = 0, context=stderr
[01:1048]\smpd_handle_op_close
[01:1048]\smpd_get_state_string
[01:1048]/smpd_get_state_string
[01:1048]op_close received - SMPD_CLOSING state.
[01:1048]process refcount == 0, waiting for the process to finish exiting.
[01:1048]\smpd_process_from_registry
[01:1048]/smpd_process_from_registry
[01:1048]\smpd_wait_process
[01:1048]/smpd_wait_process
[01:1048]\SMPDU_Sock_post_close
[01:1048].*** smpd_pinthread finishing pid:3912 ***
[01:1048].\SMPDU_Sock_post_read
[01:1048]..\SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_readv
[01:1048]./SMPDU_Sock_post_read
[01:1048]/SMPDU_Sock_post_close
[01:1048]\smpd_create_command
[01:1048].\smpd_init_command
[01:1048]./smpd_init_command
[01:1048]/smpd_create_command
[01:1048]\smpd_add_command_int_arg
[01:1048]/smpd_add_command_int_arg
[01:1048]\smpd_add_command_int_arg
[01:1048]/smpd_add_command_int_arg
[01:1048]\smpd_add_command_arg
[01:1048]/smpd_add_command_arg
[01:1048]creating an exit command for rank 0, pid 3912, exit code 123.
[01:1048]\smpd_post_write_command
[01:1048].\smpd_package_command
[01:1048]./smpd_package_command
[01:1048].\SMPDU_Sock_get_sock_id
[01:1048]./SMPDU_Sock_get_sock_id
[01:1048].smpd_post_write_command on the parent context sock 1212: 100 bytes for command: "cmd=exit src=1 dest=0 tag=16 rank=0 code=123 kvs=F2E6A159-5401-4b19-8966-5CC999BFAE91 "
[01:1048].\SMPDU_Sock_post_writev
[01:1048]./SMPDU_Sock_post_writev
[01:1048]/smpd_post_write_command
[01:1048]\smpd_free_process_struct
[01:1048]/smpd_free_process_struct
[01:1048]\smpd_free_context
[01:1048].freeing stderr context.
[01:1048].\smpd_init_context
[01:1048]..\smpd_init_command
[01:1048]../smpd_init_command
[01:1048]./smpd_init_context
[01:1048]/smpd_free_context
[01:1048]/smpd_handle_op_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_CLOSE event.error = 0, result = 0, context=stdin
[01:1048]\smpd_handle_op_close
[01:1048]\smpd_get_state_string
[01:1048]/smpd_get_state_string
[01:1048]op_close received - SMPD_CLOSING state.
[01:1048]Unaffiliated stdin context closing.
[01:1048]\smpd_free_context
[01:1048].freeing stdin context.
[01:1048].\smpd_init_context
[01:1048]..\smpd_init_command
[01:1048]../smpd_init_command
[01:1048]./smpd_init_context
[01:1048]/smpd_free_context
[01:1048]/smpd_handle_op_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_write
[01:1048]\smpd_state_writing_cmd
[01:1048].wrote command
[01:1048].command written to parent: "cmd=exit src=1 dest=0 tag=16 rank=0 code=123 kvs=F2E6A159-5401-4b19-8966-5CC999BFAE91 "
[01:1048].\smpd_free_command
[01:1048]..\smpd_init_command
[01:1048]../smpd_init_command
[01:1048]./smpd_free_command
[01:1048]/smpd_state_writing_cmd
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_read
[01:1048]\smpd_state_reading_cmd_header
[01:1048].read command header
[01:1048].command header read, posting read for data: 105 bytes
[01:1048].\SMPDU_Sock_post_read
[01:1048]..\SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_readv
[01:1048]./SMPDU_Sock_post_read
[01:1048]/smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_read
[01:1048]\smpd_state_reading_cmd
[01:1048].read command
[01:1048].\smpd_parse_command
[01:1048]./smpd_parse_command
[01:1048].read command: "cmd=suspend src=0 dest=1 tag=5 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 rank=1 exit_code=123 ctx_key=0 "
[01:1048].\smpd_handle_command
[01:1048]..handling command:
[01:1048].. src  = 0
[01:1048].. dest = 1
[01:1048].. cmd  = suspend
[01:1048].. tag  = 5
[01:1048].. ctx  = parent
[01:1048].. len  = 105
[01:1048].. str  = cmd=suspend src=0 dest=1 tag=5 name=F2E6A159-5401-4b19-8966-5CC999BFAE91 rank=1 exit_code=123 ctx_key=0 
[01:1048]..\smpd_command_destination
[01:1048]...1 -> 1 : returning NULL context
[01:1048]../smpd_command_destination
[01:1048]..\smpd_handle_suspend_command
[01:1048]...\smpd_create_command
[01:1048]....\smpd_init_command
[01:1048]..../smpd_init_command
[01:1048].../smpd_create_command
[01:1048]...\smpd_add_command_int_arg
[01:1048].../smpd_add_command_int_arg
[01:1048]...\smpd_suspend_process
[01:1048]....ERROR:SuspendThread failed with error 5 for process 1:F2E6A159-5401-4b19-8966-5CC999BFAE91:'cpi'
[01:1048].../smpd_suspend_process
[01:1048]...ERROR:unable to suspend process.
[01:1048]...\smpd_add_command_arg
[01:1048].../smpd_add_command_arg
[01:1048]...sending result command to parent context: "cmd=result src=1 dest=0 tag=17 cmd_tag=5 result="FAIL - unable to suspend process, error 5" "
[01:1048]...\smpd_post_write_command
[01:1048]....\smpd_package_command
[01:1048]..../smpd_package_command
[01:1048]....\SMPDU_Sock_get_sock_id
[01:1048]..../SMPDU_Sock_get_sock_id
[01:1048]....smpd_post_write_command on the parent context sock 1212: 106 bytes for command: "cmd=result src=1 dest=0 tag=17 cmd_tag=5 result="FAIL - unable to suspend process, error 5" "
[01:1048]....\SMPDU_Sock_post_writev
[01:1048]..../SMPDU_Sock_post_writev
[01:1048].../smpd_post_write_command
[01:1048]../smpd_handle_suspend_command
[01:1048]./smpd_handle_command
[01:1048].\smpd_post_read_command
[01:1048]..\SMPDU_Sock_get_sock_id
[01:1048]../SMPDU_Sock_get_sock_id
[01:1048]..posting a read for a command header on the parent context, sock 1212
[01:1048]..\SMPDU_Sock_post_read
[01:1048]...\SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_read
[01:1048]./smpd_post_read_command
[01:1048]/smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_WRITE event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_write
[01:1048]\smpd_state_writing_cmd
[01:1048].wrote command
[01:1048]/smpd_kill_all_processes
[01:1048]ReadFile failed, error 109
[01:1048]*** smpd_piothread finishing pid:3188 ***
[01:1048]command written to parent: "cmd=result src=1 dest=0 tag=17 cmd_tag=5 result="FAIL - unable to suspend process, error 5" "
[01:1048]\smpd_free_command
[01:1048].\smpd_init_command
[01:1048]./smpd_init_command
[01:1048]/smpd_free_command
[01:1048]/smpd_state_writing_cmd
[01:1048]ReadFile failed, error 109
[01:1048]*** smpd_piothread finishing pid:3188 ***
[01:1048]/smpd_handle_op_write
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_read
[01:1048]\smpd_state_reading_cmd_header
[01:1048]read command header
[01:1048]command header read, posting read for data: 53 bytes
[01:1048]\SMPDU_Sock_post_read
[01:1048].\SMPDU_Sock_post_readv
[01:1048]./SMPDU_Sock_post_readv
[01:1048]/SMPDU_Sock_post_read
[01:1048]/smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]GetQueuedCompletionStatus failed, GetLastError: 64
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = -1, result = 0, context=pmi
[01:1048]\smpd_handle_op_read
[01:1048]\smpd_state_reading_cmd_header
[01:1048]ERROR:unable to read the cmd header on the pmi context, Error = -1
.
[01:1048]/smpd_state_reading_cmd_header
[01:1048]/smpd_handle_op_read
[01:1048]SOCK_OP_READ failed - result = -1, closing pmi context.
[01:1048]\SMPDU_Sock_post_close
[01:1048]\SMPDU_Sock_post_read
[01:1048]\SMPDU_Sock_post_readv
[01:1048].ERROR:Error posting readv, An existing connection was forcibly closed by the remote host.(10054)
[01:1048]/SMPDU_Sock_post_readv
[01:1048]/SMPDU_Sock_post_read
[01:1048]/SMPDU_Sock_post_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_READ event.error = 0, result = 0, context=parent
[01:1048]\smpd_handle_op_read
[01:1048]\smpd_state_reading_cmd
[01:1048]read command
[01:1048]\smpd_parse_command
[01:1048]/smpd_parse_command
[01:1048]read command: "cmd=kill src=0 dest=1 tag=6 exit_code=123 ctx_key=0 "
[01:1048]\smpd_handle_command
[01:1048].handling command:
[01:1048]. src  = 0
[01:1048]. dest = 1
[01:1048]. cmd  = kill
[01:1048]. tag  = 6
[01:1048]. ctx  = parent
[01:1048]. len  = 53
[01:1048]. str  = cmd=kill src=0 dest=1 tag=6 exit_code=123 ctx_key=0 
[01:1048].\smpd_command_destination
[01:1048]..1 -> 1 : returning NULL context
[01:1048]./smpd_command_destination
[01:1048].\smpd_handle_kill_command
[01:1048]..\smpd_kill_process
[01:1048]...\smpd_process_from_registry
[01:1048].../smpd_process_from_registry
[01:1048]../smpd_kill_process
[01:1048]..ERROR:unable to kill process.
[01:1048]..Closing stdin ...
[01:1048]..\SMPDU_Sock_post_close
[01:1048]...\SMPDU_Sock_post_read
[01:1048]....\SMPDU_Sock_post_readv
[01:1048]..../SMPDU_Sock_post_readv
[01:1048].../SMPDU_Sock_post_read
[01:1048]../SMPDU_Sock_post_close
[01:1048]..Closing stdout ...
[01:1048]..\SMPDU_Sock_post_close
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...sock_post_close(1156) called while sock is reading: 0 bytes out of 1, index 0, iovlen 1.
[01:1048]../SMPDU_Sock_post_close
[01:1048]..Closing stderr ...
[01:1048]..\SMPDU_Sock_post_close
[01:1048]...\SMPDU_Sock_get_sock_id
[01:1048].../SMPDU_Sock_get_sock_id
[01:1048]...sock_post_close(1132) called while sock is reading: 0 bytes out of 1, index 0, iovlen 1.
[01:1048]../SMPDU_Sock_post_close
[01:1048]..Closing pmi ...
[01:1048]..\SMPDU_Sock_post_close
[01:1048]...ERROR:Posting close on a socket in closing state
[01:1048]../SMPDU_Sock_post_close
[01:1048]..ERROR:Unable to post close on pmi sock
[01:1048]./smpd_handle_kill_command
[01:1048]/smpd_handle_command
[01:1048]\smpd_post_read_command
[01:1048].\SMPDU_Sock_get_sock_id
[01:1048]./SMPDU_Sock_get_sock_id
[01:1048].posting a read for a command header on the parent context, sock 1212
[01:1048].\SMPDU_Sock_post_read
[01:1048]..\SMPDU_Sock_post_readv
[01:1048]../SMPDU_Sock_post_readv
[01:1048]./SMPDU_Sock_post_read
[01:1048]/smpd_post_read_command
[01:1048]/smpd_state_reading_cmd
[01:1048]/smpd_handle_op_read
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_CLOSE event.error = 0, result = 0, context=pmi
[01:1048]\smpd_handle_op_close
[01:1048]\smpd_get_state_string
[01:1048]/smpd_get_state_string
[01:1048]op_close received - SMPD_CLOSING state.
[01:1048]process refcount == 2, pmi closed.
[01:1048]\smpd_free_context
[01:1048]freeing pmi context.
[01:1048]\smpd_init_context
[01:1048].\smpd_init_command
[01:1048]./smpd_init_command
[01:1048]/smpd_init_context
[01:1048]/smpd_free_context
[01:1048]/smpd_handle_op_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]*** smpd_pinthread finishing pid:3188 ***
[01:1048]closing output socket took 0.000 seconds
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_CLOSE event.error = 0, result = 0, context=stdout
[01:1048]\smpd_handle_op_close
[01:1048]\smpd_get_state_string
[01:1048]/smpd_get_state_string
[01:1048]op_close received - SMPD_IDLE state.
[01:1048]\smpd_get_state_string
[01:1048]/smpd_get_state_string
[01:1048]closing output socket took 0.000 seconds
[01:1048]ERROR:sock_op_close returned while stdout context is in state: SMPD_IDLE
[01:1048]\smpd_free_context
[01:1048]freeing stdout context.
[01:1048]\smpd_init_context
[01:1048].\smpd_init_command
[01:1048]./smpd_init_command
[01:1048]/smpd_init_context
[01:1048]/smpd_free_context
[01:1048]/smpd_handle_op_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_CLOSE event.error = 0, result = 0, context=stderr
[01:1048]\smpd_handle_op_close
[01:1048]\smpd_get_state_string
[01:1048]/smpd_get_state_string
[01:1048]op_close received - SMPD_IDLE state.
[01:1048]\smpd_get_state_string
[01:1048]/smpd_get_state_string
[01:1048]ERROR:sock_op_close returned while stderr context is in state: SMPD_IDLE
[01:1048]\smpd_free_context
[01:1048]freeing stderr context.
[01:1048]\smpd_init_context
[01:1048].\smpd_init_command
[01:1048]./smpd_init_command
[01:1048]/smpd_init_context
[01:1048]/smpd_free_context
[01:1048]/smpd_handle_op_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]/SMPDU_Sock_wait
[01:1048]SOCK_OP_CLOSE event.error = 0, result = 0, context=stdin
[01:1048]\smpd_handle_op_close
[01:1048]\smpd_get_state_string
[01:1048]/smpd_get_state_string
[01:1048]op_close received - SMPD_IDLE state.
[01:1048]\smpd_get_state_string
[01:1048]/smpd_get_state_string
[01:1048]ERROR:sock_op_close returned while stdin context is in state: SMPD_IDLE
[01:1048]\smpd_free_context
[01:1048]freeing stdin context.
[01:1048]\smpd_init_context
[01:1048].\smpd_init_command
[01:1048]./smpd_init_command
[01:1048]/smpd_init_context
[01:1048]/smpd_free_context
[01:1048]/smpd_handle_op_close
[01:1048]sock_waiting for the next event.
[01:1048]\SMPDU_Sock_wait
[01:1048]\smpd_kill_all_processes
[01:1048]\smpd_process_from_registry
[01:1048]/smpd_process_from_registry
[01:1048]/smpd_kill_all_processes