[-1:980]..\smpd_get_opt_int
[-1:980]../smpd_get_opt_int
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt_int
[-1:980]../smpd_get_opt_int
[-1:980]..\smpd_get_opt_int
[-1:980]../smpd_get_opt_int
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt_string
[-1:980]../smpd_get_opt_string
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt_string
[-1:980]../smpd_get_opt_string
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt_string
[-1:980]../smpd_get_opt_string
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt_string
[-1:980]../smpd_get_opt_string
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt_string
[-1:980]../smpd_get_opt_string
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt_string
[-1:980]../smpd_get_opt_string
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]..\smpd_get_opt_string
[-1:980]../smpd_get_opt_string
[-1:980]..\smpd_get_opt_string
[-1:980]../smpd_get_opt_string
[-1:980]..\smpd_get_opt
[-1:980]../smpd_get_opt
[-1:980]./smpd_parse_command_args
[-1:980].\smpd_get_smpd_data
[-1:980]..\smpd_get_smpd_data_from_environment
[-1:980]../smpd_get_smpd_data_from_environment
[-1:980]./smpd_get_smpd_data
[-1:980].\smpd_entry_point
[00:980]..\smpd_set_smpd_data
[00:980]../smpd_set_smpd_data
[00:980]..created a set for the listener: 1648
[00:980]..smpd listening on port 8676
[00:980]..\smpd_create_context
[00:980]...\smpd_init_context
[00:980]....\smpd_init_command
[00:980]..../smpd_init_command
[00:980].../smpd_init_context
[00:980]../smpd_create_context
[00:980]..\smpd_option_on
[00:980]...\smpd_get_smpd_data
[00:980]....\smpd_get_smpd_data_from_environment
[00:980]..../smpd_get_smpd_data_from_environment
[00:980]....\smpd_get_smpd_data_default
[00:980]..../smpd_get_smpd_data_default
[00:980]....Unable to get the data for the key 'no_dynamic_hosts'
[00:980].../smpd_get_smpd_data
[00:980]../smpd_option_on
[00:980]..\smpd_insert_into_dynamic_hosts
[00:980]../smpd_insert_into_dynamic_hosts
[00:980]..\smpd_enter_at_state
[00:980]...sock_waiting for the next event.
[00:980]...SOCK_OP_ACCEPT
[00:980]...\smpd_handle_op_accept
[00:980]....\smpd_state_smpd_listening
[00:980].....authenticating new connection
[00:980].....\smpd_create_context
[00:980]......\smpd_init_context
[00:980].......\smpd_init_command
[00:980]......./smpd_init_command
[00:980]....../smpd_init_context
[00:980]...../smpd_create_context
[00:980].....\smpd_gen_authentication_strings
[00:980]......\smpd_hash
[00:980]....../smpd_hash
[00:980]...../smpd_gen_authentication_strings
[00:980].....posting a write of the challenge string: 1.0.8 3238
[00:980]..../smpd_state_smpd_listening
[00:980].../smpd_handle_op_accept
[00:980]...sock_waiting for the next event.
[00:980]...SOCK_OP_WRITE
[00:980]...\smpd_handle_op_write
[00:980]....\smpd_state_writing_challenge_string
[00:980].....wrote challenge string: '1.0.8 3238'
[00:980]..../smpd_state_writing_challenge_string
[00:980].../smpd_handle_op_write
[00:980]...sock_waiting for the next event.
[00:980]...SOCK_OP_READ
[00:980]...\smpd_handle_op_read
[00:980]....\smpd_state_reading_challenge_response
[00:980].....read challenge response: '8a007e503d1baf16ac233fcb4de259f6'
[00:980]..../smpd_state_reading_challenge_response
[00:980].../smpd_handle_op_read
[00:980]...sock_waiting for the next event.
[00:980]...SOCK_OP_WRITE
[00:980]...\smpd_handle_op_write
[00:980]....\smpd_state_writing_connect_result
[00:980].....wrote connect result: 'SUCCESS'
[00:980]..../smpd_state_writing_connect_result
[00:980].../smpd_handle_op_write
[00:980]...sock_waiting for the next event.
[00:980]...SOCK_OP_READ
[00:980]...\smpd_handle_op_read
[00:980]....\smpd_state_reading_session_request
[00:980].....read session request: 'process'
[00:980]..../smpd_state_reading_session_request
[00:980].../smpd_handle_op_read
[00:980]...sock_waiting for the next event.
[00:980]...SOCK_OP_WRITE
[00:980]...\smpd_handle_op_write
[00:980]....\smpd_state_writing_no_cred_request
[00:980].....wrote no cred request: 'nocredentials'
[00:980].....\smpd_start_win_mgr
[00:980]......starting command: "C:\Program Files\MPICH2\bin\smpd.exe" -d 27 -mgr -read 000005C8 -write 000005CC
[00:980]......CreateProcess
[00:980]......smpd reading the port string from the manager
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt_int
[-1:3440]../smpd_get_opt_int
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt_int
[-1:3440]../smpd_get_opt_int
[-1:3440]..\smpd_get_opt_int
[-1:3440]../smpd_get_opt_int
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt
[-1:3440]../smpd_get_opt
[-1:3440]..\smpd_get_opt_string
[-1:3440]../smpd_get_opt_string
[-1:3440]..\smpd_get_opt_string
[-1:3440]../smpd_get_opt_string
[-1:3440]..manager creating listener and session sets.
[-1:3440]..created set for manager listener, 1384
[-1:3440]..smpd manager listening on port 1094
[-1:3440]..\smpd_create_context
[-1:3440]...\smpd_init_context
[-1:3440]....\smpd_init_command
[-1:3440]..../smpd_init_command
[-1:3440].../smpd_init_context
[-1:3440]../smpd_create_context
[-1:3440]..manager writing port back to smpd.
[-1:3440]..\smpd_hide_string_arg
[-1:3440]...\first_token
[-1:3440].../first_token
[-1:3440]...\compare_token
[-1:3440].../compare_token
[-1:3440]...\next_token
[-1:3440]....\first_token
[-1:3440]..../first_token
[-1:3440]....\first_token
[-1:3440]..../first_token
[-1:3440].../next_token
[-1:3440]...\compare_token
[-1:3440].../compare_token
[-1:3440]...\compare_token
[-1:3440].../compare_token
[-1:3440]...\next_token
[-1:3440]....\first_token
[-1:3440]..../first_token
[-1:3440]....\first_token
[-1:3440]..../first_token
[-1:3440].../next_token
[-1:3440]...\compare_token
[-1:3440].../compare_token
[-1:3440]...\next_token
[-1:3440]....\first_token
[-1:3440]..../first_token
[-1:3440]....\first_token
[-1:3440]..../first_token
[-1:3440].../next_token
[-1:3440]../smpd_hide_string_arg
[-1:3440]../smpd_hide_string_arg
[00:980]......smpd sending the account to the manager
[00:980]......\smpd_hide_string_arg
[00:980].......\first_token
[00:980]......./first_token
[00:980].......\compare_token
[00:980]......./compare_token
[00:980].......\next_token
[00:980]........\first_token
[00:980]......../first_token
[00:980]........\first_token
[00:980]......../first_token
[00:980]......./next_token
[00:980]....../smpd_hide_string_arg
[00:980]....../smpd_hide_string_arg
[00:980]......\smpd_hide_string_arg
[00:980].......\first_token
[00:980]......./first_token
[00:980].......\compare_token
[00:980]......./compare_token
[00:980].......\next_token
[00:980]........\first_token
[00:980]......../first_token
[00:980]........\first_token
[00:980]......../first_token
[00:980]......./next_token
[00:980].......\compare_token
[00:980]......./compare_token
[00:980].......\compare_token
[00:980]......./compare_token
[00:980].......\next_token
[00:980]........\first_token
[00:980]......../first_token
[00:980]........\first_token
[00:980]......../first_token
[00:980]......./next_token
[00:980].......\compare_token
[00:980]......./compare_token
[00:980].......\next_token
[00:980]........\first_token
[00:980]......../first_token
[00:980]........\first_token
[00:980]......../first_token
[00:980]......./next_token
[00:980].......\compare_token
[00:980]......./compare_token
[00:980].......\next_token
[00:980]........\first_token
[00:980]......../first_token
[00:980]........\first_token
[00:980]......../first_token
[00:980]......./next_token
[00:980]....../smpd_hide_string_arg
[00:980]....../smpd_hide_string_arg
[00:980]......closing the pipe to the manager
[-1:3440]..\smpd_enter_at_state
[00:980]......smpd writing reconnect request: port 1094
[-1:3440]...sock_waiting for the next event.
[00:980]...../smpd_state_writing_no_cred_request
[00:980]..../smpd_handle_op_write
[00:980]....sock_waiting for the next event.
[00:980]....SOCK_OP_WRITE
[00:980]....\smpd_handle_op_write
[00:980].....\smpd_state_writing_reconnect_request
[00:980]......wrote reconnect request: '1094'
[00:980]...../smpd_state_writing_reconnect_request
[00:980]..../smpd_handle_op_write
[00:980]....sock_waiting for the next event.
[00:980]....SOCK_OP_CLOSE
[00:980]....\smpd_handle_op_close
[00:980].....\smpd_get_state_string
[00:980]...../smpd_get_state_string
[00:980].....op_close received - SMPD_CLOSING state.
[00:980].....Unaffiliated undetermined context closing.
[00:980].....\smpd_free_context
[00:980]......freeing undetermined context.
[00:980]......\smpd_init_context
[00:980].......\smpd_init_command
[00:980]......./smpd_init_command
[00:980]....../smpd_init_context
[00:980]...../smpd_free_context
[00:980]..../smpd_handle_op_close
[00:980]....sock_waiting for the next event.
[-1:3440]...SOCK_OP_ACCEPT
[-1:3440]...\smpd_handle_op_accept
[-1:3440]....\smpd_state_mgr_listening
[-1:3440].....accepted re-connection
[-1:3440].....\smpd_create_context
[-1:3440]......\smpd_init_context
[-1:3440].......\smpd_init_command
[-1:3440]......./smpd_init_command
[-1:3440]....../smpd_init_context
[-1:3440]...../smpd_create_context
[-1:3440].....closing the mgr listener.
[-1:3440]..../smpd_state_mgr_listening
[-1:3440].../smpd_handle_op_accept
[-1:3440]...sock_waiting for the next event.
[-1:3440]...SOCK_OP_CLOSE
[-1:3440]...\smpd_handle_op_close
[-1:3440]....\smpd_get_state_string
[-1:3440]..../smpd_get_state_string
[-1:3440]....op_close received - SMPD_MGR_LISTENING state.
[-1:3440]....\smpd_free_context
[-1:3440].....freeing listener context.
[-1:3440].....\smpd_init_context
[-1:3440]......\smpd_init_command
[-1:3440]....../smpd_init_command
[-1:3440]...../smpd_init_context
[-1:3440]..../smpd_free_context
[-1:3440].../smpd_handle_op_close
[-1:3440]...sock_waiting for the next event.
[-1:3440]...SOCK_OP_READ
[-1:3440]...\smpd_handle_op_read
[-1:3440]....\smpd_state_reading_session_header
[-1:3440].....read session header: 'id=1 parent=0 level=0'
[-1:3440].....\smpd_interpret_session_header
[-1:3440]......interpreting session header: "id=1 parent=0 level=0"
[-1:3440]...... id = 1
[01:3440]...... parent = 0
[01:3440]...... level = 0
[01:3440]...../smpd_interpret_session_header
[01:3440].....\smpd_post_read_command
[01:3440]......posting a read for a command header on the parent context, sock 1244
[01:3440]...../smpd_post_read_command
[01:3440]..../smpd_state_reading_session_header
[01:3440].../smpd_handle_op_read
[01:3440]...sock_waiting for the next event.
[01:3440]...SOCK_OP_READ
[01:3440]...\smpd_handle_op_read
[01:3440]....\smpd_state_reading_cmd_header
[01:3440].....read command header
[01:3440].....command header read, posting read for data: 34 bytes
[01:3440]..../smpd_state_reading_cmd_header
[01:3440].../smpd_handle_op_read
[01:3440]...sock_waiting for the next event.
[01:3440]...SOCK_OP_READ
[01:3440]...\smpd_handle_op_read
[01:3440]....\smpd_state_reading_cmd
[01:3440].....read command
[01:3440].....\smpd_parse_command
[01:3440]...../smpd_parse_command
[01:3440].....read command: "cmd=start_dbs src=0 dest=1 tag=0 "
[01:3440].....\smpd_handle_command
[01:3440]......handling command:
[01:3440]...... src  = 0
[01:3440]...... dest = 1
[01:3440]...... cmd  = start_dbs
[01:3440]...... tag  = 0
[01:3440]...... ctx  = parent
[01:3440]...... len  = 34
[01:3440]...... str  = cmd=start_dbs src=0 dest=1 tag=0 
[01:3440]......\smpd_command_destination
[01:3440].......1 -> 1 : returning NULL context
[01:3440]....../smpd_command_destination
[01:3440]......\smpd_handle_start_dbs_command
[01:3440].......\smpd_dbs_init
[01:3440]......./smpd_dbs_init
[01:3440].......\smpd_create_command
[01:3440]........\smpd_init_command
[01:3440]......../smpd_init_command
[01:3440]......./smpd_create_command
[01:3440].......\smpd_add_command_int_arg
[01:3440]......./smpd_add_command_int_arg
[01:3440].......\smpd_add_command_arg
[01:3440]......./smpd_add_command_arg
[01:3440].......\smpd_dbs_create
[01:3440]......./smpd_dbs_create
[01:3440].......\smpd_add_command_arg
[01:3440]......./smpd_add_command_arg
[01:3440].......\smpd_add_command_arg
[01:3440]......./smpd_add_command_arg
[01:3440].......\smpd_add_command_arg
[01:3440]......./smpd_add_command_arg
[01:3440].......sending result command to parent context: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=start_dbs kvs_name=5F19B256-9C93-4052-B66C-E4F39287DADB domain_name=1A320286-D22A-4f5c-A7AA-440D57AB34DB result=SUCCESS "
[01:3440].......\smpd_post_write_command
[01:3440]........\smpd_package_command
[01:3440]......../smpd_package_command
[01:3440]........smpd_post_write_command on the parent context sock 1244: 183 bytes for command: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=start_dbs kvs_name=5F19B256-9C93-4052-B66C-E4F39287DADB domain_name=1A320286-D22A-4f5c-A7AA-440D57AB34DB result=SUCCESS "
[01:3440]......./smpd_post_write_command
[01:3440]....../smpd_handle_start_dbs_command
[01:3440]...../smpd_handle_command
[01:3440].....\smpd_post_read_command
[01:3440]......posting a read for a command header on the parent context, sock 1244
[01:3440]...../smpd_post_read_command
[01:3440]..../smpd_state_reading_cmd
[01:3440].../smpd_handle_op_read
[01:3440]...sock_waiting for the next event.
[01:3440]...SOCK_OP_WRITE
[01:3440]...\smpd_handle_op_write
[01:3440]....\smpd_state_writing_cmd
[01:3440].....wrote command
[01:3440].....command written to parent: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=start_dbs kvs_name=5F19B256-9C93-4052-B66C-E4F39287DADB domain_name=1A320286-D22A-4f5c-A7AA-440D57AB34DB result=SUCCESS "
[01:3440].....\smpd_free_command
[01:3440]......\smpd_init_command
[01:3440]....../smpd_init_command
[01:3440]...../smpd_free_command
[01:3440]..../smpd_state_writing_cmd
[01:3440].../smpd_handle_op_write
[01:3440]...sock_waiting for the next event.
[01:3440]...SOCK_OP_READ
[01:3440]...\smpd_handle_op_read
[01:3440]....\smpd_state_reading_cmd_header
[01:3440].....read command header
[01:3440].....command header read, posting read for data: 184 bytes
[01:3440]..../smpd_state_reading_cmd_header
[01:3440].../smpd_handle_op_read
[01:3440]...sock_waiting for the next event.
[01:3440]...SOCK_OP_READ
[01:3440]...\smpd_handle_op_read
[01:3440]....\smpd_state_reading_cmd
[01:3440].....read command
[01:3440].....\smpd_parse_command
[01:3440]...../smpd_parse_command
[01:3440].....read command: "cmd=launch src=0 dest=1 tag=1 c=hostname.exe s=0 a=0 d=C:\prj\zlab p=C:\WINDOWS\system32 q=0..1 i=1 n=2 k=5F19B256-9C93-4052-B66C-E4F39287DADB kd=1A320286-D22A-4f5c-A7AA-440D57AB34DB "
[01:3440].....\smpd_handle_command
[01:3440]......handling command:
[01:3440]...... src  = 0
[01:3440]...... dest = 1
[01:3440]...... cmd  = launch
[01:3440]...... tag  = 1
[01:3440]...... ctx  = parent
[01:3440]...... len  = 184
[01:3440]...... str  = cmd=launch src=0 dest=1 tag=1 c=hostname.exe s=0 a=0 d=C:\prj\zlab p=C:\WINDOWS\system32 q=0..1 i=1 n=2 k=5F19B256-9C93-4052-B66C-E4F39287DADB kd=1A320286-D22A-4f5c-A7AA-440D57AB34DB 
[01:3440]......\smpd_command_destination
[01:3440].......1 -> 1 : returning NULL context
[01:3440]....../smpd_command_destination
[01:3440]......\smpd_handle_launch_command
[01:3440].......\smpd_create_process_struct
[01:3440]........\smpd_create_context
[01:3440].........\smpd_init_context
[01:3440]..........\smpd_init_command
[01:3440]........../smpd_init_command
[01:3440]........./smpd_init_context
[01:3440]......../smpd_create_context
[01:3440]........\smpd_create_context
[01:3440].........\smpd_init_context
[01:3440]..........\smpd_init_command
[01:3440]........../smpd_init_command
[01:3440]........./smpd_init_context
[01:3440]......../smpd_create_context
[01:3440]........\smpd_create_context
[01:3440].........\smpd_init_context
[01:3440]..........\smpd_init_command
[01:3440]........../smpd_init_command
[01:3440]........./smpd_init_context
[01:3440]......../smpd_create_context
[01:3440]........\smpd_create_context
[01:3440].........\smpd_init_context
[01:3440]..........\smpd_init_command
[01:3440]........../smpd_init_command
[01:3440]........./smpd_init_context
[01:3440]......../smpd_create_context
[01:3440]......./smpd_create_process_struct
[01:3440].......launching: 'hostname.exe'
[01:3440].......\smpd_launch_process
[01:3440]........searching for 'hostname.exe' in 'C:\WINDOWS\system32'
[01:3440]........\smpd_search_path
[01:3440]......../smpd_search_path
[01:3440]........\smpd_priority_class_to_win_class
[01:3440]......../smpd_priority_class_to_win_class
[01:3440]........\smpd_priority_to_win_priority
[01:3440]......../smpd_priority_to_win_priority
[01:3440]........\smpd_make_socket_loop_choose
[01:3440].........\smpd_get_hostname
[01:3440]........./smpd_get_hostname
[01:3440]......../smpd_make_socket_loop_choose
[01:3440]........\smpd_make_socket_loop_choose
[01:3440].........\smpd_get_hostname
[01:3440]........./smpd_get_hostname
[01:3440]......../smpd_make_socket_loop_choose
[01:3440]........\smpd_make_socket_loop_choose
[01:3440].........\smpd_get_hostname
[01:3440]........./smpd_get_hostname
[01:3440]......../smpd_make_socket_loop_choose
[01:3440]........pmi listening on port 1102
[01:3440]........\smpd_create_context
[01:3440].........\smpd_init_context
[01:3440]..........\smpd_init_command
[01:3440]........../smpd_init_command
[01:3440]........./smpd_init_context
[01:3440]......../smpd_create_context
[01:3440]........\SetEnvironmentVariables
[01:3440]......../SetEnvironmentVariables
[01:3440]........env: PMI_RANK=1
[01:3440]........env: PMI_SIZE=2
[01:3440]........env: PMI_KVS=5F19B256-9C93-4052-B66C-E4F39287DADB
[01:3440]........env: PMI_DOMAIN=1A320286-D22A-4f5c-A7AA-440D57AB34DB
[01:3440]........env: PMI_HOST=mb13 192.168.1.101 
[01:3440]........env: PMI_PORT=1102
[01:3440]........env: PMI_SMPD_ID=1
[01:3440]........env: PMI_SMPD_KEY=0
[01:3440]........env: PMI_CLIQUE=0..1
[01:3440]........env: PMI_SPAWN=0
[01:3440]........env: PMI_APPNUM=0
[01:3440]........CreateProcess(C:\WINDOWS\system32\hostname.exe)
[01:3440]........\RemoveEnvironmentVariables
[01:3440]......../RemoveEnvironmentVariables
[01:3440]........*** entering smpd_pinthread pid:2708 sock:1212 ***
[01:3440]........\smpd_process_to_registry
[01:3440].........*** entering smpd_piothread pid:2708 sock:1200 ***
[01:3440].........*** entering smpd_piothread pid:2708 sock:1176 ***
[01:3440]......../smpd_process_to_registry
[01:3440]......./smpd_launch_process
[01:3440].......\smpd_create_command
[01:3440]........\smpd_init_command
[01:3440]......../smpd_init_command
[01:3440]......./smpd_create_command
[01:3440].......\smpd_add_command_int_arg
[01:3440]......./smpd_add_command_int_arg
[01:3440].......\smpd_add_command_arg
[01:3440]......./smpd_add_command_arg
[01:3440].......\smpd_add_command_arg
[01:3440]......./smpd_add_command_arg
[01:3440].......\smpd_add_command_arg
[01:3440]......./smpd_add_command_arg
[01:3440].......\smpd_add_command_int_arg
[01:3440]......./smpd_add_command_int_arg
[01:3440].......\smpd_add_command_int_arg
[01:3440]......./smpd_add_command_int_arg
[01:3440].......\smpd_post_write_command
[01:3440]........\smpd_package_command
[01:3440]......../smpd_package_command
[01:3440]........smpd_post_write_command on the parent context sock 1244: 147 bytes for command: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=launch result=SUCCESS pg_id=5F19B256-9C93-4052-B66C-E4F39287DADB pg_rank=1 pg_ctx=0 "
[01:3440]......./smpd_post_write_command
[01:3440]....../smpd_handle_launch_command
[01:3440]...../smpd_handle_command
[01:3440].....\smpd_post_read_command
[01:3440]......posting a read for a command header on the parent context, sock 1244
[01:3440]...../smpd_post_read_command
[01:3440]..../smpd_state_reading_cmd
[01:3440].../smpd_handle_op_read
[01:3440]...sock_waiting for the next event.
[01:3440]...SOCK_OP_WRITE
[01:3440]...\smpd_handle_op_write
[01:3440]....\smpd_state_writing_cmd
[01:3440].....wrote command
[01:3440].....command written to parent: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=launch result=SUCCESS pg_id=5F19B256-9C93-4052-B66C-E4F39287DADB pg_rank=1 pg_ctx=0 "
[01:3440].....\smpd_free_command
[01:3440]......\smpd_init_command
[01:3440]....../smpd_init_command
[01:3440]...../smpd_free_command
[01:3440]..../smpd_state_writing_cmd
[01:3440].../smpd_handle_op_write
[01:3440]...sock_waiting for the next event.
[01:3440]...SOCK_OP_READ
[01:3440]...\smpd_handle_op_read
[01:3440]....\smpd_state_reading_cmd_header
[01:3440].....read command header
[01:3440].....command header read, posting read for data: 184 bytes
[01:3440]..../smpd_state_reading_cmd_header
[01:3440].../smpd_handle_op_read
[01:3440]...sock_waiting for the next event.
[01:3440]...SOCK_OP_READ
[01:3440]...\smpd_handle_op_read
[01:3440]....\smpd_state_reading_cmd
[01:3440].....read command
[01:3440].....\smpd_parse_command
[01:3440]...../smpd_parse_command
[01:3440].....read command: "cmd=launch src=0 dest=1 tag=2 c=hostname.exe s=0 a=0 d=C:\prj\zlab p=C:\WINDOWS\system32 q=0..1 i=0 n=2 k=5F19B256-9C93-4052-B66C-E4F39287DADB kd=1A320286-D22A-4f5c-A7AA-440D57AB34DB "
[01:3440].....\smpd_handle_command
[01:3440]......handling command:
[01:3440]...... src  = 0
[01:3440]...... dest = 1
[01:3440]...... cmd  = launch
[01:3440]...... tag  = 2
[01:3440]...... ctx  = parent
[01:3440]...... len  = 184
[01:3440]...... str  = cmd=launch src=0 dest=1 tag=2 c=hostname.exe s=0 a=0 d=C:\prj\zlab p=C:\WINDOWS\system32 q=0..1 i=0 n=2 k=5F19B256-9C93-4052-B66C-E4F39287DADB kd=1A320286-D22A-4f5c-A7AA-440D57AB34DB 
[01:3440]......\smpd_command_destination
[01:3440].......1 -> 1 : returning NULL context
[01:3440]....../smpd_command_destination
[01:3440]......\smpd_handle_launch_command
[01:3440].......\smpd_create_process_struct
[01:3440]........\smpd_create_context
[01:3440].........\smpd_init_context
[01:3440]..........\smpd_init_command
[01:3440]........../smpd_init_command
[01:3440]........./smpd_init_context
[01:3440]......../smpd_create_context
[01:3440]........\smpd_create_context
[01:3440].........\smpd_init_context
[01:3440]..........\smpd_init_command
[01:3440]........../smpd_init_command
[01:3440]........./smpd_init_context
[01:3440]......../smpd_create_context
[01:3440]........\smpd_create_context
[01:3440].........\smpd_init_context
[01:3440]..........\smpd_init_command
[01:3440]........../smpd_init_command
[01:3440]........./smpd_init_context
[01:3440]......../smpd_create_context
[01:3440]........\smpd_create_context
[01:3440].........\smpd_init_context
[01:3440]..........\smpd_init_command
[01:3440]........../smpd_init_command
[01:3440]........./smpd_init_context
[01:3440]......../smpd_create_context
[01:3440]......./smpd_create_process_struct
[01:3440].......launching: 'hostname.exe'
[01:3440].......\smpd_launch_process
[01:3440]........searching for 'hostname.exe' in 'C:\WINDOWS\system32'
[01:3440]........\smpd_search_path
[01:3440]......../smpd_search_path
[01:3440]........\smpd_priority_class_to_win_class
[01:3440]......../smpd_priority_class_to_win_class
[01:3440]........\smpd_priority_to_win_priority
[01:3440]......../smpd_priority_to_win_priority
[01:3440]........\smpd_make_socket_loop_choose
[01:3440].........\smpd_get_hostname
[01:3440]........./smpd_get_hostname
[01:3440]......../smpd_make_socket_loop_choose
[01:3440]........\smpd_make_socket_loop_choose
[01:3440].........\smpd_get_hostname
[01:3440]........./smpd_get_hostname
[01:3440]......../smpd_make_socket_loop_choose
[01:3440]........\smpd_make_socket_loop_choose
[01:3440].........\smpd_get_hostname
[01:3440]........./smpd_get_hostname
[01:3440]......../smpd_make_socket_loop_choose
[01:3440]........pmi listening on port 1109
[01:3440]........\smpd_create_context
[01:3440].........\smpd_init_context
[01:3440]..........\smpd_init_command
[01:3440]........../smpd_init_command
[01:3440]........./smpd_init_context
[01:3440]......../smpd_create_context
[01:3440]........\SetEnvironmentVariables
[01:3440]......../SetEnvironmentVariables
[01:3440]........env: PMI_RANK=0
[01:3440]........env: PMI_SIZE=2
[01:3440]........env: PMI_KVS=5F19B256-9C93-4052-B66C-E4F39287DADB
[01:3440]........env: PMI_DOMAIN=1A320286-D22A-4f5c-A7AA-440D57AB34DB
[01:3440]........env: PMI_HOST=mb13 192.168.1.101 
[01:3440]........env: PMI_PORT=1109
[01:3440]........env: PMI_SMPD_ID=1
[01:3440]........env: PMI_SMPD_KEY=1
[01:3440]........env: PMI_CLIQUE=0..1
[01:3440]........env: PMI_SPAWN=0
[01:3440]........env: PMI_APPNUM=0
[01:3440]........CreateProcess(C:\WINDOWS\system32\hostname.exe)
[01:3440]........\RemoveEnvironmentVariables
[01:3440]......../RemoveEnvironmentVariables
[01:3440]........*** entering smpd_pinthread pid:2592 sock:932 ***
[01:3440]........*** entering smpd_piothread pid:2592 sock:920 ***
[01:3440]........\smpd_process_to_registry
[01:3440]......../smpd_process_to_registry
[01:3440]........*** entering smpd_piothread pid:2592 sock:896 ***
[01:3440]......./smpd_launch_process
[01:3440].......\smpd_create_command
[01:3440]........\smpd_init_command
[01:3440]......../smpd_init_command
[01:3440]......./smpd_create_command
[01:3440].......\smpd_add_command_int_arg
[01:3440]......./smpd_add_command_int_arg
[01:3440].......\smpd_add_command_arg
[01:3440]......./smpd_add_command_arg
[01:3440].......\smpd_add_command_arg
[01:3440]......./smpd_add_command_arg
[01:3440].......\smpd_add_command_arg
[01:3440]......./smpd_add_command_arg
[01:3440].......\smpd_add_command_int_arg
[01:3440]......./smpd_add_command_int_arg
[01:3440].......\smpd_add_command_int_arg
[01:3440]......./smpd_add_command_int_arg
[01:3440].......\smpd_post_write_command
[01:3440]........\smpd_package_command
[01:3440]......../smpd_package_command
[01:3440]........smpd_post_write_command on the parent context sock 1244: 147 bytes for command: "cmd=result src=1 dest=0 tag=2 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=5F19B256-9C93-4052-B66C-E4F39287DADB pg_rank=0 pg_ctx=1 "
[01:3440]......./smpd_post_write_command
[01:3440]....../smpd_handle_launch_command
[01:3440]...../smpd_handle_command
[01:3440].....\smpd_post_read_command
[01:3440]......posting a read for a command header on the parent context, sock 1244
[01:3440]...../smpd_post_read_command
[01:3440]..../smpd_state_reading_cmd
[01:3440].../smpd_handle_op_read
[01:3440]...sock_waiting for the next event.
[01:3440]...SOCK_OP_WRITE
[01:3440]...\smpd_handle_op_write
[01:3440]....\smpd_state_writing_cmd
[01:3440].....wrote command
[01:3440].....command written to parent: "cmd=result src=1 dest=0 tag=2 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=5F19B256-9C93-4052-B66C-E4F39287DADB pg_rank=0 pg_ctx=1 "
[01:3440].....\smpd_free_command
[01:3440]......\smpd_init_command
[01:3440]....../smpd_init_command
[01:3440]...../smpd_free_command
[01:3440]..../smpd_state_writing_cmd
[01:3440].../smpd_handle_op_write
[01:3440]...sock_waiting for the next event.
[01:3440]../smpd_easy_send
[01:3440]/smpd_easy_send
[01:3440]SOCK_OP_READ
[01:3440]/smpd_easy_send
[01:3440]\smpd_handle_op_read
[01:3440].\smpd_state_reading_stdouterr
[01:3440].read from stdout
[01:3440]./smpd_easy_send
[01:3440].6 bytes read from stdout
[01:3440].\smpd_create_command
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_create_command
[01:3440].\smpd_add_command_int_arg
[01:3440]./smpd_add_command_int_arg
[01:3440].\smpd_add_command_int_arg
[01:3440]./smpd_add_command_int_arg
[01:3440].\smpd_add_command_arg
[01:3440]./smpd_add_command_arg
[01:3440].\smpd_post_write_command
[01:3440]..\smpd_package_command
[01:3440]../smpd_package_command
[01:3440]..smpd_post_write_command on the parent context sock 1244: 77 bytes for command: "cmd=stdout src=1 dest=0 tag=3 rank=1 first=1 data=6D6231330D0A "
[01:3440]./smpd_post_write_command
[01:3440]/smpd_state_reading_stdouterr
[01:3440]/smpd_handle_op_read
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_READ
[01:3440]\smpd_handle_op_read
[01:3440]\smpd_state_reading_stdouterr
[01:3440].read from stdout
[01:3440].6 bytes read from stdout
[01:3440].\smpd_create_command
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_create_command
[01:3440].\smpd_add_command_int_arg
[01:3440]./smpd_add_command_int_arg
[01:3440].\smpd_add_command_int_arg
[01:3440].ReadFile failed, error 109
[01:3440].ReadFile failed, error 109
[01:3440]./smpd_add_command_int_arg
[01:3440].*** smpd_piothread finishing pid:2592 ***
[01:3440].*** smpd_piothread finishing pid:2592 ***
[01:3440].\smpd_add_command_arg
[01:3440]./smpd_add_command_arg
[01:3440].\smpd_post_write_command
[01:3440]..\smpd_package_command
[01:3440]../smpd_package_command
[01:3440]..enqueueing write at the end of the list.
[01:3440]./smpd_post_write_command
[01:3440]/smpd_state_reading_stdouterr
[01:3440]/smpd_handle_op_read
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_WRITE
[01:3440]ReadFile failed, error 109
[01:3440]ReadFile failed, error 109
[01:3440]\smpd_handle_op_write
[01:3440]*** smpd_piothread finishing pid:2708 ***
[01:3440]*** smpd_piothread finishing pid:2708 ***
[01:3440]\smpd_state_writing_cmd
[01:3440].wrote command
[01:3440].command written to parent: "cmd=stdout src=1 dest=0 tag=3 rank=1 first=1 data=6D6231330D0A "
[01:3440].\smpd_free_command
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_free_command
[01:3440].smpd_handle_written: posting write(77 bytes) for command: "cmd=stdout src=1 dest=0 tag=4 rank=0 first=1 data=6D6231330D0A "
[01:3440]/smpd_state_writing_cmd
[01:3440]/smpd_handle_op_write
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_READ
[01:3440]\smpd_handle_op_read
[01:3440]\smpd_state_reading_stdouterr
[01:3440]/smpd_state_reading_stdouterr
[01:3440]/smpd_handle_op_read
[01:3440]SOCK_OP_READ failed - result = -1, closing stdout context.
[01:3440]sock_waiting for the next event.
[01:3440]closing output socket took 0.001 seconds
[01:3440]SOCK_OP_READ
[01:3440]\smpd_handle_op_read
[01:3440]\smpd_state_reading_stdouterr
[01:3440]/smpd_state_reading_stdouterr
[01:3440]/smpd_handle_op_read
[01:3440]SOCK_OP_READ failed - result = -1, closing stderr context.
[01:3440]closing output socket took 0.028 seconds
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_READ
[01:3440]\smpd_handle_op_read
[01:3440]\smpd_state_reading_stdouterr
[01:3440]/smpd_state_reading_stdouterr
[01:3440]/smpd_handle_op_read
[01:3440]SOCK_OP_READ failed - result = -1, closing stdout context.
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_READ
[01:3440]closing output socket took 0.028 seconds
[01:3440]\smpd_handle_op_read
[01:3440]\smpd_state_reading_stdouterr
[01:3440]/smpd_state_reading_stdouterr
[01:3440]/smpd_handle_op_read
[01:3440]SOCK_OP_READ failed - result = -1, closing stderr context.
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_WRITE
[01:3440]\smpd_handle_op_write
[01:3440]closing output socket took 0.028 seconds
[01:3440]\smpd_state_writing_cmd
[01:3440].wrote command
[01:3440].command written to parent: "cmd=stdout src=1 dest=0 tag=4 rank=0 first=1 data=6D6231330D0A "
[01:3440].\smpd_free_command
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_free_command
[01:3440]/smpd_state_writing_cmd
[01:3440]/smpd_handle_op_write
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_CLOSE
[01:3440]\smpd_handle_op_close
[01:3440]\smpd_get_state_string
[01:3440]/smpd_get_state_string
[01:3440]op_close received - SMPD_CLOSING state.
[01:3440]process refcount == 1, stdout closed.
[01:3440]\smpd_free_context
[01:3440].freeing stdout context.
[01:3440].\smpd_init_context
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_init_context
[01:3440]/smpd_free_context
[01:3440]/smpd_handle_op_close
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_CLOSE
[01:3440]\smpd_handle_op_close
[01:3440]\smpd_get_state_string
[01:3440]/smpd_get_state_string
[01:3440]op_close received - SMPD_CLOSING state.
[01:3440]process refcount == 0, waiting for the process to finish exiting.
[01:3440]\smpd_process_from_registry
[01:3440]/smpd_process_from_registry
[01:3440]\smpd_wait_process
[01:3440]/smpd_wait_process
[01:3440]\smpd_create_command
[01:3440].\smpd_init_command
[01:3440].*** smpd_pinthread finishing pid:2592 ***
[01:3440]./smpd_init_command
[01:3440]/smpd_create_command
[01:3440]\smpd_add_command_int_arg
[01:3440]/smpd_add_command_int_arg
[01:3440]\smpd_add_command_int_arg
[01:3440]/smpd_add_command_int_arg
[01:3440]\smpd_add_command_arg
[01:3440]/smpd_add_command_arg
[01:3440]creating an exit command for rank 0, pid 2592, exit code 0.
[01:3440]\smpd_post_write_command
[01:3440].\smpd_package_command
[01:3440]./smpd_package_command
[01:3440].smpd_post_write_command on the parent context sock 1244: 97 bytes for command: "cmd=exit src=1 dest=0 tag=5 rank=0 code=0 kvs=5F19B256-9C93-4052-B66C-E4F39287DADB "
[01:3440]/smpd_post_write_command
[01:3440]\smpd_free_process_struct
[01:3440].\smpd_free_context
[01:3440]..freeing pmi context.
[01:3440]..\smpd_init_context
[01:3440]...\smpd_init_command
[01:3440].../smpd_init_command
[01:3440]../smpd_init_context
[01:3440]./smpd_free_context
[01:3440]/smpd_free_process_struct
[01:3440]\smpd_free_context
[01:3440].freeing stderr context.
[01:3440].\smpd_init_context
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_init_context
[01:3440]/smpd_free_context
[01:3440]/smpd_handle_op_close
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_CLOSE
[01:3440]\smpd_handle_op_close
[01:3440]\smpd_get_state_string
[01:3440]/smpd_get_state_string
[01:3440]op_close received - SMPD_CLOSING state.
[01:3440]process refcount == 1, stdout closed.
[01:3440]\smpd_free_context
[01:3440].freeing stdout context.
[01:3440].\smpd_init_context
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_init_context
[01:3440]/smpd_free_context
[01:3440]/smpd_handle_op_close
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_CLOSE
[01:3440]\smpd_handle_op_close
[01:3440]\smpd_get_state_string
[01:3440]/smpd_get_state_string
[01:3440]op_close received - SMPD_CLOSING state.
[01:3440]process refcount == 0, waiting for the process to finish exiting.
[01:3440]\smpd_process_from_registry
[01:3440]/smpd_process_from_registry
[01:3440]\smpd_wait_process
[01:3440]/smpd_wait_process
[01:3440]\smpd_create_command
[01:3440].\smpd_init_command
[01:3440]./smpd_init_command
[01:3440]/smpd_create_command
[01:3440]*** smpd_pinthread finishing pid:2708 ***
[01:3440]\smpd_add_command_int_arg
[01:3440]/smpd_add_command_int_arg
[01:3440]\smpd_add_command_int_arg
[01:3440]/smpd_add_command_int_arg
[01:3440]\smpd_add_command_arg
[01:3440]/smpd_add_command_arg
[01:3440]creating an exit command for rank 1, pid 2708, exit code 0.
[01:3440]\smpd_post_write_command
[01:3440].\smpd_package_command
[01:3440]./smpd_package_command
[01:3440].enqueueing write at the end of the list.
[01:3440]/smpd_post_write_command
[01:3440]\smpd_free_process_struct
[01:3440].\smpd_free_context
[01:3440]..freeing pmi context.
[01:3440]..\smpd_init_context
[01:3440]...\smpd_init_command
[01:3440].../smpd_init_command
[01:3440]../smpd_init_context
[01:3440]./smpd_free_context
[01:3440]/smpd_free_process_struct
[01:3440]\smpd_free_context
[01:3440].freeing stderr context.
[01:3440].\smpd_init_context
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_init_context
[01:3440]/smpd_free_context
[01:3440]/smpd_handle_op_close
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_CLOSE
[01:3440]\smpd_handle_op_close
[01:3440]\smpd_get_state_string
[01:3440]/smpd_get_state_string
[01:3440]op_close received - SMPD_CLOSING state.
[01:3440]Unaffiliated stdin context closing.
[01:3440]\smpd_free_context
[01:3440].freeing stdin context.
[01:3440].\smpd_init_context
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_init_context
[01:3440]/smpd_free_context
[01:3440]/smpd_handle_op_close
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_WRITE
[01:3440]\smpd_handle_op_write
[01:3440]\smpd_state_writing_cmd
[01:3440].wrote command
[01:3440].command written to parent: "cmd=exit src=1 dest=0 tag=5 rank=0 code=0 kvs=5F19B256-9C93-4052-B66C-E4F39287DADB "
[01:3440].\smpd_free_command
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_free_command
[01:3440].smpd_handle_written: posting write(97 bytes) for command: "cmd=exit src=1 dest=0 tag=6 rank=1 code=0 kvs=5F19B256-9C93-4052-B66C-E4F39287DADB "
[01:3440]/smpd_state_writing_cmd
[01:3440]/smpd_handle_op_write
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_CLOSE
[01:3440]\smpd_handle_op_close
[01:3440]\smpd_get_state_string
[01:3440]/smpd_get_state_string
[01:3440]op_close received - SMPD_CLOSING state.
[01:3440]Unaffiliated stdin context closing.
[01:3440]\smpd_free_context
[01:3440].freeing stdin context.
[01:3440].\smpd_init_context
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_init_context
[01:3440]/smpd_free_context
[01:3440]/smpd_handle_op_close
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_WRITE
[01:3440]\smpd_handle_op_write
[01:3440]\smpd_state_writing_cmd
[01:3440].wrote command
[01:3440].command written to parent: "cmd=exit src=1 dest=0 tag=6 rank=1 code=0 kvs=5F19B256-9C93-4052-B66C-E4F39287DADB "
[01:3440].\smpd_free_command
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_free_command
[01:3440]/smpd_state_writing_cmd
[01:3440]/smpd_handle_op_write
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_READ
[01:3440]\smpd_handle_op_read
[01:3440]\smpd_state_reading_cmd_header
[01:3440].read command header
[01:3440].command header read, posting read for data: 30 bytes
[01:3440]/smpd_state_reading_cmd_header
[01:3440]/smpd_handle_op_read
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_READ
[01:3440]\smpd_handle_op_read
[01:3440]\smpd_state_reading_cmd
[01:3440].read command
[01:3440].\smpd_parse_command
[01:3440]./smpd_parse_command
[01:3440].read command: "cmd=close src=0 dest=1 tag=3 "
[01:3440].\smpd_handle_command
[01:3440]..handling command:
[01:3440].. src  = 0
[01:3440].. dest = 1
[01:3440].. cmd  = close
[01:3440].. tag  = 3
[01:3440].. ctx  = parent
[01:3440].. len  = 30
[01:3440].. str  = cmd=close src=0 dest=1 tag=3 
[01:3440]..\smpd_command_destination
[01:3440]...1 -> 1 : returning NULL context
[01:3440]../smpd_command_destination
[01:3440]..\smpd_handle_close_command
[01:3440]...\smpd_create_command
[01:3440]....\smpd_init_command
[01:3440]..../smpd_init_command
[01:3440].../smpd_create_command
[01:3440]...sending closed command to parent: "cmd=closed src=1 dest=0 tag=7 "
[01:3440]...\smpd_post_write_command
[01:3440]....\smpd_package_command
[01:3440]..../smpd_package_command
[01:3440]....smpd_post_write_command on the parent context sock 1244: 44 bytes for command: "cmd=closed src=1 dest=0 tag=7 "
[01:3440].../smpd_post_write_command
[01:3440]...posted closed command.
[01:3440]../smpd_handle_close_command
[01:3440]./smpd_handle_command
[01:3440].not posting read for another command because SMPD_CLOSE returned
[01:3440]/smpd_state_reading_cmd
[01:3440]/smpd_handle_op_read
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_WRITE
[01:3440]\smpd_handle_op_write
[01:3440]\smpd_state_writing_cmd
[01:3440].wrote command
[01:3440].command written to parent: "cmd=closed src=1 dest=0 tag=7 "
[01:3440].closed command written, posting close of the sock.
[01:3440].MPIDU_Sock_post_close(1244)
[01:3440].\smpd_free_command
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_free_command
[01:3440]/smpd_state_writing_cmd
[01:3440]/smpd_handle_op_write
[01:3440]sock_waiting for the next event.
[01:3440]SOCK_OP_CLOSE
[01:3440]\smpd_handle_op_close
[01:3440]\smpd_get_state_string
[01:3440]/smpd_get_state_string
[01:3440]op_close received - SMPD_CLOSING state.
[01:3440]Unaffiliated parent context closing.
[01:3440]\smpd_free_context
[01:3440].freeing parent context.
[01:3440].\smpd_init_context
[01:3440]..\smpd_init_command
[01:3440]../smpd_init_command
[01:3440]./smpd_init_context
[01:3440]/smpd_free_context
[01:3440]all contexts closed, exiting state machine.
[01:3440]/smpd_handle_op_close
[01:3440]/smpd_enter_at_state
[01:3440]\smpd_exit
[01:3440]\smpd_kill_all_processes
[01:3440]/smpd_kill_all_processes
[01:3440]\smpd_finalize_drive_maps
[01:3440]/smpd_finalize_drive_maps
[01:3440]\smpd_dbs_finalize
[01:3440]/smpd_dbs_finalize