[mpich-discuss] MPICH2 on Windows 7

[Dave Keller]

I seem to having a problem getting complete log information from SMPD -d.
The test program runs fine.  I can see the right number of cpi.exe
processes.  SMPD was started as admin after doing a smpd -stop.  But the log
debug output does not look like it is giving all the information or gives up
very early.

 

Mpiexec -n 2 cpi.exe

 

Output from smpd -d:

 

 

.

[-1:41864]...\smpd_get_opt_int

[-1:41864].../smpd_get_opt_int

[-1:41864]...\smpd_get_opt_int

[-1:41864].../smpd_get_opt_int

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt_string

[-1:41864].../smpd_get_opt_string

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt_string

[-1:41864].../smpd_get_opt_string

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt_string

[-1:41864].../smpd_get_opt_string

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt_string

[-1:41864].../smpd_get_opt_string

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt_string

[-1:41864].../smpd_get_opt_string

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt_string

[-1:41864].../smpd_get_opt_string

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt_string

[-1:41864].../smpd_get_opt_string

[-1:41864]...\smpd_get_opt_string

[-1:41864].../smpd_get_opt_string

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]...\smpd_get_opt

[-1:41864].../smpd_get_opt

[-1:41864]../smpd_parse_command_args

[-1:41864]..\smpd_get_smpd_data

[-1:41864]...\smpd_get_smpd_data_from_environment

[-1:41864].../smpd_get_smpd_data_from_environment

[-1:41864]...\smpd_get_smpd_data_default

[-1:41864].../smpd_get_smpd_data_default

[-1:41864]../smpd_get_smpd_data

[-1:41864]..\smpd_entry_point

[00:41864]...\smpd_set_smpd_data

[00:41864].../smpd_set_smpd_data

[00:41864]...\SMPDU_Sock_create_set

[00:41864]....\SMPDU_Sock_get_sock_set_id

[00:41864]..../SMPDU_Sock_get_sock_set_id

[00:41864]....created a set for the listener: 604

[00:41864]....\SMPDU_Sock_listen

[00:41864]..../SMPDU_Sock_listen

[00:41864]....smpd listening on port 8678

[00:41864]....\smpd_create_context

[00:41864].....\smpd_init_context

[00:41864]......\smpd_init_command

[00:41864]....../smpd_init_command

[00:41864]......\SMPDU_Sock_set_user_ptr

[00:41864]....../SMPDU_Sock_set_user_ptr

[00:41864]...../smpd_init_context

[00:41864]..../smpd_create_context

[00:41864]....\SMPDU_Sock_set_user_ptr

[00:41864]..../SMPDU_Sock_set_user_ptr

[00:41864]....\smpd_option_on

[00:41864].....\smpd_get_smpd_data

[00:41864]......\smpd_get_smpd_data_from_environment

[00:41864]....../smpd_get_smpd_data_from_environment

[00:41864]......\smpd_get_smpd_data_default

[00:41864]....../smpd_get_smpd_data_default

[00:41864]......Unable to get the data for the key 'no_dynamic_hosts'

[00:41864]...../smpd_get_smpd_data

[00:41864]..../smpd_option_on

[00:41864]....\smpd_insert_into_dynamic_hosts

[00:41864]..../smpd_insert_into_dynamic_hosts

[00:41864]....\smpd_enter_at_state

[00:41864].....sock_waiting for the next event.

[00:41864].....\SMPDU_Sock_wait

[00:41864]...../SMPDU_Sock_wait

[00:41864].....SOCK_OP_ACCEPT

[00:41864].....\smpd_handle_op_accept

[00:41864]......\smpd_state_smpd_listening

[00:41864].......\SMPDU_Sock_accept

[00:41864]......./SMPDU_Sock_accept

[00:41864].......authenticating new connection

[00:41864].......\smpd_create_context

[00:41864]........\smpd_init_context

[00:41864].........\smpd_init_command

[00:41864]........./smpd_init_command

[00:41864].........\SMPDU_Sock_set_user_ptr

[00:41864]........./SMPDU_Sock_set_user_ptr

[00:41864]......../smpd_init_context

[00:41864]......./smpd_create_context

[00:41864].......\SMPDU_Sock_set_user_ptr

