..\smpd_get_full_path_name
...fixing up exe name: 'hostname' -> '(null)'
../smpd_get_full_path_name
..handling executable:
hostname.exe
..\smpd_get_next_host
...\smpd_get_next_hostname
.../smpd_get_next_hostname
...\smpd_get_host_id
.../smpd_get_host_id
../smpd_get_next_host
..\smpd_get_next_host
...\smpd_get_next_hostname
.../smpd_get_next_hostname
...\smpd_get_host_id
.../smpd_get_host_id
../smpd_get_next_host
..\smpd_create_cliques
...\prev_launch_node
.../prev_launch_node
...\prev_launch_node
.../prev_launch_node
...\prev_launch_node
.../prev_launch_node
...\prev_launch_node
.../prev_launch_node
../smpd_create_cliques
..\smpd_fix_up_host_tree
../smpd_fix_up_host_tree
./mp_parse_command_args
.host tree:
. host: mb13, parent: 0, id: 1
.launch nodes:
. iproc: 1, id: 1, exe: hostname.exe
. iproc: 0, id: 1, exe: hostname.exe
.\smpd_get_smpd_data
..\smpd_get_smpd_data_from_environment
../smpd_get_smpd_data_from_environment
./smpd_get_smpd_data
.\smpd_create_context
..\smpd_init_context
...\smpd_init_command
.../smpd_init_command
../smpd_init_context
./smpd_create_context
.\smpd_make_socket_loop
..\smpd_get_hostname
../smpd_get_hostname
./smpd_make_socket_loop
.\smpd_create_context
..\smpd_init_context
...\smpd_init_command
.../smpd_init_command
../smpd_init_context
./smpd_create_context
.\smpd_enter_at_state
..sock_waiting for the next event.
..SOCK_OP_CONNECT
..\smpd_handle_op_connect
...connect succeeded, posting read of the challenge string
../smpd_handle_op_connect
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_challenge_string
....read challenge string: '1.0.8 3238'
....\smpd_verify_version
..../smpd_verify_version
....\smpd_hash
..../smpd_hash
.../smpd_state_reading_challenge_string
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_WRITE
..\smpd_handle_op_write
...\smpd_state_writing_challenge_response
....wrote challenge response: '8a007e503d1baf16ac233fcb4de259f6'
.../smpd_state_writing_challenge_response
../smpd_handle_op_write
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_connect_result
....read connect result: 'SUCCESS'
.../smpd_state_reading_connect_result
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_WRITE
..\smpd_handle_op_write
...\smpd_state_writing_process_session_request
....wrote process session request: 'process'
.../smpd_state_writing_process_session_request
../smpd_handle_op_write
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cred_request
....read cred request: 'nocredentials'
.../smpd_state_reading_cred_request
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_reconnect_request
....read re-connect request: '1094'
....closing the old socket in the left context.
....MPIDU_Sock_post_close(1640)
....connecting a new socket.
....\smpd_create_context
.....\smpd_init_context
......\smpd_init_command
....../smpd_init_command
...../smpd_init_context
..../smpd_create_context
....posting a re-connect to mb13:1094 in left context.
.../smpd_state_reading_reconnect_request
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_CLOSE
..\smpd_handle_op_close
...\smpd_get_state_string
.../smpd_get_state_string
...op_close received - SMPD_CLOSING state.
...Unaffiliated left context closing.
...\smpd_free_context
....freeing left context.
....\smpd_init_context
.....\smpd_init_command
...../smpd_init_command
..../smpd_init_context
.../smpd_free_context
../smpd_handle_op_close
..sock_waiting for the next event.
..SOCK_OP_CONNECT
..\smpd_handle_op_connect
...\smpd_generate_session_header
....session header: (id=1 parent=0 level=0)
.../smpd_generate_session_header
../smpd_handle_op_connect
..sock_waiting for the next event.
..SOCK_OP_WRITE
..\smpd_handle_op_write
...\smpd_state_writing_session_header
....wrote session header: 'id=1 parent=0 level=0'
....\smpd_post_read_command
.....posting a read for a command header on the left context, sock 1584
..../smpd_post_read_command
....\smpd_create_command
.....\smpd_init_command
...../smpd_init_command
..../smpd_create_command
....\smpd_post_write_command
.....\smpd_package_command
...../smpd_package_command
.....smpd_post_write_command on the left context sock 1584: 47 bytes for command: "cmd=start_dbs src=0 dest=1 tag=0 "
..../smpd_post_write_command
.../smpd_state_writing_session_header
../smpd_handle_op_write
..sock_waiting for the next event.
..SOCK_OP_WRITE
..\smpd_handle_op_write
...\smpd_state_writing_cmd
....wrote command
....command written to left: "cmd=start_dbs src=0 dest=1 tag=0 "
....moving 'start_dbs' command to the wait_list.
.../smpd_state_writing_cmd
../smpd_handle_op_write
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd_header
....read command header
....command header read, posting read for data: 170 bytes
.../smpd_state_reading_cmd_header
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd
....read command
....\smpd_parse_command
..../smpd_parse_command
....read command: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=start_dbs kvs_name=5F19B256-9C93-4052-B66C-E4F39287DADB domain_name=1A320286-D22A-4f5c-A7AA-440D57AB34DB result=SUCCESS "
....\smpd_handle_command
.....handling command:
..... src  = 1
..... dest = 0
..... cmd  = result
..... tag  = 0
..... ctx  = left
..... len  = 170
..... str  = cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=start_dbs kvs_name=5F19B256-9C93-4052-B66C-E4F39287DADB domain_name=1A320286-D22A-4f5c-A7AA-440D57AB34DB result=SUCCESS 
.....\smpd_command_destination
......0 -> 0 : returning NULL context
...../smpd_command_destination
.....\smpd_handle_result
......start_dbs succeeded, kvs_name: '5F19B256-9C93-4052-B66C-E4F39287DADB', domain_name: '1A320286-D22A-4f5c-A7AA-440D57AB34DB'
......\smpd_launch_processes
.......creating a process group of size 2 on node 0 called 5F19B256-9C93-4052-B66C-E4F39287DADB
.......\create_process_group
......./create_process_group
.......launching the processes.
.......\smpd_create_command
........\smpd_init_command
......../smpd_init_command
......./smpd_create_command
.......\smpd_add_command_arg
......./smpd_add_command_arg
.......\smpd_add_command_int_arg
......./smpd_add_command_int_arg
.......\smpd_add_command_int_arg
......./smpd_add_command_int_arg
.......\smpd_add_command_arg
......./smpd_add_command_arg
.......\smpd_add_command_arg
......./smpd_add_command_arg
.......\smpd_add_command_arg
......./smpd_add_command_arg
.......\smpd_add_command_int_arg
......./smpd_add_command_int_arg
.......\smpd_add_command_int_arg
......./smpd_add_command_int_arg
.......\smpd_add_command_arg
......./smpd_add_command_arg
.......\smpd_add_command_arg
......./smpd_add_command_arg
.......\smpd_post_write_command
........\smpd_package_command
......../smpd_package_command
........smpd_post_write_command on the left context sock 1584: 197 bytes for command: "cmd=launch src=0 dest=1 tag=1 c=hostname.exe s=0 a=0 d=C:\prj\zlab p=C:\WINDOWS\system32 q=0..1 i=1 n=2 k=5F19B256-9C93-4052-B66C-E4F39287DADB kd=1A320286-D22A-4f5c-A7AA-440D57AB34DB "
......./smpd_post_write_command
.......\smpd_create_command
........\smpd_init_command
......../smpd_init_command
......./smpd_create_command
.......\smpd_add_command_arg
......./smpd_add_command_arg
.......\smpd_add_command_int_arg
......./smpd_add_command_int_arg
.......\smpd_add_command_int_arg
......./smpd_add_command_int_arg
.......\smpd_add_command_arg
......./smpd_add_command_arg
.......\smpd_add_command_arg
......./smpd_add_command_arg
.......\smpd_add_command_arg
......./smpd_add_command_arg
.......\smpd_add_command_int_arg
......./smpd_add_command_int_arg
.......\smpd_add_command_int_arg
......./smpd_add_command_int_arg
.......\smpd_add_command_arg
......./smpd_add_command_arg
.......\smpd_add_command_arg
......./smpd_add_command_arg
.......\smpd_post_write_command
........\smpd_package_command
......../smpd_package_command
........enqueueing write at the end of the list.
......./smpd_post_write_command
....../smpd_launch_processes
......\smpd_free_command
.......\smpd_init_command
......./smpd_init_command
....../smpd_free_command
...../smpd_handle_result
..../smpd_handle_command
....\smpd_post_read_command
.....posting a read for a command header on the left context, sock 1584
..../smpd_post_read_command
.../smpd_state_reading_cmd
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_WRITE
..\smpd_handle_op_write
...\smpd_state_writing_cmd
....wrote command
....command written to left: "cmd=launch src=0 dest=1 tag=1 c=hostname.exe s=0 a=0 d=C:\prj\zlab p=C:\WINDOWS\system32 q=0..1 i=1 n=2 k=5F19B256-9C93-4052-B66C-E4F39287DADB kd=1A320286-D22A-4f5c-A7AA-440D57AB34DB "
....moving 'launch' command to the wait_list.
....smpd_handle_written: posting write(197 bytes) for command: "cmd=launch src=0 dest=1 tag=2 c=hostname.exe s=0 a=0 d=C:\prj\zlab p=C:\WINDOWS\system32 q=0..1 i=0 n=2 k=5F19B256-9C93-4052-B66C-E4F39287DADB kd=1A320286-D22A-4f5c-A7AA-440D57AB34DB "
.../smpd_state_writing_cmd
../smpd_handle_op_write
..sock_waiting for the next event.
..SOCK_OP_WRITE
..\smpd_handle_op_write
...\smpd_state_writing_cmd
....wrote command
....command written to left: "cmd=launch src=0 dest=1 tag=2 c=hostname.exe s=0 a=0 d=C:\prj\zlab p=C:\WINDOWS\system32 q=0..1 i=0 n=2 k=5F19B256-9C93-4052-B66C-E4F39287DADB kd=1A320286-D22A-4f5c-A7AA-440D57AB34DB "
....moving 'launch' command to the wait_list.
.../smpd_state_writing_cmd
../smpd_handle_op_write
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd_header
....read command header
....command header read, posting read for data: 134 bytes
.../smpd_state_reading_cmd_header
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd
....read command
....\smpd_parse_command
..../smpd_parse_command
....read command: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=launch result=SUCCESS pg_id=5F19B256-9C93-4052-B66C-E4F39287DADB pg_rank=1 pg_ctx=0 "
....\smpd_handle_command
.....handling command:
..... src  = 1
..... dest = 0
..... cmd  = result
..... tag  = 1
..... ctx  = left
..... len  = 134
..... str  = cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=launch result=SUCCESS pg_id=5F19B256-9C93-4052-B66C-E4F39287DADB pg_rank=1 pg_ctx=0 
.....\smpd_command_destination
......0 -> 0 : returning NULL context
...../smpd_command_destination
.....\smpd_handle_result
......successfully launched: 'cmd=launch src=0 dest=1 tag=1 c=hostname.exe s=0 a=0 d=C:\prj\zlab p=C:\WINDOWS\system32 q=0..1 i=1 n=2 k=5F19B256-9C93-4052-B66C-E4F39287DADB kd=1A320286-D22A-4f5c-A7AA-440D57AB34DB '
......\smpd_free_command
.......\smpd_init_command
......./smpd_init_command
....../smpd_free_command
...../smpd_handle_result
..../smpd_handle_command
....\smpd_post_read_command
.....posting a read for a command header on the left context, sock 1584
..../smpd_post_read_command
.../smpd_state_reading_cmd
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd_header
....read command header
....command header read, posting read for data: 134 bytes
.../smpd_state_reading_cmd_header
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd
....read command
....\smpd_parse_command
..../smpd_parse_command
....read command: "cmd=result src=1 dest=0 tag=2 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=5F19B256-9C93-4052-B66C-E4F39287DADB pg_rank=0 pg_ctx=1 "
....\smpd_handle_command
.....handling command:
..... src  = 1
..... dest = 0
..... cmd  = result
..... tag  = 2
..... ctx  = left
..... len  = 134
..... str  = cmd=result src=1 dest=0 tag=2 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=5F19B256-9C93-4052-B66C-E4F39287DADB pg_rank=0 pg_ctx=1 
.....\smpd_command_destination
......0 -> 0 : returning NULL context
...../smpd_command_destination
.....\smpd_handle_result
......successfully launched: 'cmd=launch src=0 dest=1 tag=2 c=hostname.exe s=0 a=0 d=C:\prj\zlab p=C:\WINDOWS\system32 q=0..1 i=0 n=2 k=5F19B256-9C93-4052-B66C-E4F39287DADB kd=1A320286-D22A-4f5c-A7AA-440D57AB34DB '
......root process launched, starting stdin redirection.
......\smpd_make_socket_loop
.......\smpd_get_hostname
......./smpd_get_hostname
....../smpd_make_socket_loop
......\smpd_create_context
.......\smpd_init_context
........\smpd_init_command
......../smpd_init_command
......./smpd_init_context
....../smpd_create_context
......\smpd_free_command
.......\smpd_init_command
......./smpd_init_command
....../smpd_free_command
...../smpd_handle_result
..../smpd_handle_command
....\smpd_post_read_command
.....posting a read for a command header on the left context, sock 1584
..../smpd_post_read_command
.../smpd_state_reading_cmd
../smpd_handle_op_read
..sock_waiting for the next event.
..smpd_stdin_thread started.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd_header
....read command header
....command header read, posting read for data: 64 bytes
.../smpd_state_reading_cmd_header
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd
....read command
....\smpd_parse_command
..../smpd_parse_command
....read command: "cmd=stdout src=1 dest=0 tag=3 rank=1 first=1 data=6D6231330D0A "
....\smpd_handle_command
.....handling command:
..... src  = 1
..... dest = 0
..... cmd  = stdout
..... tag  = 3
..... ctx  = left
..... len  = 64
..... str  = cmd=stdout src=1 dest=0 tag=3 rank=1 first=1 data=6D6231330D0A 
.....\smpd_command_destination
......0 -> 0 : returning NULL context
...../smpd_command_destination
.....\smpd_handle_stdout_command
......\write_to_stdout
mb13
....../write_to_stdout
...../smpd_handle_stdout_command
..../smpd_handle_command
....\smpd_post_read_command
.....posting a read for a command header on the left context, sock 1584
..../smpd_post_read_command
.../smpd_state_reading_cmd
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd_header
....read command header
....command header read, posting read for data: 64 bytes
.../smpd_state_reading_cmd_header
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd
....read command
....\smpd_parse_command
..../smpd_parse_command
....read command: "cmd=stdout src=1 dest=0 tag=4 rank=0 first=1 data=6D6231330D0A "
....\smpd_handle_command
.....handling command:
..... src  = 1
..... dest = 0
..... cmd  = stdout
..... tag  = 4
..... ctx  = left
..... len  = 64
..... str  = cmd=stdout src=1 dest=0 tag=4 rank=0 first=1 data=6D6231330D0A 
.....\smpd_command_destination
......0 -> 0 : returning NULL context
...../smpd_command_destination
.....\smpd_handle_stdout_command
......\write_to_stdout
mb13
....../write_to_stdout
...../smpd_handle_stdout_command
..../smpd_handle_command
....\smpd_post_read_command
.....posting a read for a command header on the left context, sock 1584
..../smpd_post_read_command
.../smpd_state_reading_cmd
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd_header
....read command header
....command header read, posting read for data: 84 bytes
.../smpd_state_reading_cmd_header
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd
....read command
....\smpd_parse_command
..../smpd_parse_command
....read command: "cmd=exit src=1 dest=0 tag=5 rank=0 code=0 kvs=5F19B256-9C93-4052-B66C-E4F39287DADB "
....\smpd_handle_command
.....handling command:
..... src  = 1
..... dest = 0
..... cmd  = exit
..... tag  = 5
..... ctx  = left
..... len  = 84
..... str  = cmd=exit src=1 dest=0 tag=5 rank=0 code=0 kvs=5F19B256-9C93-4052-B66C-E4F39287DADB 
.....\smpd_command_destination
......0 -> 0 : returning NULL context
...../smpd_command_destination
.....\smpd_handle_exit_command
......saving exit code: rank 0, exitcode 0, pg <5F19B256-9C93-4052-B66C-E4F39287DADB>
......process exited without calling init.
......process exited before anyone has called init.
...../smpd_handle_exit_command
..../smpd_handle_command
....\smpd_post_read_command
.....posting a read for a command header on the left context, sock 1584
..../smpd_post_read_command
.../smpd_state_reading_cmd
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd_header
....read command header
....command header read, posting read for data: 84 bytes
.../smpd_state_reading_cmd_header
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd
....read command
....\smpd_parse_command
..../smpd_parse_command
....read command: "cmd=exit src=1 dest=0 tag=6 rank=1 code=0 kvs=5F19B256-9C93-4052-B66C-E4F39287DADB "
....\smpd_handle_command
.....handling command:
..... src  = 1
..... dest = 0
..... cmd  = exit
..... tag  = 6
..... ctx  = left
..... len  = 84
..... str  = cmd=exit src=1 dest=0 tag=6 rank=1 code=0 kvs=5F19B256-9C93-4052-B66C-E4F39287DADB 
.....\smpd_command_destination
......0 -> 0 : returning NULL context
...../smpd_command_destination
.....\smpd_handle_exit_command
......saving exit code: rank 1, exitcode 0, pg <5F19B256-9C93-4052-B66C-E4F39287DADB>
......process exited without calling init.
......process exited before anyone has called init.
......last process exited, returning SMPD_EXIT.
...../smpd_handle_exit_command
..../smpd_handle_command
....\smpd_post_read_command
.....posting a read for a command header on the left context, sock 1584
..../smpd_post_read_command
....\smpd_create_command
.....\smpd_init_command
...../smpd_init_command
..../smpd_create_command
....\smpd_post_write_command
.....\smpd_package_command
...../smpd_package_command
.....smpd_post_write_command on the left context sock 1584: 43 bytes for command: "cmd=close src=0 dest=1 tag=3 "
..../smpd_post_write_command
.../smpd_state_reading_cmd
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_WRITE
..\smpd_handle_op_write
...\smpd_state_writing_cmd
....wrote command
....command written to left: "cmd=close src=0 dest=1 tag=3 "
....\smpd_free_command
.....\smpd_init_command
...../smpd_init_command
..../smpd_free_command
.../smpd_state_writing_cmd
../smpd_handle_op_write
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd_header
....read command header
....command header read, posting read for data: 31 bytes
.../smpd_state_reading_cmd_header
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_READ
..\smpd_handle_op_read
...\smpd_state_reading_cmd
....read command
....\smpd_parse_command
..../smpd_parse_command
....read command: "cmd=closed src=1 dest=0 tag=7 "
....\smpd_handle_command
.....handling command:
..... src  = 1
..... dest = 0
..... cmd  = closed
..... tag  = 7
..... ctx  = left
..... len  = 31
..... str  = cmd=closed src=1 dest=0 tag=7 
.....\smpd_command_destination
......0 -> 0 : returning NULL context
...../smpd_command_destination
.....\smpd_handle_closed_command
......closed command received from left child, closing sock.
......MPIDU_Sock_post_close(1584)
......received a closed at node with no parent context, assuming root, returning SMPD_EXITING.
...../smpd_handle_closed_command
..../smpd_handle_command
....not posting read for another command because SMPD_EXITING returned
.../smpd_state_reading_cmd
../smpd_handle_op_read
..sock_waiting for the next event.
..SOCK_OP_CLOSE
..\smpd_handle_op_close
...\smpd_get_state_string
.../smpd_get_state_string
...op_close received - SMPD_EXITING state.
...\smpd_free_context
....freeing left context.
....\smpd_init_context
.....\smpd_init_command
...../smpd_init_command
..../smpd_init_context
.../smpd_free_context
../smpd_handle_op_close
./smpd_enter_at_state
.ReadFile failed, closing stdin reader thread.
/main
\smpd_exit
.\smpd_kill_all_processes
./smpd_kill_all_processes
.\smpd_finalize_drive_maps
./smpd_finalize_drive_maps
.\smpd_dbs_finalize
./smpd_dbs_finalize