[-1:248]..\smpd_get_opt_int
[-1:248]../smpd_get_opt_int
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt_int
[-1:248]../smpd_get_opt_int
[-1:248]..\smpd_get_opt_int
[-1:248]../smpd_get_opt_int
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt_string
[-1:248]../smpd_get_opt_string
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt_string
[-1:248]../smpd_get_opt_string
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt_string
[-1:248]../smpd_get_opt_string
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt_string
[-1:248]../smpd_get_opt_string
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt_string
[-1:248]../smpd_get_opt_string
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt_string
[-1:248]../smpd_get_opt_string
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]..\smpd_get_opt_string
[-1:248]../smpd_get_opt_string
[-1:248]..\smpd_get_opt_string
[-1:248]../smpd_get_opt_string
[-1:248]..\smpd_get_opt
[-1:248]../smpd_get_opt
[-1:248]./smpd_parse_command_args
[-1:248].\smpd_get_smpd_data
[-1:248]..\smpd_get_smpd_data_from_environment
[-1:248]../smpd_get_smpd_data_from_environment
[-1:248]./smpd_get_smpd_data
[-1:248].\smpd_entry_point
[00:248]..\smpd_set_smpd_data
[00:248]../smpd_set_smpd_data
[00:248]..created a set for the listener: 1724
[00:248]..smpd listening on port 8676
[00:248]..\smpd_create_context
[00:248]...\smpd_init_context
[00:248]....\smpd_init_command
[00:248]..../smpd_init_command
[00:248].../smpd_init_context
[00:248]../smpd_create_context
[00:248]..\smpd_option_on
[00:248]...\smpd_get_smpd_data
[00:248]....\smpd_get_smpd_data_from_environment
[00:248]..../smpd_get_smpd_data_from_environment
[00:248]....\smpd_get_smpd_data_default
[00:248]..../smpd_get_smpd_data_default
[00:248]....Unable to get the data for the key 'no_dynamic_hosts'
[00:248].../smpd_get_smpd_data
[00:248]../smpd_option_on
[00:248]..\smpd_insert_into_dynamic_hosts
[00:248]../smpd_insert_into_dynamic_hosts
[00:248]..\smpd_enter_at_state
[00:248]...sock_waiting for the next event.
[00:248]...SOCK_OP_ACCEPT
[00:248]...\smpd_handle_op_accept
[00:248]....\smpd_state_smpd_listening
[00:248].....authenticating new connection
[00:248].....\smpd_create_context
[00:248]......\smpd_init_context
[00:248].......\smpd_init_command
[00:248]......./smpd_init_command
[00:248]....../smpd_init_context
[00:248]...../smpd_create_context
[00:248].....\smpd_gen_authentication_strings
[00:248]......\smpd_hash
[00:248]....../smpd_hash
[00:248]...../smpd_gen_authentication_strings
[00:248].....posting a write of the challenge string: 1.0.8 848
[00:248]..../smpd_state_smpd_listening
[00:248].../smpd_handle_op_accept
[00:248]...sock_waiting for the next event.
[00:248]...SOCK_OP_WRITE
[00:248]...\smpd_handle_op_write
[00:248]....\smpd_state_writing_challenge_string
[00:248].....wrote challenge string: '1.0.8 848'
[00:248]..../smpd_state_writing_challenge_string
[00:248].../smpd_handle_op_write
[00:248]...sock_waiting for the next event.
[00:248]...SOCK_OP_READ
[00:248]...\smpd_handle_op_read
[00:248]....\smpd_state_reading_challenge_response
[00:248].....read challenge response: '740a331b5fee14a3a5765c42d67e22ac'
[00:248]..../smpd_state_reading_challenge_response
[00:248].../smpd_handle_op_read
[00:248]...sock_waiting for the next event.
[00:248]...SOCK_OP_WRITE
[00:248]...\smpd_handle_op_write
[00:248]....\smpd_state_writing_connect_result
[00:248].....wrote connect result: 'SUCCESS'
[00:248]..../smpd_state_writing_connect_result
[00:248].../smpd_handle_op_write
[00:248]...sock_waiting for the next event.
[00:248]...SOCK_OP_READ
[00:248]...\smpd_handle_op_read
[00:248]....\smpd_state_reading_session_request
[00:248].....read session request: 'process'
[00:248]..../smpd_state_reading_session_request
[00:248].../smpd_handle_op_read
[00:248]...sock_waiting for the next event.
[00:248]...SOCK_OP_WRITE
[00:248]...\smpd_handle_op_write
[00:248]....\smpd_state_writing_no_cred_request
[00:248].....wrote no cred request: 'nocredentials'
[00:248].....\smpd_start_win_mgr
[00:248]......starting command: "c:\MPICH2\bin\smpd.exe" -d 27 -mgr -read 00000614 -write 00000618
[00:248]......CreateProcess
[00:248]......smpd reading the port string from the manager
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt_int
[-1:2020]../smpd_get_opt_int
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt_int
[-1:2020]../smpd_get_opt_int
[-1:2020]..\smpd_get_opt_int
[-1:2020]../smpd_get_opt_int
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt
[-1:2020]../smpd_get_opt
[-1:2020]..\smpd_get_opt_string
[-1:2020]../smpd_get_opt_string
[-1:2020]..\smpd_get_opt_string
[-1:2020]../smpd_get_opt_string
[-1:2020]..manager creating listener and session sets.
[-1:2020]..created set for manager listener, 1460
[-1:2020]..smpd manager listening on port 1048
[-1:2020]..\smpd_create_context
[-1:2020]...\smpd_init_context
[-1:2020]....\smpd_init_command
[-1:2020]..../smpd_init_command
[-1:2020].../smpd_init_context
[-1:2020]../smpd_create_context
[-1:2020]..manager writing port back to smpd.
[00:248]......smpd sending the account to the manager
[00:248]......\smpd_hide_string_arg
[00:248].......\first_token
[00:248]......./first_token
[00:248].......\compare_token
[00:248]......./compare_token
[00:248].......\next_token
[00:248]........\first_token
[00:248]......../first_token
[00:248]........\first_token
[00:248]......../first_token
[00:248]......./next_token
[00:248]....../smpd_hide_string_arg
[00:248]....../smpd_hide_string_arg
[00:248]......\smpd_hide_string_arg
[00:248].......\first_token
[00:248]......./first_token
[00:248].......\compare_token
[00:248]......./compare_token
[00:248].......\next_token
[00:248]........\first_token
[00:248]......../first_token
[00:248]........\first_token
[00:248]......../first_token
[00:248]......./next_token
[00:248].......\compare_token
[00:248]......./compare_token
[00:248].......\compare_token
[00:248]......./compare_token
[00:248].......\next_token
[00:248]........\first_token
[00:248]......../first_token
[00:248]........\first_token
[00:248]......../first_token
[00:248]......./next_token
[00:248].......\compare_token
[00:248]......./compare_token
[00:248].......\next_token
[00:248]........\first_token
[00:248]......../first_token
[00:248]........\first_token
[00:248]......../first_token
[00:248]......./next_token
[00:248].......\compare_token
[00:248]......./compare_token
[00:248].......\next_token
[00:248]........\first_token
[00:248]......../first_token
[00:248]........\first_token
[00:248]......../first_token
[00:248]......./next_token
[00:248]....../smpd_hide_string_arg
[00:248]....../smpd_hide_string_arg
[-1:2020]..\smpd_hide_string_arg
[-1:2020]...\first_token
[-1:2020].../first_token
[-1:2020]...\compare_token
[-1:2020].../compare_token
[-1:2020]...\next_token
[-1:2020]....\first_token
[-1:2020]..../first_token
[-1:2020]....\first_token
[-1:2020]..../first_token
[-1:2020].../next_token
[-1:2020]...\compare_token
[-1:2020].../compare_token
[-1:2020]...\compare_token
[-1:2020].../compare_token
[-1:2020]...\next_token
[-1:2020]....\first_token
[-1:2020]..../first_token
[-1:2020]....\first_token
[-1:2020]..../first_token
[-1:2020].../next_token
[-1:2020]...\compare_token
[-1:2020].../compare_token
[-1:2020]...\next_token
[-1:2020]....\first_token
[-1:2020]..../first_token
[-1:2020]....\first_token
[-1:2020]..../first_token
[-1:2020].../next_token
[-1:2020]../smpd_hide_string_arg
[-1:2020]../smpd_hide_string_arg
[00:248]......closing the pipe to the manager
[00:248]......smpd writing reconnect request: port 1048
[00:248]...../smpd_state_writing_no_cred_request
[00:248]..../smpd_handle_op_write
[00:248]....sock_waiting for the next event.
[00:248]....SOCK_OP_WRITE
[00:248]....\smpd_handle_op_write
[00:248].....\smpd_state_writing_reconnect_request
[00:248]......wrote reconnect request: '1048'
[00:248]...../smpd_state_writing_reconnect_request
[00:248]..../smpd_handle_op_write
[00:248]....sock_waiting for the next event.
[-1:2020]..\smpd_enter_at_state
[-1:2020]...sock_waiting for the next event.
[00:248]....SOCK_OP_CLOSE
[00:248]....\smpd_handle_op_close
[00:248].....\smpd_get_state_string
[00:248]...../smpd_get_state_string
[00:248].....op_close received - SMPD_CLOSING state.
[00:248].....Unaffiliated undetermined context closing.
[00:248].....\smpd_free_context
[00:248]......freeing undetermined context.
[00:248]......\smpd_init_context
[00:248].......\smpd_init_command
[00:248]......./smpd_init_command
[00:248]....../smpd_init_context
[00:248]...../smpd_free_context
[00:248]..../smpd_handle_op_close
[00:248]....sock_waiting for the next event.
[-1:2020]...SOCK_OP_ACCEPT
[-1:2020]...\smpd_handle_op_accept
[-1:2020]....\smpd_state_mgr_listening
[-1:2020].....accepted re-connection
[-1:2020].....\smpd_create_context
[-1:2020]......\smpd_init_context
[-1:2020].......\smpd_init_command
[-1:2020]......./smpd_init_command
[-1:2020]....../smpd_init_context
[-1:2020]...../smpd_create_context
[-1:2020].....closing the mgr listener.
[-1:2020]..../smpd_state_mgr_listening
[-1:2020].../smpd_handle_op_accept
[-1:2020]...sock_waiting for the next event.
[-1:2020]...SOCK_OP_CLOSE
[-1:2020]...\smpd_handle_op_close
[-1:2020]....\smpd_get_state_string
[-1:2020]..../smpd_get_state_string
[-1:2020]....op_close received - SMPD_MGR_LISTENING state.
[-1:2020]....\smpd_free_context
[-1:2020].....freeing listener context.
[-1:2020].....\smpd_init_context
[-1:2020]......\smpd_init_command
[-1:2020]....../smpd_init_command
[-1:2020]...../smpd_init_context
[-1:2020]..../smpd_free_context
[-1:2020].../smpd_handle_op_close
[-1:2020]...sock_waiting for the next event.
[-1:2020]...SOCK_OP_READ
[-1:2020]...\smpd_handle_op_read
[-1:2020]....\smpd_state_reading_session_header
[-1:2020].....read session header: 'id=2 parent=1 level=1'
[-1:2020].....\smpd_interpret_session_header
[-1:2020]......interpreting session header: "id=2 parent=1 level=1"
[-1:2020]...... id = 2
[02:2020]...... parent = 1
[02:2020]...... level = 1
[02:2020]...../smpd_interpret_session_header
[02:2020].....\smpd_post_read_command
[02:2020]......posting a read for a command header on the parent context, sock 1320
[02:2020]...../smpd_post_read_command
[02:2020]..../smpd_state_reading_session_header
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd_header
[02:2020].....read command header
[02:2020].....command header read, posting read for data: 180 bytes
[02:2020]..../smpd_state_reading_cmd_header
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd
[02:2020].....read command
[02:2020].....\smpd_parse_command
[02:2020]...../smpd_parse_command
[02:2020].....read command: "cmd=launch src=0 dest=2 tag=2 c=c:\MPICH2\examples\cpi.exe s=0 a=0 d=C:\MPICH2\examples q=1 i=1 n=2 k=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB kd=FF7AB23C-74DF-4727-BC9C-9274A9B2E185 "
[02:2020].....\smpd_handle_command
[02:2020]......handling command:
[02:2020]...... src  = 0
[02:2020]...... dest = 2
[02:2020]...... cmd  = launch
[02:2020]...... tag  = 2
[02:2020]...... ctx  = parent
[02:2020]...... len  = 180
[02:2020]...... str  = cmd=launch src=0 dest=2 tag=2 c=c:\MPICH2\examples\cpi.exe s=0 a=0 d=C:\MPICH2\examples q=1 i=1 n=2 k=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB kd=FF7AB23C-74DF-4727-BC9C-9274A9B2E185 
[02:2020]......\smpd_command_destination
[02:2020].......2 -> 2 : returning NULL context
[02:2020]....../smpd_command_destination
[02:2020]......\smpd_handle_launch_command
[02:2020].......\smpd_create_process_struct
[02:2020]........\smpd_create_context
[02:2020].........\smpd_init_context
[02:2020]..........\smpd_init_command
[02:2020]........../smpd_init_command
[02:2020]........./smpd_init_context
[02:2020]......../smpd_create_context
[02:2020]........\smpd_create_context
[02:2020].........\smpd_init_context
[02:2020]..........\smpd_init_command
[02:2020]........../smpd_init_command
[02:2020]........./smpd_init_context
[02:2020]......../smpd_create_context
[02:2020]........\smpd_create_context
[02:2020].........\smpd_init_context
[02:2020]..........\smpd_init_command
[02:2020]........../smpd_init_command
[02:2020]........./smpd_init_context
[02:2020]......../smpd_create_context
[02:2020]........\smpd_create_context
[02:2020].........\smpd_init_context
[02:2020]..........\smpd_init_command
[02:2020]........../smpd_init_command
[02:2020]........./smpd_init_context
[02:2020]......../smpd_create_context
[02:2020]......./smpd_create_process_struct
[02:2020].......launching: 'c:\MPICH2\examples\cpi.exe'
[02:2020].......\smpd_launch_process
[02:2020]........\smpd_priority_class_to_win_class
[02:2020]......../smpd_priority_class_to_win_class
[02:2020]........\smpd_priority_to_win_priority
[02:2020]......../smpd_priority_to_win_priority
[02:2020]........\smpd_make_socket_loop_choose
[02:2020].........\smpd_get_hostname
[02:2020]........./smpd_get_hostname
[02:2020]......../smpd_make_socket_loop_choose
[02:2020]........\smpd_make_socket_loop_choose
[02:2020].........\smpd_get_hostname
[02:2020]........./smpd_get_hostname
[02:2020]......../smpd_make_socket_loop_choose
[02:2020]........\smpd_make_socket_loop_choose
[02:2020].........\smpd_get_hostname
[02:2020]........./smpd_get_hostname
[02:2020]......../smpd_make_socket_loop_choose
[02:2020]........pmi listening on port 1055
[02:2020]........\smpd_create_context
[02:2020].........\smpd_init_context
[02:2020]..........\smpd_init_command
[02:2020]........../smpd_init_command
[02:2020]........./smpd_init_context
[02:2020]......../smpd_create_context
[02:2020]........\SetEnvironmentVariables
[02:2020]......../SetEnvironmentVariables
[02:2020]........env: PMI_RANK=1
[02:2020]........env: PMI_SIZE=2
[02:2020]........env: PMI_KVS=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB
[02:2020]........env: PMI_DOMAIN=FF7AB23C-74DF-4727-BC9C-9274A9B2E185
[02:2020]........env: PMI_HOST=rtis08.etf.bg.ac.yu 147.91.12.151 
[02:2020]........env: PMI_PORT=1055
[02:2020]........env: PMI_SMPD_ID=2
[02:2020]........env: PMI_SMPD_KEY=0
[02:2020]........env: PMI_CLIQUE=1
[02:2020]........env: PMI_SPAWN=0
[02:2020]........env: PMI_APPNUM=0
[02:2020]........CreateProcess(c:\MPICH2\examples\cpi.exe)
[02:2020]........\RemoveEnvironmentVariables
[02:2020]......../RemoveEnvironmentVariables
[02:2020]........\smpd_process_to_registry
[02:2020]......../smpd_process_to_registry
[02:2020]......./smpd_launch_process
[02:2020].......\smpd_create_command
[02:2020]........\smpd_init_command
[02:2020]......../smpd_init_command
[02:2020]......./smpd_create_command
[02:2020].......\smpd_add_command_int_arg
[02:2020]......./smpd_add_command_int_arg
[02:2020].......\smpd_add_command_arg
[02:2020]......./smpd_add_command_arg
[02:2020].......\smpd_add_command_arg
[02:2020]......./smpd_add_command_arg
[02:2020].......\smpd_add_command_arg
[02:2020].......*** entering smpd_pinthread pid:544 sock:1288 ***
[02:2020].......*** entering smpd_piothread pid:544 sock:1276 ***
[02:2020].......*** entering smpd_piothread pid:544 sock:1252 ***
[02:2020]......./smpd_add_command_arg
[02:2020].......\smpd_add_command_int_arg
[02:2020]......./smpd_add_command_int_arg
[02:2020].......\smpd_add_command_int_arg
[02:2020]......./smpd_add_command_int_arg
[02:2020].......\smpd_post_write_command
[02:2020]........\smpd_package_command
[02:2020]......../smpd_package_command
[02:2020]........smpd_post_write_command on the parent context sock 1320: 147 bytes for command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB pg_rank=1 pg_ctx=0 "
[02:2020]......./smpd_post_write_command
[02:2020]....../smpd_handle_launch_command
[02:2020]...../smpd_handle_command
[02:2020].....\smpd_post_read_command
[02:2020]......posting a read for a command header on the parent context, sock 1320
[02:2020]...../smpd_post_read_command
[02:2020]..../smpd_state_reading_cmd
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_WRITE
[02:2020]...\smpd_handle_op_write
[02:2020]....\smpd_state_writing_cmd
[02:2020].....wrote command
[02:2020].....command written to parent: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB pg_rank=1 pg_ctx=0 "
[02:2020].....\smpd_free_command
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_free_command
[02:2020]..../smpd_state_writing_cmd
[02:2020].../smpd_handle_op_write
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_ACCEPT
[02:2020]...\smpd_handle_op_accept
[02:2020]....\smpd_state_pmi_listening
[02:2020].....\smpd_post_read_command
[02:2020]......posting a read for a command header on the pmi context, sock 1096
[02:2020]...../smpd_post_read_command
[02:2020]..../smpd_state_pmi_listening
[02:2020].../smpd_handle_op_accept
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_CLOSE
[02:2020]...\smpd_handle_op_close
[02:2020]....\smpd_get_state_string
[02:2020]..../smpd_get_state_string
[02:2020]....op_close received - SMPD_CLOSING state.
[02:2020]....Unaffiliated PMI_LISTENER context closing.
[02:2020]....\smpd_free_context
[02:2020].....freeing PMI_LISTENER context.
[02:2020].....\smpd_init_context
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_init_context
[02:2020]..../smpd_free_context
[02:2020].../smpd_handle_op_close
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd_header
[02:2020].....read command header
[02:2020].....command header read, posting read for data: 105 bytes
[02:2020]..../smpd_state_reading_cmd_header
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd
[02:2020].....read command
[02:2020].....\smpd_parse_command
[02:2020]...../smpd_parse_command
[02:2020].....read command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB key=1 value=2 node_id=2 "
[02:2020].....\smpd_handle_command
[02:2020]......handling command:
[02:2020]...... src  = 2
[02:2020]...... dest = 0
[02:2020]...... cmd  = init
[02:2020]...... tag  = 0
[02:2020]...... ctx  = pmi
[02:2020]...... len  = 105
[02:2020]...... str  = cmd=init src=2 dest=0 tag=0 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB key=1 value=2 node_id=2 
[02:2020]......\smpd_command_destination
[02:2020].......2 -> 0 : returning parent_context: 0 < 2
[02:2020]....../smpd_command_destination
[02:2020]......forwarding command to 1
[02:2020]......\smpd_forward_command
[02:2020].......\smpd_create_command_copy
[02:2020]......./smpd_create_command_copy
[02:2020].......posting write of forwarded command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB key=1 value=2 node_id=2 "
[02:2020].......\smpd_post_write_command
[02:2020]........\smpd_package_command
[02:2020]......../smpd_package_command
[02:2020]........smpd_post_write_command on the parent context sock 1320: 118 bytes for command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB key=1 value=2 node_id=2 "
[02:2020]......./smpd_post_write_command
[02:2020]....../smpd_forward_command
[02:2020]...../smpd_handle_command
[02:2020].....\smpd_post_read_command
[02:2020]......posting a read for a command header on the pmi context, sock 1096
[02:2020]...../smpd_post_read_command
[02:2020]..../smpd_state_reading_cmd
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_WRITE
[02:2020]...\smpd_handle_op_write
[02:2020]....\smpd_state_writing_cmd
[02:2020].....wrote command
[02:2020].....command written to parent: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB key=1 value=2 node_id=2 "
[02:2020].....\smpd_free_command
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_free_command
[02:2020]..../smpd_state_writing_cmd
[02:2020].../smpd_handle_op_write
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd_header
[02:2020].....read command header
[02:2020].....command header read, posting read for data: 66 bytes
[02:2020]..../smpd_state_reading_cmd_header
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd
[02:2020].....read command
[02:2020].....\smpd_parse_command
[02:2020]...../smpd_parse_command
[02:2020].....read command: "cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[02:2020].....\smpd_handle_command
[02:2020]......handling command:
[02:2020]...... src  = 0
[02:2020]...... dest = 2
[02:2020]...... cmd  = result
[02:2020]...... tag  = 4
[02:2020]...... ctx  = parent
[02:2020]...... len  = 66
[02:2020]...... str  = cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS 
[02:2020]......\smpd_command_destination
[02:2020].......2 -> 2 : returning NULL context
[02:2020]....../smpd_command_destination
[02:2020]......\smpd_handle_result
[02:2020].......forwarding the dbs result command to the pmi context 0.
[02:2020].......\smpd_forward_command
[02:2020]........\smpd_create_command_copy
[02:2020]......../smpd_create_command_copy
[02:2020]........posting write of forwarded command: "cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[02:2020]........\smpd_post_write_command
[02:2020].........\smpd_package_command
[02:2020]........./smpd_package_command
[02:2020].........smpd_post_write_command on the pmi context sock 1096: 79 bytes for command: "cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[02:2020]......../smpd_post_write_command
[02:2020]......./smpd_forward_command
[02:2020]....../smpd_handle_result
[02:2020]...../smpd_handle_command
[02:2020].....\smpd_post_read_command
[02:2020]......posting a read for a command header on the parent context, sock 1320
[02:2020]...../smpd_post_read_command
[02:2020]..../smpd_state_reading_cmd
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_WRITE
[02:2020]...\smpd_handle_op_write
[02:2020]....\smpd_state_writing_cmd
[02:2020].....wrote command
[02:2020].....command written to pmi: "cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[02:2020].....\smpd_free_command
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_free_command
[02:2020]..../smpd_state_writing_cmd
[02:2020].../smpd_handle_op_write
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd_header
[02:2020].....read command header
[02:2020].....command header read, posting read for data: 174 bytes
[02:2020]..../smpd_state_reading_cmd_header
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd
[02:2020].....read command
[02:2020].....\smpd_parse_command
[02:2020]...../smpd_parse_command
[02:2020].....read command: "cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB key=P1-businesscard value="port=1057 description=rtis08.etf.bg.ac.yu ifname=147.91.12.151 " "
[02:2020].....\smpd_handle_command
[02:2020]......handling command:
[02:2020]...... src  = 2
[02:2020]...... dest = 1
[02:2020]...... cmd  = dbput
[02:2020]...... tag  = 1
[02:2020]...... ctx  = pmi
[02:2020]...... len  = 174
[02:2020]...... str  = cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB key=P1-businesscard value="port=1057 description=rtis08.etf.bg.ac.yu ifname=147.91.12.151 " 
[02:2020]......\smpd_command_destination
[02:2020].......2 -> 1 : returning parent_context: 1 < 2
[02:2020]....../smpd_command_destination
[02:2020]......forwarding command to 1
[02:2020]......\smpd_forward_command
[02:2020].......\smpd_create_command_copy
[02:2020]......./smpd_create_command_copy
[02:2020].......posting write of forwarded command: "cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB key=P1-businesscard value="port=1057 description=rtis08.etf.bg.ac.yu ifname=147.91.12.151 " "
[02:2020].......\smpd_post_write_command
[02:2020]........\smpd_package_command
[02:2020]......../smpd_package_command
[02:2020]........smpd_post_write_command on the parent context sock 1320: 187 bytes for command: "cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB key=P1-businesscard value="port=1057 description=rtis08.etf.bg.ac.yu ifname=147.91.12.151 " "
[02:2020]......./smpd_post_write_command
[02:2020]....../smpd_forward_command
[02:2020]...../smpd_handle_command
[02:2020].....\smpd_post_read_command
[02:2020]......posting a read for a command header on the pmi context, sock 1096
[02:2020]...../smpd_post_read_command
[02:2020]..../smpd_state_reading_cmd
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_WRITE
[02:2020]...\smpd_handle_op_write
[02:2020]....\smpd_state_writing_cmd
[02:2020].....wrote command
[02:2020].....command written to parent: "cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB key=P1-businesscard value="port=1057 description=rtis08.etf.bg.ac.yu ifname=147.91.12.151 " "
[02:2020].....\smpd_free_command
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_free_command
[02:2020]..../smpd_state_writing_cmd
[02:2020].../smpd_handle_op_write
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd_header
[02:2020].....read command header
[02:2020].....command header read, posting read for data: 85 bytes
[02:2020]..../smpd_state_reading_cmd_header
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd
[02:2020].....read command
[02:2020].....\smpd_parse_command
[02:2020]...../smpd_parse_command
[02:2020].....read command: "cmd=result src=1 dest=2 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[02:2020].....\smpd_handle_command
[02:2020]......handling command:
[02:2020]...... src  = 1
[02:2020]...... dest = 2
[02:2020]...... cmd  = result
[02:2020]...... tag  = 3
[02:2020]...... ctx  = parent
[02:2020]...... len  = 85
[02:2020]...... str  = cmd=result src=1 dest=2 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS 
[02:2020]......\smpd_command_destination
[02:2020].......2 -> 2 : returning NULL context
[02:2020]....../smpd_command_destination
[02:2020]......\smpd_handle_result
[02:2020].......forwarding the dbs result command to the pmi context 0.
[02:2020].......\smpd_forward_command
[02:2020]........\smpd_create_command_copy
[02:2020]......../smpd_create_command_copy
[02:2020]........posting write of forwarded command: "cmd=result src=1 dest=2 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[02:2020]........\smpd_post_write_command
[02:2020].........\smpd_package_command
[02:2020]........./smpd_package_command
[02:2020].........smpd_post_write_command on the pmi context sock 1096: 98 bytes for command: "cmd=result src=1 dest=2 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[02:2020]......../smpd_post_write_command
[02:2020]......./smpd_forward_command
[02:2020]....../smpd_handle_result
[02:2020]...../smpd_handle_command
[02:2020].....\smpd_post_read_command
[02:2020]......posting a read for a command header on the parent context, sock 1320
[02:2020]...../smpd_post_read_command
[02:2020]..../smpd_state_reading_cmd
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd_header
[02:2020].....read command header
[02:2020].....command header read, posting read for data: 92 bytes
[02:2020]..../smpd_state_reading_cmd_header
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_WRITE
[02:2020]...\smpd_handle_op_write
[02:2020]....\smpd_state_writing_cmd
[02:2020].....wrote command
[02:2020].....command written to pmi: "cmd=result src=1 dest=2 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[02:2020].....\smpd_free_command
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_free_command
[02:2020]..../smpd_state_writing_cmd
[02:2020].../smpd_handle_op_write
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd
[02:2020].....read command
[02:2020].....\smpd_parse_command
[02:2020]...../smpd_parse_command
[02:2020].....read command: "cmd=barrier src=2 dest=1 tag=2 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB value=2 "
[02:2020].....\smpd_handle_command
[02:2020]......handling command:
[02:2020]...... src  = 2
[02:2020]...... dest = 1
[02:2020]...... cmd  = barrier
[02:2020]...... tag  = 2
[02:2020]...... ctx  = pmi
[02:2020]...... len  = 92
[02:2020]...... str  = cmd=barrier src=2 dest=1 tag=2 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB value=2 
[02:2020]......\smpd_command_destination
[02:2020].......2 -> 1 : returning parent_context: 1 < 2
[02:2020]....../smpd_command_destination
[02:2020]......forwarding command to 1
[02:2020]......\smpd_forward_command
[02:2020].......\smpd_create_command_copy
[02:2020]......./smpd_create_command_copy
[02:2020].......posting write of forwarded command: "cmd=barrier src=2 dest=1 tag=2 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB value=2 "
[02:2020].......\smpd_post_write_command
[02:2020]........\smpd_package_command
[02:2020]......../smpd_package_command
[02:2020]........smpd_post_write_command on the parent context sock 1320: 105 bytes for command: "cmd=barrier src=2 dest=1 tag=2 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB value=2 "
[02:2020]......./smpd_post_write_command
[02:2020]....../smpd_forward_command
[02:2020]...../smpd_handle_command
[02:2020].....\smpd_post_read_command
[02:2020]......posting a read for a command header on the pmi context, sock 1096
[02:2020]...../smpd_post_read_command
[02:2020]..../smpd_state_reading_cmd
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_WRITE
[02:2020]...\smpd_handle_op_write
[02:2020]....\smpd_state_writing_cmd
[02:2020].....wrote command
[02:2020].....command written to parent: "cmd=barrier src=2 dest=1 tag=2 ctx_key=0 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB value=2 "
[02:2020].....\smpd_free_command
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_free_command
[02:2020]..../smpd_state_writing_cmd
[02:2020].../smpd_handle_op_write
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd_header
[02:2020].....read command header
[02:2020].....command header read, posting read for data: 87 bytes
[02:2020]..../smpd_state_reading_cmd_header
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd
[02:2020].....read command
[02:2020].....\smpd_parse_command
[02:2020]...../smpd_parse_command
[02:2020].....read command: "cmd=result src=1 dest=2 tag=5 cmd_tag=2 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:2020].....\smpd_handle_command
[02:2020]......handling command:
[02:2020]...... src  = 1
[02:2020]...... dest = 2
[02:2020]...... cmd  = result
[02:2020]...... tag  = 5
[02:2020]...... ctx  = parent
[02:2020]...... len  = 87
[02:2020]...... str  = cmd=result src=1 dest=2 tag=5 cmd_tag=2 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS 
[02:2020]......\smpd_command_destination
[02:2020].......2 -> 2 : returning NULL context
[02:2020]....../smpd_command_destination
[02:2020]......\smpd_handle_result
[02:2020].......forwarding the dbs result command to the pmi context 0.
[02:2020].......\smpd_forward_command
[02:2020]........\smpd_create_command_copy
[02:2020]......../smpd_create_command_copy
[02:2020]........posting write of forwarded command: "cmd=result src=1 dest=2 tag=5 cmd_tag=2 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:2020]........\smpd_post_write_command
[02:2020].........\smpd_package_command
[02:2020]........./smpd_package_command
[02:2020].........smpd_post_write_command on the pmi context sock 1096: 100 bytes for command: "cmd=result src=1 dest=2 tag=5 cmd_tag=2 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:2020]......../smpd_post_write_command
[02:2020]......./smpd_forward_command
[02:2020]....../smpd_handle_result
[02:2020]...../smpd_handle_command
[02:2020].....\smpd_post_read_command
[02:2020]......posting a read for a command header on the parent context, sock 1320
[02:2020]...../smpd_post_read_command
[02:2020]..../smpd_state_reading_cmd
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_WRITE
[02:2020]...\smpd_handle_op_write
[02:2020]....\smpd_state_writing_cmd
[02:2020].....wrote command
[02:2020].....command written to pmi: "cmd=result src=1 dest=2 tag=5 cmd_tag=2 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:2020].....\smpd_free_command
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_free_command
[02:2020]..../smpd_state_writing_cmd
[02:2020].../smpd_handle_op_write
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd_header
[02:2020].....read command header
[02:2020].....command header read, posting read for data: 105 bytes
[02:2020]..../smpd_state_reading_cmd_header
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd
[02:2020].....read command
[02:2020].....\smpd_parse_command
[02:2020]...../smpd_parse_command
[02:2020].....read command: "cmd=suspend src=0 dest=2 tag=8 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB rank=1 exit_code=123 ctx_key=0 "
[02:2020].....\smpd_handle_command
[02:2020]......handling command:
[02:2020]...... src  = 0
[02:2020]...... dest = 2
[02:2020]...... cmd  = suspend
[02:2020]...... tag  = 8
[02:2020]...... ctx  = parent
[02:2020]...... len  = 105
[02:2020]...... str  = cmd=suspend src=0 dest=2 tag=8 name=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB rank=1 exit_code=123 ctx_key=0 
[02:2020]......\smpd_command_destination
[02:2020].......2 -> 2 : returning NULL context
[02:2020]....../smpd_command_destination
[02:2020]......\smpd_handle_suspend_command
[02:2020].......\smpd_create_command
[02:2020]........\smpd_init_command
[02:2020]......../smpd_init_command
[02:2020]......./smpd_create_command
[02:2020].......\smpd_add_command_int_arg
[02:2020]......./smpd_add_command_int_arg
[02:2020].......\smpd_suspend_process
[02:2020]......./smpd_suspend_process
[02:2020].......\smpd_add_command_arg
[02:2020]......./smpd_add_command_arg
[02:2020].......sending result command to parent context: "cmd=result src=2 dest=0 tag=1 cmd_tag=8 result=SUCCESS "
[02:2020].......\smpd_post_write_command
[02:2020]........\smpd_package_command
[02:2020]......../smpd_package_command
[02:2020]........smpd_post_write_command on the parent context sock 1320: 69 bytes for command: "cmd=result src=2 dest=0 tag=1 cmd_tag=8 result=SUCCESS "
[02:2020]......./smpd_post_write_command
[02:2020]....../smpd_handle_suspend_command
[02:2020]...../smpd_handle_command
[02:2020].....\smpd_post_read_command
[02:2020]......posting a read for a command header on the parent context, sock 1320
[02:2020]...../smpd_post_read_command
[02:2020]..../smpd_state_reading_cmd
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_WRITE
[02:2020]...\smpd_handle_op_write
[02:2020]....\smpd_state_writing_cmd
[02:2020].....wrote command
[02:2020].....command written to parent: "cmd=result src=2 dest=0 tag=1 cmd_tag=8 result=SUCCESS "
[02:2020].....\smpd_free_command
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_free_command
[02:2020]..../smpd_state_writing_cmd
[02:2020].../smpd_handle_op_write
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd_header
[02:2020].....read command header
[02:2020].....command header read, posting read for data: 54 bytes
[02:2020]..../smpd_state_reading_cmd_header
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd
[02:2020].....read command
[02:2020].....\smpd_parse_command
[02:2020]...../smpd_parse_command
[02:2020].....read command: "cmd=kill src=0 dest=2 tag=11 exit_code=123 ctx_key=0 "
[02:2020].....\smpd_handle_command
[02:2020]......handling command:
[02:2020]...... src  = 0
[02:2020]...... dest = 2
[02:2020]...... cmd  = kill
[02:2020]...... tag  = 11
[02:2020]...... ctx  = parent
[02:2020]...... len  = 54
[02:2020]...... str  = cmd=kill src=0 dest=2 tag=11 exit_code=123 ctx_key=0 
[02:2020]......\smpd_command_destination
[02:2020].......2 -> 2 : returning NULL context
[02:2020]....../smpd_command_destination
[02:2020]......\smpd_handle_kill_command
[02:2020].......\smpd_kill_process
[02:2020]........\smpd_process_from_registry
[02:2020]......../smpd_process_from_registry
[02:2020]........ReadFile failed, error 109
[02:2020]........*** smpd_piothread finishing pid:544 ***
[02:2020]........ReadFile failed, error 109
[02:2020]........*** smpd_piothread finishing pid:544 ***
[02:2020]......./smpd_kill_process
[02:2020]....../smpd_handle_kill_command
[02:2020]...../smpd_handle_command
[02:2020].....\smpd_post_read_command
[02:2020]......posting a read for a command header on the parent context, sock 1320
[02:2020]...../smpd_post_read_command
[02:2020]..../smpd_state_reading_cmd
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd_header
[02:2020].....ERROR:unable to read the cmd header on the pmi context, generic socket failure, error stack:
MPIDU_Sock_wait(2603): The specified network name is no longer available. (errno 64).
[02:2020]..../smpd_state_reading_cmd_header
[02:2020].../smpd_handle_op_read
[02:2020]...SOCK_OP_READ failed - result = -1, closing pmi context.
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_stdouterr
[02:2020]..../smpd_state_reading_stdouterr
[02:2020].../smpd_handle_op_read
[02:2020]...SOCK_OP_READ failed - result = -1, closing stderr context.
[02:2020]...closing output socket took 0.041 seconds
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_stdouterr
[02:2020]..../smpd_state_reading_stdouterr
[02:2020].../smpd_handle_op_read
[02:2020]...SOCK_OP_READ failed - result = -1, closing stdout context.
[02:2020]...closing output socket took 0.041 seconds
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_CLOSE
[02:2020]...\smpd_handle_op_close
[02:2020]....\smpd_get_state_string
[02:2020]..../smpd_get_state_string
[02:2020]....op_close received - SMPD_CLOSING state.
[02:2020]....process refcount == 2, pmi closed.
[02:2020]....\smpd_free_context
[02:2020].....freeing pmi context.
[02:2020].....\smpd_init_context
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_init_context
[02:2020]..../smpd_free_context
[02:2020].../smpd_handle_op_close
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_CLOSE
[02:2020]...\smpd_handle_op_close
[02:2020]....\smpd_get_state_string
[02:2020]..../smpd_get_state_string
[02:2020]....op_close received - SMPD_CLOSING state.
[02:2020]....process refcount == 1, stderr closed.
[02:2020]....\smpd_free_context
[02:2020].....freeing stderr context.
[02:2020].....\smpd_init_context
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_init_context
[02:2020]..../smpd_free_context
[02:2020].../smpd_handle_op_close
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_CLOSE
[02:2020]...\smpd_handle_op_close
[02:2020]....\smpd_get_state_string
[02:2020]..../smpd_get_state_string
[02:2020]....op_close received - SMPD_CLOSING state.
[02:2020]....process refcount == 0, waiting for the process to finish exiting.
[02:2020]....\smpd_process_from_registry
[02:2020]..../smpd_process_from_registry
[02:2020]....\smpd_wait_process
[02:2020]..../smpd_wait_process
[02:2020]....*** smpd_pinthread finishing pid:544 ***
[02:2020]....\smpd_create_command
[02:2020].....\smpd_init_command
[02:2020]...../smpd_init_command
[02:2020]..../smpd_create_command
[02:2020]....\smpd_add_command_int_arg
[02:2020]..../smpd_add_command_int_arg
[02:2020]....\smpd_add_command_int_arg
[02:2020]..../smpd_add_command_int_arg
[02:2020]....\smpd_add_command_arg
[02:2020]..../smpd_add_command_arg
[02:2020]....creating an exit command for rank 1, pid 544, exit code 123.
[02:2020]....\smpd_post_write_command
[02:2020].....\smpd_package_command
[02:2020]...../smpd_package_command
[02:2020].....smpd_post_write_command on the parent context sock 1320: 99 bytes for command: "cmd=exit src=2 dest=0 tag=2 rank=1 code=123 kvs=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB "
[02:2020]..../smpd_post_write_command
[02:2020]....\smpd_free_process_struct
[02:2020]..../smpd_free_process_struct
[02:2020]....\smpd_free_context
[02:2020].....freeing stdout context.
[02:2020].....\smpd_init_context
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_init_context
[02:2020]..../smpd_free_context
[02:2020].../smpd_handle_op_close
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_CLOSE
[02:2020]...\smpd_handle_op_close
[02:2020]....\smpd_get_state_string
[02:2020]..../smpd_get_state_string
[02:2020]....op_close received - SMPD_CLOSING state.
[02:2020]....Unaffiliated stdin context closing.
[02:2020]....\smpd_free_context
[02:2020].....freeing stdin context.
[02:2020].....\smpd_init_context
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_init_context
[02:2020]..../smpd_free_context
[02:2020].../smpd_handle_op_close
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_WRITE
[02:2020]...\smpd_handle_op_write
[02:2020]....\smpd_state_writing_cmd
[02:2020].....wrote command
[02:2020].....command written to parent: "cmd=exit src=2 dest=0 tag=2 rank=1 code=123 kvs=4EB53D0A-5F25-460b-82FD-F7003EDBB2EB "
[02:2020].....\smpd_free_command
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_free_command
[02:2020]..../smpd_state_writing_cmd
[02:2020].../smpd_handle_op_write
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd_header
[02:2020].....read command header
[02:2020].....command header read, posting read for data: 31 bytes
[02:2020]..../smpd_state_reading_cmd_header
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_READ
[02:2020]...\smpd_handle_op_read
[02:2020]....\smpd_state_reading_cmd
[02:2020].....read command
[02:2020].....\smpd_parse_command
[02:2020]...../smpd_parse_command
[02:2020].....read command: "cmd=close src=1 dest=2 tag=11 "
[02:2020].....\smpd_handle_command
[02:2020]......handling command:
[02:2020]...... src  = 1
[02:2020]...... dest = 2
[02:2020]...... cmd  = close
[02:2020]...... tag  = 11
[02:2020]...... ctx  = parent
[02:2020]...... len  = 31
[02:2020]...... str  = cmd=close src=1 dest=2 tag=11 
[02:2020]......\smpd_command_destination
[02:2020].......2 -> 2 : returning NULL context
[02:2020]....../smpd_command_destination
[02:2020]......\smpd_handle_close_command
[02:2020].......\smpd_create_command
[02:2020]........\smpd_init_command
[02:2020]......../smpd_init_command
[02:2020]......./smpd_create_command
[02:2020].......sending closed command to parent: "cmd=closed src=2 dest=1 tag=3 "
[02:2020].......\smpd_post_write_command
[02:2020]........\smpd_package_command
[02:2020]......../smpd_package_command
[02:2020]........smpd_post_write_command on the parent context sock 1320: 44 bytes for command: "cmd=closed src=2 dest=1 tag=3 "
[02:2020]......./smpd_post_write_command
[02:2020].......posted closed command.
[02:2020]....../smpd_handle_close_command
[02:2020]...../smpd_handle_command
[02:2020].....not posting read for another command because SMPD_CLOSE returned
[02:2020]..../smpd_state_reading_cmd
[02:2020].../smpd_handle_op_read
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_WRITE
[02:2020]...\smpd_handle_op_write
[02:2020]....\smpd_state_writing_cmd
[02:2020].....wrote command
[02:2020].....command written to parent: "cmd=closed src=2 dest=1 tag=3 "
[02:2020].....closed command written, posting close of the sock.
[02:2020].....MPIDU_Sock_post_close(1320)
[02:2020].....\smpd_free_command
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_free_command
[02:2020]..../smpd_state_writing_cmd
[02:2020].../smpd_handle_op_write
[02:2020]...sock_waiting for the next event.
[02:2020]...SOCK_OP_CLOSE
[02:2020]...\smpd_handle_op_close
[02:2020]....\smpd_get_state_string
[02:2020]..../smpd_get_state_string
[02:2020]....op_close received - SMPD_CLOSING state.
[02:2020]....Unaffiliated parent context closing.
[02:2020]....\smpd_free_context
[02:2020].....freeing parent context.
[02:2020].....\smpd_init_context
[02:2020]......\smpd_init_command
[02:2020]....../smpd_init_command
[02:2020]...../smpd_init_context
[02:2020]..../smpd_free_context
[02:2020]....all contexts closed, exiting state machine.
[02:2020].../smpd_handle_op_close
[02:2020]../smpd_enter_at_state
[02:2020]..\smpd_exit
[02:2020]...\smpd_kill_all_processes
[02:2020].../smpd_kill_all_processes
[02:2020]...\smpd_finalize_drive_maps
[02:2020].../smpd_finalize_drive_maps
[02:2020]...\smpd_dbs_finalize
[02:2020].../smpd_dbs_finalize
[00:248]....SOCK_OP_ACCEPT
[00:248]....\smpd_handle_op_accept
[00:248].....\smpd_state_smpd_listening
[00:248]......authenticating new connection
[00:248]......\smpd_create_context
[00:248].......\smpd_init_context
[00:248]........\smpd_init_command
[00:248]......../smpd_init_command
[00:248]......./smpd_init_context
[00:248]....../smpd_create_context
[00:248]......\smpd_gen_authentication_strings
[00:248].......\smpd_hash
[00:248]......./smpd_hash
[00:248]....../smpd_gen_authentication_strings
[00:248]......posting a write of the challenge string: 1.0.8 19116
[00:248]...../smpd_state_smpd_listening
[00:248]..../smpd_handle_op_accept
[00:248]....sock_waiting for the next event.
[00:248]....SOCK_OP_WRITE
[00:248]....\smpd_handle_op_write
[00:248].....\smpd_state_writing_challenge_string
[00:248]......wrote challenge string: '1.0.8 19116'
[00:248]...../smpd_state_writing_challenge_string
[00:248]..../smpd_handle_op_write
[00:248]....sock_waiting for the next event.
[00:248]....SOCK_OP_READ
[00:248]....\smpd_handle_op_read
[00:248].....\smpd_state_reading_challenge_response
[00:248]......read challenge response: '65416c24ffc131197a93d9ac231e1eab'
[00:248]...../smpd_state_reading_challenge_response
[00:248]..../smpd_handle_op_read
[00:248]....sock_waiting for the next event.
[00:248]....SOCK_OP_WRITE
[00:248]....\smpd_handle_op_write
[00:248].....\smpd_state_writing_connect_result
[00:248]......wrote connect result: 'SUCCESS'
[00:248]...../smpd_state_writing_connect_result
[00:248]..../smpd_handle_op_write
[00:248]....sock_waiting for the next event.
[00:248]....SOCK_OP_READ
[00:248]....\smpd_handle_op_read
[00:248].....\smpd_state_reading_session_request
[00:248]......read session request: 'process'
[00:248]...../smpd_state_reading_session_request
[00:248]..../smpd_handle_op_read
[00:248]....sock_waiting for the next event.
[00:248]....SOCK_OP_WRITE
[00:248]....\smpd_handle_op_write
[00:248].....\smpd_state_writing_no_cred_request
[00:248]......wrote no cred request: 'nocredentials'
[00:248]......\smpd_start_win_mgr
[00:248].......starting command: "c:\MPICH2\bin\smpd.exe" -d 27 -mgr -read 00000618 -write 00000610
[00:248].......CreateProcess
[00:248].......smpd reading the port string from the manager
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt_int
[-1:304]../smpd_get_opt_int
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt_int
[-1:304]../smpd_get_opt_int
[-1:304]..\smpd_get_opt_int
[-1:304]../smpd_get_opt_int
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt
[-1:304]../smpd_get_opt
[-1:304]..\smpd_get_opt_string
[-1:304]../smpd_get_opt_string
[-1:304]..\smpd_get_opt_string
[-1:304]../smpd_get_opt_string
[-1:304]..manager creating listener and session sets.
[-1:304]..created set for manager listener, 1460
[-1:304]..smpd manager listening on port 1059
[-1:304]..\smpd_create_context
[-1:304]...\smpd_init_context
[-1:304]....\smpd_init_command
[-1:304]..../smpd_init_command
[-1:304].../smpd_init_context
[-1:304]../smpd_create_context
[-1:304]..manager writing port back to smpd.
[00:248].......smpd sending the account to the manager
[00:248].......\smpd_hide_string_arg
[00:248]........\first_token
[00:248]......../first_token
[00:248]........\compare_token
[00:248]......../compare_token
[00:248]........\next_token
[00:248].........\first_token
[00:248]........./first_token
[00:248].........\first_token
[00:248]........./first_token
[00:248]......../next_token
[00:248]......./smpd_hide_string_arg
[00:248]......./smpd_hide_string_arg
[00:248].......\smpd_hide_string_arg
[00:248]........\first_token
[00:248]......../first_token
[00:248]........\compare_token
[00:248]......../compare_token
[00:248]........\next_token
[00:248].........\first_token
[00:248]........./first_token
[00:248].........\first_token
[00:248]........./first_token
[00:248]......../next_token
[00:248]........\compare_token
[00:248]......../compare_token
[00:248]........\compare_token
[00:248]......../compare_token
[00:248]........\next_token
[00:248].........\first_token
[00:248]........./first_token
[00:248].........\first_token
[00:248]........./first_token
[00:248]......../next_token
[00:248]........\compare_token
[00:248]......../compare_token
[00:248]........\next_token
[00:248].........\first_token
[00:248]........./first_token
[00:248].........\first_token
[00:248]........./first_token
[00:248]......../next_token
[00:248]........\compare_token
[00:248]......../compare_token
[00:248]........\next_token
[00:248].........\first_token
[00:248]........./first_token
[00:248].........\first_token
[00:248]........./first_token
[00:248]......../next_token
[00:248]......./smpd_hide_string_arg
[00:248]......./smpd_hide_string_arg
[-1:304]..\smpd_hide_string_arg
[-1:304]...\first_token
[-1:304].../first_token
[-1:304]...\compare_token
[-1:304].../compare_token
[-1:304]...\next_token
[-1:304]....\first_token
[-1:304]..../first_token
[-1:304]....\first_token
[-1:304]..../first_token
[-1:304].../next_token
[-1:304]...\compare_token
[-1:304].../compare_token
[-1:304]...\compare_token
[-1:304].../compare_token
[-1:304]...\next_token
[-1:304]....\first_token
[-1:304]..../first_token
[-1:304]....\first_token
[-1:304]..../first_token
[-1:304].../next_token
[-1:304]...\compare_token
[-1:304].../compare_token
[-1:304]...\next_token
[-1:304]....\first_token
[-1:304]..../first_token
[-1:304]....\first_token
[-1:304]..../first_token
[-1:304].../next_token
[-1:304]../smpd_hide_string_arg
[-1:304]../smpd_hide_string_arg
[00:248].......closing the pipe to the manager
[-1:304]..\smpd_enter_at_state
[-1:304]...sock_waiting for the next event.
[00:248].......smpd writing reconnect request: port 1059
[00:248]....../smpd_state_writing_no_cred_request
[00:248]...../smpd_handle_op_write
[00:248].....sock_waiting for the next event.
[00:248].....SOCK_OP_WRITE
[00:248].....\smpd_handle_op_write
[00:248]......\smpd_state_writing_reconnect_request
[00:248].......wrote reconnect request: '1059'
[00:248]....../smpd_state_writing_reconnect_request
[00:248]...../smpd_handle_op_write
[00:248].....sock_waiting for the next event.
[00:248].....SOCK_OP_CLOSE
[00:248].....\smpd_handle_op_close
[00:248]......\smpd_get_state_string
[00:248]....../smpd_get_state_string
[00:248]......op_close received - SMPD_CLOSING state.
[00:248]......Unaffiliated undetermined context closing.
[00:248]......\smpd_free_context
[00:248].......freeing undetermined context.
[00:248].......\smpd_init_context
[00:248]........\smpd_init_command
[00:248]......../smpd_init_command
[00:248]......./smpd_init_context
[00:248]....../smpd_free_context
[00:248]...../smpd_handle_op_close
[00:248].....sock_waiting for the next event.
[-1:304]...SOCK_OP_ACCEPT
[-1:304]...\smpd_handle_op_accept
[-1:304]....\smpd_state_mgr_listening
[-1:304].....accepted re-connection
[-1:304].....\smpd_create_context
[-1:304]......\smpd_init_context
[-1:304].......\smpd_init_command
[-1:304]......./smpd_init_command
[-1:304]....../smpd_init_context
[-1:304]...../smpd_create_context
[-1:304].....closing the mgr listener.
[-1:304]..../smpd_state_mgr_listening
[-1:304].../smpd_handle_op_accept
[-1:304]...sock_waiting for the next event.
[-1:304]...SOCK_OP_CLOSE
[-1:304]...\smpd_handle_op_close
[-1:304]....\smpd_get_state_string
[-1:304]..../smpd_get_state_string
[-1:304]....op_close received - SMPD_MGR_LISTENING state.
[-1:304]....\smpd_free_context
[-1:304].....freeing listener context.
[-1:304].....\smpd_init_context
[-1:304]......\smpd_init_command
[-1:304]....../smpd_init_command
[-1:304]...../smpd_init_context
[-1:304]..../smpd_free_context
[-1:304].../smpd_handle_op_close
[-1:304]...sock_waiting for the next event.
[-1:304]...SOCK_OP_READ
[-1:304]...\smpd_handle_op_read
[-1:304]....\smpd_state_reading_session_header
[-1:304].....read session header: 'id=2 parent=1 level=1'
[-1:304].....\smpd_interpret_session_header
[-1:304]......interpreting session header: "id=2 parent=1 level=1"
[-1:304]...... id = 2
[02:304]...... parent = 1
[02:304]...... level = 1
[02:304]...../smpd_interpret_session_header
[02:304].....\smpd_post_read_command
[02:304]......posting a read for a command header on the parent context, sock 1320
[02:304]...../smpd_post_read_command
[02:304]..../smpd_state_reading_session_header
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd_header
[02:304].....read command header
[02:304].....command header read, posting read for data: 180 bytes
[02:304]..../smpd_state_reading_cmd_header
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd
[02:304].....read command
[02:304].....\smpd_parse_command
[02:304]...../smpd_parse_command
[02:304].....read command: "cmd=launch src=0 dest=2 tag=2 c=c:\MPICH2\examples\cpi.exe s=0 a=0 d=C:\MPICH2\examples q=1 i=1 n=2 k=4F8C828E-90AD-42bd-90FC-9FC265FD3814 kd=37654993-6CA6-4597-AAFF-C750DAB04F9B "
[02:304].....\smpd_handle_command
[02:304]......handling command:
[02:304]...... src  = 0
[02:304]...... dest = 2
[02:304]...... cmd  = launch
[02:304]...... tag  = 2
[02:304]...... ctx  = parent
[02:304]...... len  = 180
[02:304]...... str  = cmd=launch src=0 dest=2 tag=2 c=c:\MPICH2\examples\cpi.exe s=0 a=0 d=C:\MPICH2\examples q=1 i=1 n=2 k=4F8C828E-90AD-42bd-90FC-9FC265FD3814 kd=37654993-6CA6-4597-AAFF-C750DAB04F9B 
[02:304]......\smpd_command_destination
[02:304].......2 -> 2 : returning NULL context
[02:304]....../smpd_command_destination
[02:304]......\smpd_handle_launch_command
[02:304].......\smpd_create_process_struct
[02:304]........\smpd_create_context
[02:304].........\smpd_init_context
[02:304]..........\smpd_init_command
[02:304]........../smpd_init_command
[02:304]........./smpd_init_context
[02:304]......../smpd_create_context
[02:304]........\smpd_create_context
[02:304].........\smpd_init_context
[02:304]..........\smpd_init_command
[02:304]........../smpd_init_command
[02:304]........./smpd_init_context
[02:304]......../smpd_create_context
[02:304]........\smpd_create_context
[02:304].........\smpd_init_context
[02:304]..........\smpd_init_command
[02:304]........../smpd_init_command
[02:304]........./smpd_init_context
[02:304]......../smpd_create_context
[02:304]........\smpd_create_context
[02:304].........\smpd_init_context
[02:304]..........\smpd_init_command
[02:304]........../smpd_init_command
[02:304]........./smpd_init_context
[02:304]......../smpd_create_context
[02:304]......./smpd_create_process_struct
[02:304].......launching: 'c:\MPICH2\examples\cpi.exe'
[02:304].......\smpd_launch_process
[02:304]........\smpd_priority_class_to_win_class
[02:304]......../smpd_priority_class_to_win_class
[02:304]........\smpd_priority_to_win_priority
[02:304]......../smpd_priority_to_win_priority
[02:304]........\smpd_make_socket_loop_choose
[02:304].........\smpd_get_hostname
[02:304]........./smpd_get_hostname
[02:304]......../smpd_make_socket_loop_choose
[02:304]........\smpd_make_socket_loop_choose
[02:304].........\smpd_get_hostname
[02:304]........./smpd_get_hostname
[02:304]......../smpd_make_socket_loop_choose
[02:304]........\smpd_make_socket_loop_choose
[02:304].........\smpd_get_hostname
[02:304]........./smpd_get_hostname
[02:304]......../smpd_make_socket_loop_choose
[02:304]........pmi listening on port 1066
[02:304]........\smpd_create_context
[02:304].........\smpd_init_context
[02:304]..........\smpd_init_command
[02:304]........../smpd_init_command
[02:304]........./smpd_init_context
[02:304]......../smpd_create_context
[02:304]........\SetEnvironmentVariables
[02:304]......../SetEnvironmentVariables
[02:304]........env: PMI_RANK=1
[02:304]........env: PMI_SIZE=2
[02:304]........env: PMI_KVS=4F8C828E-90AD-42bd-90FC-9FC265FD3814
[02:304]........env: PMI_DOMAIN=37654993-6CA6-4597-AAFF-C750DAB04F9B
[02:304]........env: PMI_HOST=rtis08.etf.bg.ac.yu 147.91.12.151 
[02:304]........env: PMI_PORT=1066
[02:304]........env: PMI_SMPD_ID=2
[02:304]........env: PMI_SMPD_KEY=0
[02:304]........env: PMI_CLIQUE=1
[02:304]........env: PMI_SPAWN=0
[02:304]........env: PMI_APPNUM=0
[02:304]........CreateProcess(c:\MPICH2\examples\cpi.exe)
[02:304]........\RemoveEnvironmentVariables
[02:304]......../RemoveEnvironmentVariables
[02:304]........\smpd_process_to_registry
[02:304]......../smpd_process_to_registry
[02:304]......./smpd_launch_process
[02:304].......\smpd_create_command
[02:304]........\smpd_init_command
[02:304]......../smpd_init_command
[02:304]......./smpd_create_command
[02:304].......\smpd_add_command_int_arg
[02:304].......*** entering smpd_pinthread pid:568 sock:1288 ***
[02:304].......*** entering smpd_piothread pid:568 sock:1276 ***
[02:304].......*** entering smpd_piothread pid:568 sock:1252 ***
[02:304]......./smpd_add_command_int_arg
[02:304].......\smpd_add_command_arg
[02:304]......./smpd_add_command_arg
[02:304].......\smpd_add_command_arg
[02:304]......./smpd_add_command_arg
[02:304].......\smpd_add_command_arg
[02:304]......./smpd_add_command_arg
[02:304].......\smpd_add_command_int_arg
[02:304]......./smpd_add_command_int_arg
[02:304].......\smpd_add_command_int_arg
[02:304]......./smpd_add_command_int_arg
[02:304].......\smpd_post_write_command
[02:304]........\smpd_package_command
[02:304]......../smpd_package_command
[02:304]........smpd_post_write_command on the parent context sock 1320: 147 bytes for command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=4F8C828E-90AD-42bd-90FC-9FC265FD3814 pg_rank=1 pg_ctx=0 "
[02:304]......./smpd_post_write_command
[02:304]....../smpd_handle_launch_command
[02:304]...../smpd_handle_command
[02:304].....\smpd_post_read_command
[02:304]......posting a read for a command header on the parent context, sock 1320
[02:304]...../smpd_post_read_command
[02:304]..../smpd_state_reading_cmd
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_WRITE
[02:304]...\smpd_handle_op_write
[02:304]....\smpd_state_writing_cmd
[02:304].....wrote command
[02:304].....command written to parent: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=4F8C828E-90AD-42bd-90FC-9FC265FD3814 pg_rank=1 pg_ctx=0 "
[02:304].....\smpd_free_command
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_free_command
[02:304]..../smpd_state_writing_cmd
[02:304].../smpd_handle_op_write
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_ACCEPT
[02:304]...\smpd_handle_op_accept
[02:304]....\smpd_state_pmi_listening
[02:304].....\smpd_post_read_command
[02:304]......posting a read for a command header on the pmi context, sock 1096
[02:304]...../smpd_post_read_command
[02:304]..../smpd_state_pmi_listening
[02:304].../smpd_handle_op_accept
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_CLOSE
[02:304]...\smpd_handle_op_close
[02:304]....\smpd_get_state_string
[02:304]..../smpd_get_state_string
[02:304]....op_close received - SMPD_CLOSING state.
[02:304]....Unaffiliated PMI_LISTENER context closing.
[02:304]....\smpd_free_context
[02:304].....freeing PMI_LISTENER context.
[02:304].....\smpd_init_context
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_init_context
[02:304]..../smpd_free_context
[02:304].../smpd_handle_op_close
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd_header
[02:304].....read command header
[02:304].....command header read, posting read for data: 105 bytes
[02:304]..../smpd_state_reading_cmd_header
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd
[02:304].....read command
[02:304].....\smpd_parse_command
[02:304]...../smpd_parse_command
[02:304].....read command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 key=1 value=2 node_id=2 "
[02:304].....\smpd_handle_command
[02:304]......handling command:
[02:304]...... src  = 2
[02:304]...... dest = 0
[02:304]...... cmd  = init
[02:304]...... tag  = 0
[02:304]...... ctx  = pmi
[02:304]...... len  = 105
[02:304]...... str  = cmd=init src=2 dest=0 tag=0 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 key=1 value=2 node_id=2 
[02:304]......\smpd_command_destination
[02:304].......2 -> 0 : returning parent_context: 0 < 2
[02:304]....../smpd_command_destination
[02:304]......forwarding command to 1
[02:304]......\smpd_forward_command
[02:304].......\smpd_create_command_copy
[02:304]......./smpd_create_command_copy
[02:304].......posting write of forwarded command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 key=1 value=2 node_id=2 "
[02:304].......\smpd_post_write_command
[02:304]........\smpd_package_command
[02:304]......../smpd_package_command
[02:304]........smpd_post_write_command on the parent context sock 1320: 118 bytes for command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 key=1 value=2 node_id=2 "
[02:304]......./smpd_post_write_command
[02:304]....../smpd_forward_command
[02:304]...../smpd_handle_command
[02:304].....\smpd_post_read_command
[02:304]......posting a read for a command header on the pmi context, sock 1096
[02:304]...../smpd_post_read_command
[02:304]..../smpd_state_reading_cmd
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_WRITE
[02:304]...\smpd_handle_op_write
[02:304]....\smpd_state_writing_cmd
[02:304].....wrote command
[02:304].....command written to parent: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 key=1 value=2 node_id=2 "
[02:304].....\smpd_free_command
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_free_command
[02:304]..../smpd_state_writing_cmd
[02:304].../smpd_handle_op_write
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd_header
[02:304].....read command header
[02:304].....command header read, posting read for data: 66 bytes
[02:304]..../smpd_state_reading_cmd_header
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd
[02:304].....read command
[02:304].....\smpd_parse_command
[02:304]...../smpd_parse_command
[02:304].....read command: "cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[02:304].....\smpd_handle_command
[02:304]......handling command:
[02:304]...... src  = 0
[02:304]...... dest = 2
[02:304]...... cmd  = result
[02:304]...... tag  = 4
[02:304]...... ctx  = parent
[02:304]...... len  = 66
[02:304]...... str  = cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS 
[02:304]......\smpd_command_destination
[02:304].......2 -> 2 : returning NULL context
[02:304]....../smpd_command_destination
[02:304]......\smpd_handle_result
[02:304].......forwarding the dbs result command to the pmi context 0.
[02:304].......\smpd_forward_command
[02:304]........\smpd_create_command_copy
[02:304]......../smpd_create_command_copy
[02:304]........posting write of forwarded command: "cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[02:304]........\smpd_post_write_command
[02:304].........\smpd_package_command
[02:304]........./smpd_package_command
[02:304].........smpd_post_write_command on the pmi context sock 1096: 79 bytes for command: "cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[02:304]......../smpd_post_write_command
[02:304]......./smpd_forward_command
[02:304]....../smpd_handle_result
[02:304]...../smpd_handle_command
[02:304].....\smpd_post_read_command
[02:304]......posting a read for a command header on the parent context, sock 1320
[02:304]...../smpd_post_read_command
[02:304]..../smpd_state_reading_cmd
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_WRITE
[02:304]...\smpd_handle_op_write
[02:304]....\smpd_state_writing_cmd
[02:304].....wrote command
[02:304].....command written to pmi: "cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS "
[02:304].....\smpd_free_command
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_free_command
[02:304]..../smpd_state_writing_cmd
[02:304].../smpd_handle_op_write
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd_header
[02:304].....read command header
[02:304].....command header read, posting read for data: 174 bytes
[02:304]..../smpd_state_reading_cmd_header
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd
[02:304].....read command
[02:304].....\smpd_parse_command
[02:304]...../smpd_parse_command
[02:304].....read command: "cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 key=P1-businesscard value="port=1068 description=rtis08.etf.bg.ac.yu ifname=147.91.12.151 " "
[02:304].....\smpd_handle_command
[02:304]......handling command:
[02:304]...... src  = 2
[02:304]...... dest = 1
[02:304]...... cmd  = dbput
[02:304]...... tag  = 1
[02:304]...... ctx  = pmi
[02:304]...... len  = 174
[02:304]...... str  = cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 key=P1-businesscard value="port=1068 description=rtis08.etf.bg.ac.yu ifname=147.91.12.151 " 
[02:304]......\smpd_command_destination
[02:304].......2 -> 1 : returning parent_context: 1 < 2
[02:304]....../smpd_command_destination
[02:304]......forwarding command to 1
[02:304]......\smpd_forward_command
[02:304].......\smpd_create_command_copy
[02:304]......./smpd_create_command_copy
[02:304].......posting write of forwarded command: "cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 key=P1-businesscard value="port=1068 description=rtis08.etf.bg.ac.yu ifname=147.91.12.151 " "
[02:304].......\smpd_post_write_command
[02:304]........\smpd_package_command
[02:304]......../smpd_package_command
[02:304]........smpd_post_write_command on the parent context sock 1320: 187 bytes for command: "cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 key=P1-businesscard value="port=1068 description=rtis08.etf.bg.ac.yu ifname=147.91.12.151 " "
[02:304]......./smpd_post_write_command
[02:304]....../smpd_forward_command
[02:304]...../smpd_handle_command
[02:304].....\smpd_post_read_command
[02:304]......posting a read for a command header on the pmi context, sock 1096
[02:304]...../smpd_post_read_command
[02:304]..../smpd_state_reading_cmd
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_WRITE
[02:304]...\smpd_handle_op_write
[02:304]....\smpd_state_writing_cmd
[02:304].....wrote command
[02:304].....command written to parent: "cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 key=P1-businesscard value="port=1068 description=rtis08.etf.bg.ac.yu ifname=147.91.12.151 " "
[02:304].....\smpd_free_command
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_free_command
[02:304]..../smpd_state_writing_cmd
[02:304].../smpd_handle_op_write
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd_header
[02:304].....read command header
[02:304].....command header read, posting read for data: 85 bytes
[02:304]..../smpd_state_reading_cmd_header
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd
[02:304].....read command
[02:304].....\smpd_parse_command
[02:304]...../smpd_parse_command
[02:304].....read command: "cmd=result src=1 dest=2 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[02:304].....\smpd_handle_command
[02:304]......handling command:
[02:304]...... src  = 1
[02:304]...... dest = 2
[02:304]...... cmd  = result
[02:304]...... tag  = 3
[02:304]...... ctx  = parent
[02:304]...... len  = 85
[02:304]...... str  = cmd=result src=1 dest=2 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS 
[02:304]......\smpd_command_destination
[02:304].......2 -> 2 : returning NULL context
[02:304]....../smpd_command_destination
[02:304]......\smpd_handle_result
[02:304].......forwarding the dbs result command to the pmi context 0.
[02:304].......\smpd_forward_command
[02:304]........\smpd_create_command_copy
[02:304]......../smpd_create_command_copy
[02:304]........posting write of forwarded command: "cmd=result src=1 dest=2 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[02:304]........\smpd_post_write_command
[02:304].........\smpd_package_command
[02:304]........./smpd_package_command
[02:304].........smpd_post_write_command on the pmi context sock 1096: 98 bytes for command: "cmd=result src=1 dest=2 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[02:304]......../smpd_post_write_command
[02:304]......./smpd_forward_command
[02:304]....../smpd_handle_result
[02:304]...../smpd_handle_command
[02:304].....\smpd_post_read_command
[02:304]......posting a read for a command header on the parent context, sock 1320
[02:304]...../smpd_post_read_command
[02:304]..../smpd_state_reading_cmd
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd_header
[02:304].....read command header
[02:304].....command header read, posting read for data: 92 bytes
[02:304]..../smpd_state_reading_cmd_header
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_WRITE
[02:304]...\smpd_handle_op_write
[02:304]....\smpd_state_writing_cmd
[02:304].....wrote command
[02:304].....command written to pmi: "cmd=result src=1 dest=2 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS "
[02:304].....\smpd_free_command
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_free_command
[02:304]..../smpd_state_writing_cmd
[02:304].../smpd_handle_op_write
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd
[02:304].....read command
[02:304].....\smpd_parse_command
[02:304]...../smpd_parse_command
[02:304].....read command: "cmd=barrier src=2 dest=1 tag=2 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 value=2 "
[02:304].....\smpd_handle_command
[02:304]......handling command:
[02:304]...... src  = 2
[02:304]...... dest = 1
[02:304]...... cmd  = barrier
[02:304]...... tag  = 2
[02:304]...... ctx  = pmi
[02:304]...... len  = 92
[02:304]...... str  = cmd=barrier src=2 dest=1 tag=2 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 value=2 
[02:304]......\smpd_command_destination
[02:304].......2 -> 1 : returning parent_context: 1 < 2
[02:304]....../smpd_command_destination
[02:304]......forwarding command to 1
[02:304]......\smpd_forward_command
[02:304].......\smpd_create_command_copy
[02:304]......./smpd_create_command_copy
[02:304].......posting write of forwarded command: "cmd=barrier src=2 dest=1 tag=2 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 value=2 "
[02:304].......\smpd_post_write_command
[02:304]........\smpd_package_command
[02:304]......../smpd_package_command
[02:304]........smpd_post_write_command on the parent context sock 1320: 105 bytes for command: "cmd=barrier src=2 dest=1 tag=2 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 value=2 "
[02:304]......./smpd_post_write_command
[02:304]....../smpd_forward_command
[02:304]...../smpd_handle_command
[02:304].....\smpd_post_read_command
[02:304]......posting a read for a command header on the pmi context, sock 1096
[02:304]...../smpd_post_read_command
[02:304]..../smpd_state_reading_cmd
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_WRITE
[02:304]...\smpd_handle_op_write
[02:304]....\smpd_state_writing_cmd
[02:304].....wrote command
[02:304].....command written to parent: "cmd=barrier src=2 dest=1 tag=2 ctx_key=0 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 value=2 "
[02:304].....\smpd_free_command
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_free_command
[02:304]..../smpd_state_writing_cmd
[02:304].../smpd_handle_op_write
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd_header
[02:304].....read command header
[02:304].....command header read, posting read for data: 87 bytes
[02:304]..../smpd_state_reading_cmd_header
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd
[02:304].....read command
[02:304].....\smpd_parse_command
[02:304]...../smpd_parse_command
[02:304].....read command: "cmd=result src=1 dest=2 tag=5 cmd_tag=2 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:304].....\smpd_handle_command
[02:304]......handling command:
[02:304]...... src  = 1
[02:304]...... dest = 2
[02:304]...... cmd  = result
[02:304]...... tag  = 5
[02:304]...... ctx  = parent
[02:304]...... len  = 87
[02:304]...... str  = cmd=result src=1 dest=2 tag=5 cmd_tag=2 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS 
[02:304]......\smpd_command_destination
[02:304].......2 -> 2 : returning NULL context
[02:304]....../smpd_command_destination
[02:304]......\smpd_handle_result
[02:304].......forwarding the dbs result command to the pmi context 0.
[02:304].......\smpd_forward_command
[02:304]........\smpd_create_command_copy
[02:304]......../smpd_create_command_copy
[02:304]........posting write of forwarded command: "cmd=result src=1 dest=2 tag=5 cmd_tag=2 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:304]........\smpd_post_write_command
[02:304].........\smpd_package_command
[02:304]........./smpd_package_command
[02:304].........smpd_post_write_command on the pmi context sock 1096: 100 bytes for command: "cmd=result src=1 dest=2 tag=5 cmd_tag=2 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:304]......../smpd_post_write_command
[02:304]......./smpd_forward_command
[02:304]....../smpd_handle_result
[02:304]...../smpd_handle_command
[02:304].....\smpd_post_read_command
[02:304]......posting a read for a command header on the parent context, sock 1320
[02:304]...../smpd_post_read_command
[02:304]..../smpd_state_reading_cmd
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_WRITE
[02:304]...\smpd_handle_op_write
[02:304]....\smpd_state_writing_cmd
[02:304].....wrote command
[02:304].....command written to pmi: "cmd=result src=1 dest=2 tag=5 cmd_tag=2 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS "
[02:304].....\smpd_free_command
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_free_command
[02:304]..../smpd_state_writing_cmd
[02:304].../smpd_handle_op_write
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd_header
[02:304].....read command header
[02:304].....command header read, posting read for data: 105 bytes
[02:304]..../smpd_state_reading_cmd_header
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd
[02:304].....read command
[02:304].....\smpd_parse_command
[02:304]...../smpd_parse_command
[02:304].....read command: "cmd=suspend src=0 dest=2 tag=7 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 rank=1 exit_code=123 ctx_key=0 "
[02:304].....\smpd_handle_command
[02:304]......handling command:
[02:304]...... src  = 0
[02:304]...... dest = 2
[02:304]...... cmd  = suspend
[02:304]...... tag  = 7
[02:304]...... ctx  = parent
[02:304]...... len  = 105
[02:304]...... str  = cmd=suspend src=0 dest=2 tag=7 name=4F8C828E-90AD-42bd-90FC-9FC265FD3814 rank=1 exit_code=123 ctx_key=0 
[02:304]......\smpd_command_destination
[02:304].......2 -> 2 : returning NULL context
[02:304]....../smpd_command_destination
[02:304]......\smpd_handle_suspend_command
[02:304].......\smpd_create_command
[02:304]........\smpd_init_command
[02:304]......../smpd_init_command
[02:304]......./smpd_create_command
[02:304].......\smpd_add_command_int_arg
[02:304]......./smpd_add_command_int_arg
[02:304].......\smpd_suspend_process
[02:304]......./smpd_suspend_process
[02:304].......\smpd_add_command_arg
[02:304]......./smpd_add_command_arg
[02:304].......sending result command to parent context: "cmd=result src=2 dest=0 tag=1 cmd_tag=7 result=SUCCESS "
[02:304].......\smpd_post_write_command
[02:304]........\smpd_package_command
[02:304]......../smpd_package_command
[02:304]........smpd_post_write_command on the parent context sock 1320: 69 bytes for command: "cmd=result src=2 dest=0 tag=1 cmd_tag=7 result=SUCCESS "
[02:304]......./smpd_post_write_command
[02:304]....../smpd_handle_suspend_command
[02:304]...../smpd_handle_command
[02:304].....\smpd_post_read_command
[02:304]......posting a read for a command header on the parent context, sock 1320
[02:304]...../smpd_post_read_command
[02:304]..../smpd_state_reading_cmd
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_WRITE
[02:304]...\smpd_handle_op_write
[02:304]....\smpd_state_writing_cmd
[02:304].....wrote command
[02:304].....command written to parent: "cmd=result src=2 dest=0 tag=1 cmd_tag=7 result=SUCCESS "
[02:304].....\smpd_free_command
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_free_command
[02:304]..../smpd_state_writing_cmd
[02:304].../smpd_handle_op_write
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd_header
[02:304].....read command header
[02:304].....command header read, posting read for data: 54 bytes
[02:304]..../smpd_state_reading_cmd_header
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd
[02:304].....read command
[02:304].....\smpd_parse_command
[02:304]...../smpd_parse_command
[02:304].....read command: "cmd=kill src=0 dest=2 tag=10 exit_code=123 ctx_key=0 "
[02:304].....\smpd_handle_command
[02:304]......handling command:
[02:304]...... src  = 0
[02:304]...... dest = 2
[02:304]...... cmd  = kill
[02:304]...... tag  = 10
[02:304]...... ctx  = parent
[02:304]...... len  = 54
[02:304]...... str  = cmd=kill src=0 dest=2 tag=10 exit_code=123 ctx_key=0 
[02:304]......\smpd_command_destination
[02:304].......2 -> 2 : returning NULL context
[02:304]....../smpd_command_destination
[02:304]......\smpd_handle_kill_command
[02:304].......\smpd_kill_process
[02:304]........\smpd_process_from_registry
[02:304]......../smpd_process_from_registry
[02:304]........ReadFile failed, error 109
[02:304]........*** smpd_piothread finishing pid:568 ***
[02:304]........ReadFile failed, error 109
[02:304]........*** smpd_piothread finishing pid:568 ***
[02:304]......./smpd_kill_process
[02:304]....../smpd_handle_kill_command
[02:304]...../smpd_handle_command
[02:304].....\smpd_post_read_command
[02:304]......posting a read for a command header on the parent context, sock 1320
[02:304]...../smpd_post_read_command
[02:304]..../smpd_state_reading_cmd
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd_header
[02:304].....ERROR:unable to read the cmd header on the pmi context, generic socket failure, error stack:
MPIDU_Sock_wait(2603): The specified network name is no longer available. (errno 64).
[02:304]..../smpd_state_reading_cmd_header
[02:304].../smpd_handle_op_read
[02:304]...SOCK_OP_READ failed - result = -1, closing pmi context.
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_stdouterr
[02:304]..../smpd_state_reading_stdouterr
[02:304].../smpd_handle_op_read
[02:304]...SOCK_OP_READ failed - result = -1, closing stderr context.
[02:304]...closing output socket took 0.001 seconds
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_stdouterr
[02:304]..../smpd_state_reading_stdouterr
[02:304].../smpd_handle_op_read
[02:304]...SOCK_OP_READ failed - result = -1, closing stdout context.
[02:304]...closing output socket took 0.001 seconds
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_CLOSE
[02:304]...\smpd_handle_op_close
[02:304]....\smpd_get_state_string
[02:304]..../smpd_get_state_string
[02:304]....op_close received - SMPD_CLOSING state.
[02:304]....process refcount == 2, pmi closed.
[02:304]....\smpd_free_context
[02:304].....freeing pmi context.
[02:304].....\smpd_init_context
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_init_context
[02:304]..../smpd_free_context
[02:304].../smpd_handle_op_close
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_CLOSE
[02:304]...\smpd_handle_op_close
[02:304]....\smpd_get_state_string
[02:304]..../smpd_get_state_string
[02:304]....op_close received - SMPD_CLOSING state.
[02:304]....process refcount == 1, stderr closed.
[02:304]....\smpd_free_context
[02:304].....freeing stderr context.
[02:304].....\smpd_init_context
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_init_context
[02:304]..../smpd_free_context
[02:304].../smpd_handle_op_close
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_CLOSE
[02:304]...\smpd_handle_op_close
[02:304]....\smpd_get_state_string
[02:304]..../smpd_get_state_string
[02:304]....op_close received - SMPD_CLOSING state.
[02:304]....process refcount == 0, waiting for the process to finish exiting.
[02:304]....\smpd_process_from_registry
[02:304]..../smpd_process_from_registry
[02:304]....\smpd_wait_process
[02:304]..../smpd_wait_process
[02:304]....*** smpd_pinthread finishing pid:568 ***
[02:304]....\smpd_create_command
[02:304].....\smpd_init_command
[02:304]...../smpd_init_command
[02:304]..../smpd_create_command
[02:304]....\smpd_add_command_int_arg
[02:304]..../smpd_add_command_int_arg
[02:304]....\smpd_add_command_int_arg
[02:304]..../smpd_add_command_int_arg
[02:304]....\smpd_add_command_arg
[02:304]..../smpd_add_command_arg
[02:304]....creating an exit command for rank 1, pid 568, exit code 123.
[02:304]....\smpd_post_write_command
[02:304].....\smpd_package_command
[02:304]...../smpd_package_command
[02:304].....smpd_post_write_command on the parent context sock 1320: 99 bytes for command: "cmd=exit src=2 dest=0 tag=2 rank=1 code=123 kvs=4F8C828E-90AD-42bd-90FC-9FC265FD3814 "
[02:304]..../smpd_post_write_command
[02:304]....\smpd_free_process_struct
[02:304]..../smpd_free_process_struct
[02:304]....\smpd_free_context
[02:304].....freeing stdout context.
[02:304].....\smpd_init_context
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_init_context
[02:304]..../smpd_free_context
[02:304].../smpd_handle_op_close
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_CLOSE
[02:304]...\smpd_handle_op_close
[02:304]....\smpd_get_state_string
[02:304]..../smpd_get_state_string
[02:304]....op_close received - SMPD_CLOSING state.
[02:304]....Unaffiliated stdin context closing.
[02:304]....\smpd_free_context
[02:304].....freeing stdin context.
[02:304].....\smpd_init_context
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_init_context
[02:304]..../smpd_free_context
[02:304].../smpd_handle_op_close
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_WRITE
[02:304]...\smpd_handle_op_write
[02:304]....\smpd_state_writing_cmd
[02:304].....wrote command
[02:304].....command written to parent: "cmd=exit src=2 dest=0 tag=2 rank=1 code=123 kvs=4F8C828E-90AD-42bd-90FC-9FC265FD3814 "
[02:304].....\smpd_free_command
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_free_command
[02:304]..../smpd_state_writing_cmd
[02:304].../smpd_handle_op_write
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd_header
[02:304].....read command header
[02:304].....command header read, posting read for data: 31 bytes
[02:304]..../smpd_state_reading_cmd_header
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_READ
[02:304]...\smpd_handle_op_read
[02:304]....\smpd_state_reading_cmd
[02:304].....read command
[02:304].....\smpd_parse_command
[02:304]...../smpd_parse_command
[02:304].....read command: "cmd=close src=1 dest=2 tag=10 "
[02:304].....\smpd_handle_command
[02:304]......handling command:
[02:304]...... src  = 1
[02:304]...... dest = 2
[02:304]...... cmd  = close
[02:304]...... tag  = 10
[02:304]...... ctx  = parent
[02:304]...... len  = 31
[02:304]...... str  = cmd=close src=1 dest=2 tag=10 
[02:304]......\smpd_command_destination
[02:304].......2 -> 2 : returning NULL context
[02:304]....../smpd_command_destination
[02:304]......\smpd_handle_close_command
[02:304].......\smpd_create_command
[02:304]........\smpd_init_command
[02:304]......../smpd_init_command
[02:304]......./smpd_create_command
[02:304].......sending closed command to parent: "cmd=closed src=2 dest=1 tag=3 "
[02:304].......\smpd_post_write_command
[02:304]........\smpd_package_command
[02:304]......../smpd_package_command
[02:304]........smpd_post_write_command on the parent context sock 1320: 44 bytes for command: "cmd=closed src=2 dest=1 tag=3 "
[02:304]......./smpd_post_write_command
[02:304].......posted closed command.
[02:304]....../smpd_handle_close_command
[02:304]...../smpd_handle_command
[02:304].....not posting read for another command because SMPD_CLOSE returned
[02:304]..../smpd_state_reading_cmd
[02:304].../smpd_handle_op_read
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_WRITE
[02:304]...\smpd_handle_op_write
[02:304]....\smpd_state_writing_cmd
[02:304].....wrote command
[02:304].....command written to parent: "cmd=closed src=2 dest=1 tag=3 "
[02:304].....closed command written, posting close of the sock.
[02:304].....MPIDU_Sock_post_close(1320)
[02:304].....\smpd_free_command
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_free_command
[02:304]..../smpd_state_writing_cmd
[02:304].../smpd_handle_op_write
[02:304]...sock_waiting for the next event.
[02:304]...SOCK_OP_CLOSE
[02:304]...\smpd_handle_op_close
[02:304]....\smpd_get_state_string
[02:304]..../smpd_get_state_string
[02:304]....op_close received - SMPD_CLOSING state.
[02:304]....Unaffiliated parent context closing.
[02:304]....\smpd_free_context
[02:304].....freeing parent context.
[02:304].....\smpd_init_context
[02:304]......\smpd_init_command
[02:304]....../smpd_init_command
[02:304]...../smpd_init_context
[02:304]..../smpd_free_context
[02:304]....all contexts closed, exiting state machine.
[02:304].../smpd_handle_op_close
[02:304]../smpd_enter_at_state
[02:304]..\smpd_exit
[02:304]...\smpd_kill_all_processes
[02:304].../smpd_kill_all_processes
[02:304]...\smpd_finalize_drive_maps
[02:304].../smpd_finalize_drive_maps
[02:304]...\smpd_dbs_finalize
[02:304].../smpd_dbs_finalize