[00:41864]......./SMPDU_Sock_set_user_ptr

[00:41864].......\smpd_gen_authentication_strings

[00:41864]........\smpd_hash

[00:41864]......../smpd_hash

[00:41864]......./smpd_gen_authentication_strings

[00:41864].......posting a write of the challenge string: 3.1 5676

[00:41864].......\SMPDU_Sock_post_write

[00:41864]........\SMPDU_Sock_post_writev

[00:41864]......../SMPDU_Sock_post_writev

[00:41864]......./SMPDU_Sock_post_write

[00:41864]....../smpd_state_smpd_listening

[00:41864]...../smpd_handle_op_accept

[00:41864].....sock_waiting for the next event.

[00:41864].....\SMPDU_Sock_wait

[00:41864]...../SMPDU_Sock_wait

[00:41864].....SOCK_OP_WRITE

[00:41864].....\smpd_handle_op_write

[00:41864]......\smpd_state_writing_challenge_string

[00:41864].......wrote challenge string: '3.1 5676'

[00:41864].......\SMPDU_Sock_post_read

[00:41864]........\SMPDU_Sock_post_readv

[00:41864]......../SMPDU_Sock_post_readv

[00:41864]......./SMPDU_Sock_post_read

[00:41864]....../smpd_state_writing_challenge_string

[00:41864]...../smpd_handle_op_write

[00:41864].....sock_waiting for the next event.

[00:41864].....\SMPDU_Sock_wait

[00:41864]...../SMPDU_Sock_wait

[00:41864].....SOCK_OP_READ event.error = 0, result = 0, context->type=20

[00:41864].....\smpd_handle_op_read

[00:41864]......\smpd_state_reading_challenge_response

[00:41864].......read challenge response: 'fbc7aa3f86387511622eac4177c4d071'

[00:41864].......\SMPDU_Sock_post_write

[00:41864]........\SMPDU_Sock_post_writev

[00:41864]......../SMPDU_Sock_post_writev

[00:41864]......./SMPDU_Sock_post_write

[00:41864]....../smpd_state_reading_challenge_response

[00:41864]...../smpd_handle_op_read

[00:41864].....sock_waiting for the next event.

[00:41864].....\SMPDU_Sock_wait

[00:41864]...../SMPDU_Sock_wait

[00:41864].....SOCK_OP_WRITE

[00:41864].....\smpd_handle_op_write

[00:41864]......\smpd_state_writing_connect_result

[00:41864].......wrote connect result: 'SUCCESS'

[00:41864].......\SMPDU_Sock_post_read

[00:41864]........\SMPDU_Sock_post_readv

[00:41864]......../SMPDU_Sock_post_readv

[00:41864]......./SMPDU_Sock_post_read

[00:41864]....../smpd_state_writing_connect_result

[00:41864]...../smpd_handle_op_write

[00:41864].....sock_waiting for the next event.

[00:41864].....\SMPDU_Sock_wait

[00:41864]...../SMPDU_Sock_wait

[00:41864].....SOCK_OP_READ event.error = 0, result = 0, context->type=20

[00:41864].....\smpd_handle_op_read

[00:41864]......\smpd_state_reading_session_request

[00:41864].......read session request: 'process'

[00:41864].......\SMPDU_Sock_post_write

[00:41864]........\SMPDU_Sock_post_writev

[00:41864]......../SMPDU_Sock_post_writev

[00:41864]......./SMPDU_Sock_post_write

[00:41864]....../smpd_state_reading_session_request

[00:41864]...../smpd_handle_op_read

[00:41864].....sock_waiting for the next event.

[00:41864].....\SMPDU_Sock_wait

[00:41864]...../SMPDU_Sock_wait

[00:41864].....SOCK_OP_WRITE

[00:41864].....\smpd_handle_op_write

[00:41864]......\smpd_state_writing_no_cred_request

[00:41864].......wrote no cred request: 'nocredentials'

[00:41864].......\smpd_start_win_mgr

[00:41864]........starting command: "C:\Program Files (x86)\Common
Files\Intel\S

hared Libraries\redist\intel64\mpirt\smpd.exe" -d 27 -mgr -pipe
smpd_temporary_p

ipe

[00:41864]........CreateProcess

[00:41864]........smpd reading the port string from the manager

[00:41864]........smpd sending the account to the manager

[00:41864]........\smpd_hide_string_arg

[00:41864].........\first_token

[00:41864]........./first_token

[00:41864].........\compare_token

[00:41864]........./compare_token

[00:41864].........\next_token

[00:41864]..........\first_token

[00:41864]........../first_token

[00:41864]..........\first_token

[00:41864]........../first_token

[00:41864]........./next_token

[00:41864]......../smpd_hide_string_arg

[00:41864]......../smpd_hide_string_arg

[00:41864]........\smpd_hide_string_arg

[00:41864].........\first_token

[00:41864]........./first_token

[00:41864].........\compare_token

[00:41864]........./compare_token

[00:41864].........\next_token

[00:41864]..........\first_token

[00:41864]........../first_token

[00:41864]..........\first_token

[00:41864]........../first_token

[00:41864]........./next_token

[00:41864].........\compare_token

[00:41864]........./compare_token

[00:41864].........\compare_token

[00:41864]........./compare_token

[00:41864].........\next_token

[00:41864]..........\first_token

[00:41864]........../first_token

[00:41864]..........\first_token

[00:41864]........../first_token

[00:41864]........./next_token

[00:41864].........\compare_token

[00:41864]........./compare_token

[00:41864].........\next_token

[00:41864]..........\first_token

[00:41864]........../first_token

[00:41864]..........\first_token

[00:41864]........../first_token

[00:41864]........./next_token

[00:41864].........\compare_token

[00:41864]........./compare_token

[00:41864].........\next_token

[00:41864]..........\first_token

[00:41864]........../first_token

[00:41864]..........\first_token

[00:41864]........../first_token

[00:41864]........./next_token

[00:41864]......../smpd_hide_string_arg

[00:41864]......../smpd_hide_string_arg

[00:41864]........closing the pipe to the manager

[00:41864]........smpd writing reconnect request: port 60913

[00:41864]........\SMPDU_Sock_post_write

[00:41864].........\SMPDU_Sock_post_writev

[00:41864]........./SMPDU_Sock_post_writev

[00:41864]......../SMPDU_Sock_post_write

[00:41864]......./smpd_state_writing_no_cred_request

[00:41864]....../smpd_handle_op_write

[00:41864]......sock_waiting for the next event.

[00:41864]......\SMPDU_Sock_wait

[00:41864]....../SMPDU_Sock_wait

[00:41864]......SOCK_OP_WRITE

[00:41864]......\smpd_handle_op_write

[00:41864].......\smpd_state_writing_reconnect_request

[00:41864]........wrote reconnect request: '60913'

[00:41864]........\SMPDU_Sock_post_close

[00:41864].........\SMPDU_Sock_post_read

[00:41864]..........\SMPDU_Sock_post_readv

[00:41864]........../SMPDU_Sock_post_readv

[00:41864]........./SMPDU_Sock_post_read

[00:41864]......../SMPDU_Sock_post_close

[00:41864]......./smpd_state_writing_reconnect_request

[00:41864]....../smpd_handle_op_write

[00:41864]......sock_waiting for the next event.

[00:41864]......\SMPDU_Sock_wait

[00:41864]....../SMPDU_Sock_wait

[00:41864]......SOCK_OP_CLOSE

[00:41864]......\smpd_handle_op_close

[00:41864].......\smpd_get_state_string

[00:41864]......./smpd_get_state_string

[00:41864].......op_close received - SMPD_CLOSING state.

[00:41864].......Unaffiliated undetermined context closing.

[00:41864].......\smpd_free_context

[00:41864]........freeing undetermined context.

[00:41864]........\smpd_init_context

[00:41864].........\smpd_init_command

[00:41864]........./smpd_init_command

[00:41864]......../smpd_init_context

[00:41864]......./smpd_free_context

[00:41864]....../smpd_handle_op_close

[00:41864]......sock_waiting for the next event.

[00:41864]......\SMPDU_Sock_wait

 

 

Even though cpi.exe is still running and I enter many interval numbers - I
never see any additional log entries.

 

 

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.mcs.anl.gov/pipermail/mpich-discuss/attachments/20111011/30b27e7b/attachment.htm>