Microsoft Windows XP [Version 5.1.2600] (C) Copyright 1985-2001 Microsoft Corp. C:\>smpd -d [-1:2224]..\smpd_get_opt_int [-1:2224]../smpd_get_opt_int [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt_int [-1:2224]../smpd_get_opt_int [-1:2224]..\smpd_get_opt_int [-1:2224]../smpd_get_opt_int [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt_string [-1:2224]../smpd_get_opt_string [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt_string [-1:2224]../smpd_get_opt_string [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt_string [-1:2224]../smpd_get_opt_string [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt_string [-1:2224]../smpd_get_opt_string [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt_string [-1:2224]../smpd_get_opt_string [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt_string [-1:2224]../smpd_get_opt_string [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]..\smpd_get_opt_string [-1:2224]../smpd_get_opt_string [-1:2224]..\smpd_get_opt_string [-1:2224]../smpd_get_opt_string [-1:2224]..\smpd_get_opt [-1:2224]../smpd_get_opt [-1:2224]./smpd_parse_command_args [-1:2224].\smpd_entry_point [00:2224]..\smpd_set_smpd_data [00:2224]../smpd_set_smpd_data [00:2224]..created a set for the listener: 1744 [00:2224]..smpd listening on port 8676 [00:2224]..\smpd_create_context [00:2224]...\smpd_init_context [00:2224]....\smpd_init_command [00:2224]..../smpd_init_command [00:2224].../smpd_init_context [00:2224]../smpd_create_context [00:2224]..\smpd_insert_into_dynamic_hosts [00:2224]../smpd_insert_into_dynamic_hosts [00:2224]..\smpd_enter_at_state [00:2224]...sock_waiting for the next event. [00:2224]...SOCK_OP_ACCEPT [00:2224]...\smpd_handle_op_accept [00:2224]....\smpd_state_smpd_listening [00:2224].....authenticating new connection [00:2224].....\smpd_create_context [00:2224]......\smpd_init_context [00:2224].......\smpd_init_command [00:2224]......./smpd_init_command [00:2224]....../smpd_init_context [00:2224]...../smpd_create_context [00:2224].....\smpd_gen_authentication_strings [00:2224]......\smpd_hash [00:2224]....../smpd_hash [00:2224]...../smpd_gen_authentication_strings [00:2224].....posting a write of the challenge string: 1.0.6 7301 [00:2224]..../smpd_state_smpd_listening [00:2224].../smpd_handle_op_accept [00:2224]...sock_waiting for the next event. [00:2224]...SOCK_OP_WRITE [00:2224]...\smpd_handle_op_write [00:2224]....\smpd_state_writing_challenge_string [00:2224].....wrote challenge string: '1.0.6 7301' [00:2224]..../smpd_state_writing_challenge_string [00:2224].../smpd_handle_op_write [00:2224]...sock_waiting for the next event. [00:2224]...SOCK_OP_READ [00:2224]...\smpd_handle_op_read [00:2224]....\smpd_state_reading_challenge_response [00:2224].....read challenge response: '72b42e78beb26199cce828c9a1e70f16' [00:2224]..../smpd_state_reading_challenge_response [00:2224].../smpd_handle_op_read [00:2224]...sock_waiting for the next event. [00:2224]...SOCK_OP_WRITE [00:2224]...\smpd_handle_op_write [00:2224]....\smpd_state_writing_connect_result [00:2224].....wrote connect result: 'SUCCESS' [00:2224]..../smpd_state_writing_connect_result [00:2224].../smpd_handle_op_write [00:2224]...sock_waiting for the next event. [00:2224]...SOCK_OP_READ [00:2224]...\smpd_handle_op_read [00:2224]....\smpd_state_reading_session_request [00:2224].....read session request: 'process' [00:2224]..../smpd_state_reading_session_request [00:2224].../smpd_handle_op_read [00:2224]...sock_waiting for the next event. [00:2224]...SOCK_OP_WRITE [00:2224]...\smpd_handle_op_write [00:2224]....\smpd_state_writing_no_cred_request [00:2224].....wrote no cred request: 'nocredentials' [00:2224].....\smpd_start_win_mgr [00:2224]......starting command: "C:\Program Files\MPICH2\bin\smpd.exe" -d 27 -mgr -read 00000628 -wri te 0000062C [00:2224]......CreateProcess [00:2224]......smpd reading the port string from the manager [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt_int [-1:4324]../smpd_get_opt_int [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt_int [-1:4324]../smpd_get_opt_int [-1:4324]..\smpd_get_opt_int [-1:4324]../smpd_get_opt_int [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt [-1:4324]../smpd_get_opt [-1:4324]..\smpd_get_opt_string [-1:4324]../smpd_get_opt_string [-1:4324]..\smpd_get_opt_string [-1:4324]../smpd_get_opt_string [-1:4324]..manager creating listener and session sets. [-1:4324]..created set for manager listener, 1480 [-1:4324]..smpd manager listening on port 3077 [-1:4324]..\smpd_create_context [-1:4324]...\smpd_init_context [-1:4324]....\smpd_init_command [-1:4324]..../smpd_init_command [-1:4324].../smpd_init_context [-1:4324]../smpd_create_context [-1:4324]..manager writing port back to smpd. [-1:4324]..\smpd_hide_string_arg [-1:4324]...\first_token [-1:4324].../first_token [-1:4324]...\compare_token [-1:4324].../compare_token [-1:4324]...\next_token [-1:4324]....\first_token [-1:4324]..../first_token [-1:4324]....\first_token [-1:4324]..../first_token [-1:4324].../next_token [-1:4324]...\compare_token [-1:4324].../compare_token [-1:4324]...\compare_token [-1:4324].../compare_token [-1:4324]...\next_token [-1:4324]....\first_token [-1:4324]..../first_token [-1:4324]....\first_token [-1:4324]..../first_token [-1:4324].../next_token [-1:4324]...\compare_token [-1:4324].../compare_token [-1:4324]...\next_token [-1:4324]....\first_token [-1:4324]..../first_token [-1:4324]....\first_token [-1:4324]..../first_token [-1:4324].../next_token [-1:4324]../smpd_hide_string_arg [-1:4324]../smpd_hide_string_arg [00:2224]......smpd sending the account to the manager [00:2224]......\smpd_hide_string_arg [00:2224].......\first_token [00:2224]......./first_token [00:2224].......\compare_token [00:2224]......./compare_token [00:2224].......\next_token [00:2224]........\first_token [00:2224]......../first_token [00:2224]........\first_token [00:2224]......../first_token [00:2224]......./next_token [00:2224]....../smpd_hide_string_arg [00:2224]....../smpd_hide_string_arg [00:2224]......\smpd_hide_string_arg [00:2224].......\first_token [00:2224]......./first_token [00:2224].......\compare_token [00:2224]......./compare_token [00:2224].......\next_token [00:2224]........\first_token [00:2224]......../first_token [00:2224]........\first_token [00:2224]......../first_token [00:2224]......./next_token [00:2224].......\compare_token [00:2224]......./compare_token [00:2224].......\compare_token [00:2224]......./compare_token [00:2224].......\next_token [00:2224]........\first_token [00:2224]......../first_token [00:2224]........\first_token [00:2224]......../first_token [00:2224]......./next_token [00:2224].......\compare_token [00:2224]......./compare_token [00:2224].......\next_token [00:2224]........\first_token [00:2224]......../first_token [00:2224]........\first_token [00:2224]......../first_token [00:2224]......./next_token [00:2224].......\compare_token [00:2224]......./compare_token [00:2224].......\next_token [00:2224]........\first_token [00:2224]......../first_token [00:2224]........\first_token [00:2224]......../first_token [00:2224]......./next_token [00:2224]....../smpd_hide_string_arg [00:2224]....../smpd_hide_string_arg [00:2224]......closing the pipe to the manager [-1:4324]..\smpd_enter_at_state [00:2224]......smpd writing reconnect request: port 3077 [-1:4324]...sock_waiting for the next event. [00:2224]...../smpd_state_writing_no_cred_request [00:2224]..../smpd_handle_op_write [00:2224]....sock_waiting for the next event. [00:2224]....SOCK_OP_WRITE [00:2224]....\smpd_handle_op_write [00:2224].....\smpd_state_writing_reconnect_request [00:2224]......wrote reconnect request: '3077' [00:2224]...../smpd_state_writing_reconnect_request [-1:4324]...SOCK_OP_ACCEPT [00:2224]..../smpd_handle_op_write [-1:4324]...\smpd_handle_op_accept [00:2224]....sock_waiting for the next event. [-1:4324]....\smpd_state_mgr_listening [00:2224]....SOCK_OP_CLOSE [-1:4324].....accepted re-connection [00:2224]....\smpd_handle_op_close [-1:4324].....\smpd_create_context [00:2224].....\smpd_get_state_string [-1:4324]......\smpd_init_context [00:2224]...../smpd_get_state_string [-1:4324].......\smpd_init_command [00:2224].....op_close received - SMPD_CLOSING state. [-1:4324]......./smpd_init_command [00:2224].....Unaffiliated undetermined context closing. [-1:4324]....../smpd_init_context [00:2224].....\smpd_free_context [-1:4324]...../smpd_create_context [00:2224]......freeing undetermined context. [-1:4324].....closing the mgr listener. [00:2224]......\smpd_init_context [-1:4324]..../smpd_state_mgr_listening [00:2224].......\smpd_init_command [-1:4324].../smpd_handle_op_accept [00:2224]......./smpd_init_command [-1:4324]...sock_waiting for the next event. [00:2224]....../smpd_init_context [-1:4324]...SOCK_OP_READ [00:2224]...../smpd_free_context [-1:4324]...\smpd_handle_op_read [00:2224]..../smpd_handle_op_close [-1:4324]....\smpd_state_reading_session_header [00:2224]....sock_waiting for the next event. [-1:4324].....read session header: 'id=1 parent=0 level=0' [-1:4324].....\smpd_interpret_session_header [-1:4324]......interpreting session header: "id=1 parent=0 level=0" [-1:4324]...... id = 1 [01:4324]...... parent = 0 [01:4324]...... level = 0 [01:4324]...../smpd_interpret_session_header [01:4324].....\smpd_post_read_command [01:4324]......posting a read for a command header on the parent context, sock 1340 [01:4324]...../smpd_post_read_command [01:4324]..../smpd_state_reading_session_header [01:4324].../smpd_handle_op_read [01:4324]...sock_waiting for the next event. [01:4324]...SOCK_OP_CLOSE [01:4324]...\smpd_handle_op_close [01:4324]....\smpd_get_state_string [01:4324]..../smpd_get_state_string [01:4324]....op_close received - SMPD_MGR_LISTENING state. [01:4324]....\smpd_free_context [01:4324].....freeing listener context. [01:4324].....\smpd_init_context [01:4324]......\smpd_init_command [01:4324]....../smpd_init_command [01:4324]...../smpd_init_context [01:4324]..../smpd_free_context [01:4324].../smpd_handle_op_close [01:4324]...sock_waiting for the next event. [01:4324]...SOCK_OP_READ [01:4324]...\smpd_handle_op_read [01:4324]....\smpd_state_reading_cmd_header [01:4324].....read command header [01:4324].....command header read, posting read for data: 54 bytes [01:4324]..../smpd_state_reading_cmd_header [01:4324].../smpd_handle_op_read [01:4324]...sock_waiting for the next event. [01:4324]...SOCK_OP_READ [01:4324]...\smpd_handle_op_read [01:4324]....\smpd_state_reading_cmd [01:4324].....read command [01:4324].....\smpd_parse_command [01:4324]...../smpd_parse_command [01:4324].....read command: "cmd=connect src=0 dest=1 tag=0 host=crpscsgapxt id=2 " [01:4324].....\smpd_handle_command [01:4324]......handling command: [01:4324]...... src = 0 [01:4324]...... dest = 1 [01:4324]...... cmd = connect [01:4324]...... tag = 0 [01:4324]...... ctx = parent [01:4324]...... len = 54 [01:4324]...... str = cmd=connect src=0 dest=1 tag=0 host=crpscsgapxt id=2 [01:4324]......\smpd_command_destination [01:4324].......1 -> 1 : returning NULL context [01:4324]....../smpd_command_destination [01:4324]......\smpd_handle_connect_command [01:4324].......now connecting to crpscsgapxt [01:4324].......\smpd_create_context [01:4324]........\smpd_init_context [01:4324].........\smpd_init_command [01:4324]........./smpd_init_command [01:4324]......../smpd_init_context [01:4324]......./smpd_create_context [01:4324].......adding new left child context [01:4324].......\smpd_init_context [01:4324]........\smpd_init_command [01:4324]......../smpd_init_command [01:4324]......./smpd_init_context [01:4324]....../smpd_handle_connect_command [01:4324]...../smpd_handle_command [01:4324].....\smpd_post_read_command [01:4324]......posting a read for a command header on the parent context, sock 1340 [01:4324]...../smpd_post_read_command [01:4324]..../smpd_state_reading_cmd [01:4324].../smpd_handle_op_read [01:4324]...sock_waiting for the next event. [01:4324]...SOCK_OP_CONNECT [01:4324]...\smpd_handle_op_connect [01:4324]....connect succeeded, posting read of the challenge string [01:4324].../smpd_handle_op_connect [01:4324]...sock_waiting for the next event. [01:4324]...SOCK_OP_READ [01:4324]...\smpd_handle_op_read [01:4324]....\smpd_state_reading_challenge_string [01:4324].....read challenge string: '1.0.6 17192' [01:4324].....\smpd_verify_version [01:4324]...../smpd_verify_version [01:4324].....\smpd_hash [01:4324]...../smpd_hash [01:4324]..../smpd_state_reading_challenge_string [01:4324].../smpd_handle_op_read [01:4324]...sock_waiting for the next event. [01:4324]...SOCK_OP_WRITE [01:4324]...\smpd_handle_op_write [01:4324]....\smpd_state_writing_challenge_response [01:4324].....wrote challenge response: '494b5dee89c107643bf95461ab24a62c' [01:4324]..../smpd_state_writing_challenge_response [01:4324].../smpd_handle_op_write [01:4324]...sock_waiting for the next event. [01:4324]...SOCK_OP_READ [01:4324]...\smpd_handle_op_read [01:4324]....\smpd_state_reading_connect_result [01:4324].....read connect result: 'SUCCESS' [01:4324]..../smpd_state_reading_connect_result [01:4324].../smpd_handle_op_read [01:4324]...sock_waiting for the next event. [01:4324]...SOCK_OP_WRITE [01:4324]...\smpd_handle_op_write [01:4324]....\smpd_state_writing_process_session_request [01:4324].....wrote process session request: 'process' [01:4324]..../smpd_state_writing_process_session_request [01:4324].../smpd_handle_op_write [01:4324]...sock_waiting for the next event. [01:4324]...SOCK_OP_READ [01:4324]...\smpd_handle_op_read [01:4324]....\smpd_state_reading_cred_request [01:4324].....read cred request: 'credentials' [01:4324].....\smpd_hide_string_arg [01:4324]......\first_token [01:4324]....../first_token [01:4324]......\compare_token [01:4324]....../compare_token [01:4324]......\next_token [01:4324].......\first_token [01:4324]......./first_token [01:4324].......\first_token [01:4324]......./first_token [01:4324]....../next_token [01:4324]...../smpd_hide_string_arg [01:4324]...../smpd_hide_string_arg [01:4324].....\smpd_hide_string_arg [01:4324]......\first_token [01:4324]....../first_token [01:4324]......\compare_token [01:4324]....../compare_token [01:4324]......\next_token [01:4324].......\first_token [01:4324]......./first_token [01:4324].......\first_token [01:4324]......./first_token [01:4324]....../next_token [01:4324]...../smpd_hide_string_arg [01:4324]...../smpd_hide_string_arg [01:4324].....\smpd_hide_string_arg [01:4324]......\first_token [01:4324]....../first_token [01:4324]......\compare_token [01:4324]....../compare_token [01:4324]......\next_token [01:4324].......\first_token [01:4324]......./first_token [01:4324].......\first_token [01:4324]......./first_token [01:4324]....../next_token [01:4324]...../smpd_hide_string_arg [01:4324]...../smpd_hide_string_arg [01:4324]......\smpd_option_on [01:4324].......\smpd_get_smpd_data [01:4324]........\smpd_get_smpd_data_from_environment [01:4324]......../smpd_get_smpd_data_from_environment [01:4324]........\smpd_get_smpd_data_default [01:4324]......../smpd_get_smpd_data_default [01:4324]........Unable to get the data for the key 'nocache' [01:4324]......./smpd_get_smpd_data [01:4324]....../smpd_option_on [01:4324].....\smpd_hide_string_arg [01:4324]......\first_token [01:4324]....../first_token [01:4324]......\compare_token [01:4324]....../compare_token [01:4324]......\next_token [01:4324].......\first_token [01:4324]......./first_token [01:4324].......\first_token [01:4324]......./first_token [01:4324]....../next_token [01:4324]...../smpd_hide_string_arg [01:4324]...../smpd_hide_string_arg [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cred_ack_yes [01:4324]......wrote cred request yes ack. [01:4324]...../smpd_state_writing_cred_ack_yes [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_account [01:4324]......wrote account: 'nbshi8a' [01:4324]......\smpd_encrypt_data [01:4324]....../smpd_encrypt_data [01:4324]...../smpd_state_writing_account [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_hide_string_arg [01:4324]......\first_token [01:4324]....../first_token [01:4324]......\compare_token [01:4324]....../compare_token [01:4324]......\next_token [01:4324].......\first_token [01:4324]......./first_token [01:4324].......\first_token [01:4324]......./first_token [01:4324]....../next_token [01:4324]...../smpd_hide_string_arg [01:4324]...../smpd_hide_string_arg [01:4324]......\smpd_hide_string_arg [01:4324].......\first_token [01:4324]......./first_token [01:4324].......\compare_token [01:4324]......./compare_token [01:4324].......\next_token [01:4324]........\first_token [01:4324]......../first_token [01:4324]........\first_token [01:4324]......../first_token [01:4324]......./next_token [01:4324]....../smpd_hide_string_arg [01:4324]....../smpd_hide_string_arg [01:4324].....\smpd_hide_string_arg [01:4324]......\first_token [01:4324]....../first_token [01:4324]......\compare_token [01:4324]....../compare_token [01:4324]......\next_token [01:4324].......\first_token [01:4324]......./first_token [01:4324].......\first_token [01:4324]......./first_token [01:4324]....../next_token [01:4324]...../smpd_hide_string_arg [01:4324]...../smpd_hide_string_arg [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_process_result [01:4324]......read process session result: 'SUCCESS' [01:4324]...../smpd_state_reading_process_result [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_reconnect_request [01:4324]......read re-connect request: '1419' [01:4324]......closing the old socket in the left context. [01:4324]......MPIDU_Sock_post_close(1320) [01:4324]......connecting a new socket. [01:4324]......\smpd_create_context [01:4324].......\smpd_init_context [01:4324]........\smpd_init_command [01:4324]......../smpd_init_command [01:4324]......./smpd_init_context [01:4324]....../smpd_create_context [01:4324]......posting a re-connect to crpscsgapxt:1419 in left context. [01:4324]...../smpd_state_reading_reconnect_request [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_CLOSE [01:4324]....\smpd_handle_op_close [01:4324].....\smpd_get_state_string [01:4324]...../smpd_get_state_string [01:4324].....op_close received - SMPD_CLOSING state. [01:4324].....Unaffiliated left context closing. [01:4324].....\smpd_free_context [01:4324]......freeing left context. [01:4324]......\smpd_init_context [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_init_context [01:4324]...../smpd_free_context [01:4324]..../smpd_handle_op_close [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_CONNECT [01:4324]....\smpd_handle_op_connect [01:4324].....\smpd_generate_session_header [01:4324]......session header: (id=2 parent=1 level=1) [01:4324]...../smpd_generate_session_header [01:4324]..../smpd_handle_op_connect [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_session_header [01:4324]......wrote session header: 'id=2 parent=1 level=1' [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the left context, sock 1296 [01:4324]....../smpd_post_read_command [01:4324]......\smpd_create_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_create_command [01:4324]......\smpd_add_command_int_arg [01:4324]....../smpd_add_command_int_arg [01:4324]......\smpd_add_command_arg [01:4324]....../smpd_add_command_arg [01:4324]......\smpd_add_command_arg [01:4324]....../smpd_add_command_arg [01:4324]......\smpd_command_destination [01:4324].......1 -> 0 : returning parent_context: 0 < 1 [01:4324]....../smpd_command_destination [01:4324]......sending result command: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=connect resul t=SUCCESS " [01:4324]......\smpd_post_write_command [01:4324].......\smpd_package_command [01:4324]......./smpd_package_command [01:4324].......smpd_post_write_command on the parent context sock 1340: 86 bytes for command: "cmd=re sult src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=connect result=SUCCESS " [01:4324]....../smpd_post_write_command [01:4324]...../smpd_state_writing_session_header [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cmd [01:4324]......wrote command [01:4324]......command written to parent: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=connect re sult=SUCCESS " [01:4324]......\smpd_free_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_free_command [01:4324]...../smpd_state_writing_cmd [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd_header [01:4324]......read command header [01:4324]......command header read, posting read for data: 34 bytes [01:4324]...../smpd_state_reading_cmd_header [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd [01:4324]......read command [01:4324]......\smpd_parse_command [01:4324]....../smpd_parse_command [01:4324]......read command: "cmd=start_dbs src=0 dest=1 tag=1 " [01:4324]......\smpd_handle_command [01:4324].......handling command: [01:4324]....... src = 0 [01:4324]....... dest = 1 [01:4324]....... cmd = start_dbs [01:4324]....... tag = 1 [01:4324]....... ctx = parent [01:4324]....... len = 34 [01:4324]....... str = cmd=start_dbs src=0 dest=1 tag=1 [01:4324].......\smpd_command_destination [01:4324]........1 -> 1 : returning NULL context [01:4324]......./smpd_command_destination [01:4324].......\smpd_handle_start_dbs_command [01:4324]........\smpd_dbs_init [01:4324]......../smpd_dbs_init [01:4324]........\smpd_create_command [01:4324].........\smpd_init_command [01:4324]........./smpd_init_command [01:4324]......../smpd_create_command [01:4324]........\smpd_add_command_int_arg [01:4324]......../smpd_add_command_int_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........\smpd_dbs_create [01:4324]......../smpd_dbs_create [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........sending result command to parent context: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cm d_orig=start_dbs kvs_name=8623E2F2-530C-4ac1-A5FD-54E130D2E71F domain_name=30BBB9AD-EB40-4080-B1B5-BAD B4D3EECF2 result=SUCCESS " [01:4324]........\smpd_post_write_command [01:4324].........\smpd_package_command [01:4324]........./smpd_package_command [01:4324].........smpd_post_write_command on the parent context sock 1340: 183 bytes for command: "cmd =result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=start_dbs kvs_name=8623E2F2-530C-4ac1-A5FD-54E130D2E71F domain_name=30BBB9AD-EB40-4080-B1B5-BADB4D3EECF2 result=SUCCESS " [01:4324]......../smpd_post_write_command [01:4324]......./smpd_handle_start_dbs_command [01:4324]....../smpd_handle_command [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the parent context, sock 1340 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_reading_cmd [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cmd [01:4324]......wrote command [01:4324]......command written to parent: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=start_dbs kvs_name=8623E2F2-530C-4ac1-A5FD-54E130D2E71F domain_name=30BBB9AD-EB40-4080-B1B5-BADB4D3EECF2 result= SUCCESS " [01:4324]......\smpd_free_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_free_command [01:4324]...../smpd_state_writing_cmd [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd_header [01:4324]......read command header [01:4324]......command header read, posting read for data: 264 bytes [01:4324]...../smpd_state_reading_cmd_header [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd [01:4324]......read command [01:4324]......\smpd_parse_command [01:4324]....../smpd_parse_command [01:4324]......read command: "cmd=launch src=0 dest=2 tag=2 c="c:\public\bin\MpiAppD.exe C:\temp\mpi\7 input.txt output.txt log.txt" s=0 a=0 e=" MPICH_INTERFACE_HOSTNAME=171.185.14.188" d=C:\public\bin q= 1 i=1 n=2 k=8623E2F2-530C-4ac1-A5FD-54E130D2E71F kd=30BBB9AD-EB40-4080-B1B5-BADB4D3EECF2 " [01:4324]......\smpd_handle_command [01:4324].......handling command: [01:4324]....... src = 0 [01:4324]....... dest = 2 [01:4324]....... cmd = launch [01:4324]....... tag = 2 [01:4324]....... ctx = parent [01:4324]....... len = 264 [01:4324]....... str = cmd=launch src=0 dest=2 tag=2 c="c:\public\bin\MpiAppD.exe C:\temp\mpi\7 input .txt output.txt log.txt" s=0 a=0 e=" MPICH_INTERFACE_HOSTNAME=171.185.14.188" d=C:\public\bin q=1 i=1 n=2 k=8623E2F2-530C-4ac1-A5FD-54E130D2E71F kd=30BBB9AD-EB40-4080-B1B5-BADB4D3EECF2 [01:4324].......\smpd_command_destination [01:4324]........1 -> 2 : returning left_context [01:4324]......./smpd_command_destination [01:4324].......forwarding command to 2 [01:4324].......\smpd_forward_command [01:4324]........\smpd_create_command_copy [01:4324]......../smpd_create_command_copy [01:4324]........posting write of forwarded command: "cmd=launch src=0 dest=2 tag=2 c="c:\public\bin\M piAppD.exe C:\temp\mpi\7 input.txt output.txt log.txt" s=0 a=0 e=" MPICH_INTERFACE_HOSTNAME=171.185.14 .188" d=C:\public\bin q=1 i=1 n=2 k=8623E2F2-530C-4ac1-A5FD-54E130D2E71F kd=30BBB9AD-EB40-4080-B1B5-BA DB4D3EECF2 " [01:4324]........\smpd_post_write_command [01:4324].........\smpd_package_command [01:4324]........./smpd_package_command [01:4324].........smpd_post_write_command on the left context sock 1296: 277 bytes for command: "cmd=l aunch src=0 dest=2 tag=2 c="c:\public\bin\MpiAppD.exe C:\temp\mpi\7 input.txt output.txt log.txt" s=0 a=0 e=" MPICH_INTERFACE_HOSTNAME=171.185.14.188" d=C:\public\bin q=1 i=1 n=2 k=8623E2F2-530C-4ac1-A5FD -54E130D2E71F kd=30BBB9AD-EB40-4080-B1B5-BADB4D3EECF2 " [01:4324]......../smpd_post_write_command [01:4324]......./smpd_forward_command [01:4324]....../smpd_handle_command [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the parent context, sock 1340 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_reading_cmd [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cmd [01:4324]......wrote command [01:4324]......command written to left: "cmd=launch src=0 dest=2 tag=2 c="c:\public\bin\MpiAppD.exe C: \temp\mpi\7 input.txt output.txt log.txt" s=0 a=0 e=" MPICH_INTERFACE_HOSTNAME=171.185.14.188" d=C:\pu blic\bin q=1 i=1 n=2 k=8623E2F2-530C-4ac1-A5FD-54E130D2E71F kd=30BBB9AD-EB40-4080-B1B5-BADB4D3EECF2 " [01:4324]......\smpd_free_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_free_command [01:4324]...../smpd_state_writing_cmd [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd_header [01:4324]......read command header [01:4324]......command header read, posting read for data: 263 bytes [01:4324]...../smpd_state_reading_cmd_header [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd [01:4324]......read command [01:4324]......\smpd_parse_command [01:4324]....../smpd_parse_command [01:4324]......read command: "cmd=launch src=0 dest=1 tag=3 c="c:\public\bin\MpiAppD.exe C:\temp\mpi\7 input.txt output.txt log.txt" s=0 a=0 e=" MPICH_INTERFACE_HOSTNAME=171.188.33.19" d=C:\public\bin q=0 i=0 n=2 k=8623E2F2-530C-4ac1-A5FD-54E130D2E71F kd=30BBB9AD-EB40-4080-B1B5-BADB4D3EECF2 " [01:4324]......\smpd_handle_command [01:4324].......handling command: [01:4324]....... src = 0 [01:4324]....... dest = 1 [01:4324]....... cmd = launch [01:4324]....... tag = 3 [01:4324]....... ctx = parent [01:4324]....... len = 263 [01:4324]....... str = cmd=launch src=0 dest=1 tag=3 c="c:\public\bin\MpiAppD.exe C:\temp\mpi\7 input .txt output.txt log.txt" s=0 a=0 e=" MPICH_INTERFACE_HOSTNAME=171.188.33.19" d=C:\public\bin q=0 i=0 n =2 k=8623E2F2-530C-4ac1-A5FD-54E130D2E71F kd=30BBB9AD-EB40-4080-B1B5-BADB4D3EECF2 [01:4324].......\smpd_command_destination [01:4324]........1 -> 1 : returning NULL context [01:4324]......./smpd_command_destination [01:4324].......\smpd_handle_launch_command [01:4324]........\smpd_create_process_struct [01:4324].........\smpd_create_context [01:4324]..........\smpd_init_context [01:4324]...........\smpd_init_command [01:4324].........../smpd_init_command [01:4324]........../smpd_init_context [01:4324]........./smpd_create_context [01:4324].........\smpd_create_context [01:4324]..........\smpd_init_context [01:4324]...........\smpd_init_command [01:4324].........../smpd_init_command [01:4324]........../smpd_init_context [01:4324]........./smpd_create_context [01:4324].........\smpd_create_context [01:4324]..........\smpd_init_context [01:4324]...........\smpd_init_command [01:4324].........../smpd_init_command [01:4324]........../smpd_init_context [01:4324]........./smpd_create_context [01:4324].........\smpd_create_context [01:4324]..........\smpd_init_context [01:4324]...........\smpd_init_command [01:4324].........../smpd_init_command [01:4324]........../smpd_init_context [01:4324]........./smpd_create_context [01:4324]......../smpd_create_process_struct [01:4324]........launching: 'c:\public\bin\MpiAppD.exe C:\temp\mpi\7 input.txt output.txt log.txt' [01:4324]........\smpd_launch_process [01:4324].........\smpd_priority_class_to_win_class [01:4324]........./smpd_priority_class_to_win_class [01:4324].........\smpd_priority_to_win_priority [01:4324]........./smpd_priority_to_win_priority [01:4324].........\smpd_make_socket_loop_choose [01:4324]..........\smpd_get_hostname [01:4324]........../smpd_get_hostname [01:4324]........./smpd_make_socket_loop_choose [01:4324].........\smpd_make_socket_loop_choose [01:4324]..........\smpd_get_hostname [01:4324]........../smpd_get_hostname [01:4324]........./smpd_make_socket_loop_choose [01:4324].........\smpd_make_socket_loop_choose [01:4324]..........\smpd_get_hostname [01:4324]........../smpd_get_hostname [01:4324]........./smpd_make_socket_loop_choose [01:4324].........pmi listening on port 3087 [01:4324].........\smpd_create_context [01:4324]..........\smpd_init_context [01:4324]...........\smpd_init_command [01:4324].........../smpd_init_command [01:4324]........../smpd_init_context [01:4324]........./smpd_create_context [01:4324].........\SetEnvironmentVariables [01:4324]..........setting environment variable: = <171.188.33.19> [01:4324]........./SetEnvironmentVariables [01:4324].........env: PMI_RANK=0 [01:4324].........env: PMI_SIZE=2 [01:4324].........env: PMI_KVS=8623E2F2-530C-4ac1-A5FD-54E130D2E71F [01:4324].........env: PMI_DOMAIN=30BBB9AD-EB40-4080-B1B5-BADB4D3EECF2 [01:4324].........env: PMI_HOST=B0016350B383E.corp.bankofamerica.com 171.188.33.19 [01:4324].........env: PMI_PORT=3087 [01:4324].........env: PMI_SMPD_ID=1 [01:4324].........env: PMI_SMPD_KEY=0 [01:4324].........env: PMI_CLIQUE=0 [01:4324].........env: PMI_SPAWN=0 [01:4324].........env: PMI_APPNUM=0 [01:4324].........CreateProcess(c:\public\bin\MpiAppD.exe C:\temp\mpi\7 input.txt output.txt log.txt) [01:4324].........\RemoveEnvironmentVariables [01:4324]........./RemoveEnvironmentVariables [01:4324].........*** entering smpd_pinthread pid:2028 sock:1288 *** [01:4324].........\smpd_process_to_registry [01:4324]..........*** entering smpd_piothread pid:2028 sock:1268 *** [01:4324]..........*** entering smpd_piothread pid:2028 sock:1244 *** [01:4324]........./smpd_process_to_registry [01:4324]......../smpd_launch_process [01:4324]........\smpd_create_command [01:4324].........\smpd_init_command [01:4324]........./smpd_init_command [01:4324]......../smpd_create_command [01:4324]........\smpd_add_command_int_arg [01:4324]......../smpd_add_command_int_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........\smpd_add_command_int_arg [01:4324]......../smpd_add_command_int_arg [01:4324]........\smpd_add_command_int_arg [01:4324]......../smpd_add_command_int_arg [01:4324]........\smpd_post_write_command [01:4324].........\smpd_package_command [01:4324]........./smpd_package_command [01:4324].........smpd_post_write_command on the parent context sock 1340: 147 bytes for command: "cmd =result src=1 dest=0 tag=2 cmd_tag=3 cmd_orig=launch result=SUCCESS pg_id=8623E2F2-530C-4ac1-A5FD-54E1 30D2E71F pg_rank=0 pg_ctx=0 " [01:4324]......../smpd_post_write_command [01:4324]......./smpd_handle_launch_command [01:4324]....../smpd_handle_command [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the parent context, sock 1340 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_reading_cmd [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd_header [01:4324]......read command header [01:4324]......command header read, posting read for data: 134 bytes [01:4324]...../smpd_state_reading_cmd_header [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cmd [01:4324]......wrote command [01:4324]......command written to parent: "cmd=result src=1 dest=0 tag=2 cmd_tag=3 cmd_orig=launch res ult=SUCCESS pg_id=8623E2F2-530C-4ac1-A5FD-54E130D2E71F pg_rank=0 pg_ctx=0 " [01:4324]......\smpd_free_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_free_command [01:4324]...../smpd_state_writing_cmd [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd [01:4324]......read command [01:4324]......\smpd_parse_command [01:4324]....../smpd_parse_command [01:4324]......read command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS p g_id=8623E2F2-530C-4ac1-A5FD-54E130D2E71F pg_rank=1 pg_ctx=0 " [01:4324]......\smpd_handle_command [01:4324].......handling command: [01:4324]....... src = 2 [01:4324]....... dest = 0 [01:4324]....... cmd = result [01:4324]....... tag = 0 [01:4324]....... ctx = left [01:4324]....... len = 134 [01:4324]....... str = cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=8 623E2F2-530C-4ac1-A5FD-54E130D2E71F pg_rank=1 pg_ctx=0 [01:4324].......\smpd_command_destination [01:4324]........1 -> 0 : returning parent_context: 0 < 1 [01:4324]......./smpd_command_destination [01:4324].......forwarding command to 0 [01:4324].......\smpd_forward_command [01:4324]........\smpd_create_command_copy [01:4324]......../smpd_create_command_copy [01:4324]........posting write of forwarded command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig =launch result=SUCCESS pg_id=8623E2F2-530C-4ac1-A5FD-54E130D2E71F pg_rank=1 pg_ctx=0 " [01:4324]........\smpd_post_write_command [01:4324].........\smpd_package_command [01:4324]........./smpd_package_command [01:4324].........smpd_post_write_command on the parent context sock 1340: 147 bytes for command: "cmd =result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=8623E2F2-530C-4ac1-A5FD-54E1 30D2E71F pg_rank=1 pg_ctx=0 " [01:4324]......../smpd_post_write_command [01:4324]......./smpd_forward_command [01:4324]....../smpd_handle_command [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the left context, sock 1296 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_reading_cmd [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cmd [01:4324]......wrote command [01:4324]......command written to parent: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch res ult=SUCCESS pg_id=8623E2F2-530C-4ac1-A5FD-54E130D2E71F pg_rank=1 pg_ctx=0 " [01:4324]......\smpd_free_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_free_command [01:4324]...../smpd_state_writing_cmd [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd_header [01:4324]......read command header [01:4324]......command header read, posting read for data: 105 bytes [01:4324]...../smpd_state_reading_cmd_header [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd [01:4324]......read command [01:4324]......\smpd_parse_command [01:4324]....../smpd_parse_command [01:4324]......read command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E13 0D2E71F key=1 value=2 node_id=2 " [01:4324]......\smpd_handle_command [01:4324].......handling command: [01:4324]....... src = 2 [01:4324]....... dest = 0 [01:4324]....... cmd = init [01:4324]....... tag = 0 [01:4324]....... ctx = left [01:4324]....... len = 105 [01:4324]....... str = cmd=init src=2 dest=0 tag=0 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E130D2E71 F key=1 value=2 node_id=2 [01:4324].......\smpd_command_destination [01:4324]........1 -> 0 : returning parent_context: 0 < 1 [01:4324]......./smpd_command_destination [01:4324].......forwarding command to 0 [01:4324].......\smpd_forward_command [01:4324]........\smpd_create_command_copy [01:4324]......../smpd_create_command_copy [01:4324]........posting write of forwarded command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=8623E 2F2-530C-4ac1-A5FD-54E130D2E71F key=1 value=2 node_id=2 " [01:4324]........\smpd_post_write_command [01:4324].........\smpd_package_command [01:4324]........./smpd_package_command [01:4324].........smpd_post_write_command on the parent context sock 1340: 118 bytes for command: "cmd =init src=2 dest=0 tag=0 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E130D2E71F key=1 value=2 node_id=2 " [01:4324]......../smpd_post_write_command [01:4324]......./smpd_forward_command [01:4324]....../smpd_handle_command [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the left context, sock 1296 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_reading_cmd [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cmd [01:4324]......wrote command [01:4324]......command written to parent: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=8623E2F2-530C-4a c1-A5FD-54E130D2E71F key=1 value=2 node_id=2 " [01:4324]......\smpd_free_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_free_command [01:4324]...../smpd_state_writing_cmd [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd_header [01:4324]......read command header [01:4324]......command header read, posting read for data: 66 bytes [01:4324]...../smpd_state_reading_cmd_header [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd [01:4324]......read command [01:4324]......\smpd_parse_command [01:4324]....../smpd_parse_command [01:4324]......read command: "cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS " [01:4324]......\smpd_handle_command [01:4324].......handling command: [01:4324]....... src = 0 [01:4324]....... dest = 2 [01:4324]....... cmd = result [01:4324]....... tag = 4 [01:4324]....... ctx = parent [01:4324]....... len = 66 [01:4324]....... str = cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS [01:4324].......\smpd_command_destination [01:4324]........1 -> 2 : returning left_context [01:4324]......./smpd_command_destination [01:4324].......forwarding command to 2 [01:4324].......\smpd_forward_command [01:4324]........\smpd_create_command_copy [01:4324]......../smpd_create_command_copy [01:4324]........posting write of forwarded command: "cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key= 0 result=SUCCESS " [01:4324]........\smpd_post_write_command [01:4324].........\smpd_package_command [01:4324]........./smpd_package_command [01:4324].........smpd_post_write_command on the left context sock 1296: 79 bytes for command: "cmd=re sult src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS " [01:4324]......../smpd_post_write_command [01:4324]......./smpd_forward_command [01:4324]....../smpd_handle_command [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the parent context, sock 1340 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_reading_cmd [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cmd [01:4324]......wrote command [01:4324]......command written to left: "cmd=result src=0 dest=2 tag=4 cmd_tag=0 ctx_key=0 result=SUCC ESS " [01:4324]......\smpd_free_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_free_command [01:4324]...../smpd_state_writing_cmd [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd_header [01:4324]......read command header [01:4324]......command header read, posting read for data: 170 bytes [01:4324]...../smpd_state_reading_cmd_header [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd [01:4324]......read command [01:4324]......\smpd_parse_command [01:4324]....../smpd_parse_command [01:4324]......read command: "cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E1 30D2E71F key=P1-businesscard value="port=1428 description=171.185.14.188 ifname=171.185.14.188 " " [01:4324]......\smpd_handle_command [01:4324].......handling command: [01:4324]....... src = 2 [01:4324]....... dest = 1 [01:4324]....... cmd = dbput [01:4324]....... tag = 1 [01:4324]....... ctx = left [01:4324]....... len = 170 [01:4324]....... str = cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E130D2E7 1F key=P1-businesscard value="port=1428 description=171.185.14.188 ifname=171.185.14.188 " [01:4324].......\smpd_command_destination [01:4324]........1 -> 1 : returning NULL context [01:4324]......./smpd_command_destination [01:4324].......\smpd_handle_dbs_command [01:4324]........\smpd_create_command [01:4324].........\smpd_init_command [01:4324]........./smpd_init_command [01:4324]......../smpd_create_command [01:4324]........\smpd_add_command_int_arg [01:4324]......../smpd_add_command_int_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........\get_name_key_value [01:4324]......../get_name_key_value [01:4324]........\smpd_dbs_put [01:4324]......../smpd_dbs_put [01:4324]........sending reply to dbs command 'cmd=dbput src=2 dest=1 tag=1 ctx_key=0 name=8623E2F2-53 0C-4ac1-A5FD-54E130D2E71F key=P1-businesscard value="port=1428 description=171.185.14.188 ifname=171.1 85.14.188 " '. [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........sending result command to left context: "cmd=result src=1 dest=2 tag=3 cmd_tag=1 cmd_ orig=dbput ctx_key=0 result=DBS_SUCCESS " [01:4324]........\smpd_post_write_command [01:4324].........\smpd_package_command [01:4324]........./smpd_package_command [01:4324].........smpd_post_write_command on the left context sock 1296: 98 bytes for command: "cmd=re sult src=1 dest=2 tag=3 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS " [01:4324]......../smpd_post_write_command [01:4324]......./smpd_handle_dbs_command [01:4324]....../smpd_handle_command [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the left context, sock 1296 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_reading_cmd [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cmd [01:4324]......wrote command [01:4324]......command written to left: "cmd=result src=1 dest=2 tag=3 cmd_tag=1 cmd_orig=dbput ctx_ke y=0 result=DBS_SUCCESS " [01:4324]......\smpd_free_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_free_command [01:4324]...../smpd_state_writing_cmd [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd_header [01:4324]......read command header [01:4324]......command header read, posting read for data: 92 bytes [01:4324]...../smpd_state_reading_cmd_header [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd [01:4324]......read command [01:4324]......\smpd_parse_command [01:4324]....../smpd_parse_command [01:4324]......read command: "cmd=barrier src=2 dest=1 tag=2 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54 E130D2E71F value=2 " [01:4324]......\smpd_handle_command [01:4324].......handling command: [01:4324]....... src = 2 [01:4324]....... dest = 1 [01:4324]....... cmd = barrier [01:4324]....... tag = 2 [01:4324]....... ctx = left [01:4324]....... len = 92 [01:4324]....... str = cmd=barrier src=2 dest=1 tag=2 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E130D2 E71F value=2 [01:4324].......\smpd_command_destination [01:4324]........1 -> 1 : returning NULL context [01:4324]......./smpd_command_destination [01:4324].......\smpd_handle_barrier_command [01:4324]........initializing barrier(8623E2F2-530C-4ac1-A5FD-54E130D2E71F): in=1 size=2 [01:4324]......./smpd_handle_barrier_command [01:4324]....../smpd_handle_command [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the left context, sock 1296 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_reading_cmd [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_ACCEPT [01:4324]....\smpd_handle_op_accept [01:4324].....\smpd_state_pmi_listening [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the pmi context, sock 1092 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_pmi_listening [01:4324]..../smpd_handle_op_accept [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_CLOSE [01:4324]....\smpd_handle_op_close [01:4324].....\smpd_get_state_string [01:4324]...../smpd_get_state_string [01:4324].....op_close received - SMPD_CLOSING state. [01:4324].....Unaffiliated unknown context closing. [01:4324].....\smpd_free_context [01:4324]......freeing unknown context. [01:4324]......\smpd_init_context [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_init_context [01:4324]...../smpd_free_context [01:4324]..../smpd_handle_op_close [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd_header [01:4324]......read command header [01:4324]......command header read, posting read for data: 105 bytes [01:4324]...../smpd_state_reading_cmd_header [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd [01:4324]......read command [01:4324]......\smpd_parse_command [01:4324]....../smpd_parse_command [01:4324]......read command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E13 0D2E71F key=0 value=2 node_id=1 " [01:4324]......\smpd_handle_command [01:4324].......handling command: [01:4324]....... src = 1 [01:4324]....... dest = 0 [01:4324]....... cmd = init [01:4324]....... tag = 0 [01:4324]....... ctx = pmi [01:4324]....... len = 105 [01:4324]....... str = cmd=init src=1 dest=0 tag=0 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E130D2E71 F key=0 value=2 node_id=1 [01:4324].......\smpd_command_destination [01:4324]........1 -> 0 : returning parent_context: 0 < 1 [01:4324]......./smpd_command_destination [01:4324].......forwarding command to 0 [01:4324].......\smpd_forward_command [01:4324]........\smpd_create_command_copy [01:4324]......../smpd_create_command_copy [01:4324]........posting write of forwarded command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=8623E 2F2-530C-4ac1-A5FD-54E130D2E71F key=0 value=2 node_id=1 " [01:4324]........\smpd_post_write_command [01:4324].........\smpd_package_command [01:4324]........./smpd_package_command [01:4324].........smpd_post_write_command on the parent context sock 1340: 118 bytes for command: "cmd =init src=1 dest=0 tag=0 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E130D2E71F key=0 value=2 node_id=1 " [01:4324]......../smpd_post_write_command [01:4324]......./smpd_forward_command [01:4324]....../smpd_handle_command [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the pmi context, sock 1092 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_reading_cmd [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cmd [01:4324]......wrote command [01:4324]......command written to parent: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=8623E2F2-530C-4a c1-A5FD-54E130D2E71F key=0 value=2 node_id=1 " [01:4324]......\smpd_free_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_free_command [01:4324]...../smpd_state_writing_cmd [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd_header [01:4324]......read command header [01:4324]......command header read, posting read for data: 66 bytes [01:4324]...../smpd_state_reading_cmd_header [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd [01:4324]......read command [01:4324]......\smpd_parse_command [01:4324]....../smpd_parse_command [01:4324]......read command: "cmd=result src=0 dest=1 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS " [01:4324]......\smpd_handle_command [01:4324].......handling command: [01:4324]....... src = 0 [01:4324]....... dest = 1 [01:4324]....... cmd = result [01:4324]....... tag = 5 [01:4324]....... ctx = parent [01:4324]....... len = 66 [01:4324]....... str = cmd=result src=0 dest=1 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS [01:4324].......\smpd_command_destination [01:4324]........1 -> 1 : returning NULL context [01:4324]......./smpd_command_destination [01:4324].......\smpd_handle_result [01:4324]........forwarding the dbs result command to the pmi context 0. [01:4324]........\smpd_forward_command [01:4324].........\smpd_create_command_copy [01:4324]........./smpd_create_command_copy [01:4324].........posting write of forwarded command: "cmd=result src=0 dest=1 tag=5 cmd_tag=0 ctx_key =0 result=SUCCESS " [01:4324].........\smpd_post_write_command [01:4324]..........\smpd_package_command [01:4324]........../smpd_package_command [01:4324]..........smpd_post_write_command on the pmi context sock 1092: 79 bytes for command: "cmd=re sult src=0 dest=1 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS " [01:4324]........./smpd_post_write_command [01:4324]......../smpd_forward_command [01:4324]......./smpd_handle_result [01:4324]....../smpd_handle_command [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the parent context, sock 1340 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_reading_cmd [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cmd [01:4324]......wrote command [01:4324]......command written to pmi: "cmd=result src=0 dest=1 tag=5 cmd_tag=0 ctx_key=0 result=SUCCE SS " [01:4324]......\smpd_free_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_free_command [01:4324]...../smpd_state_writing_cmd [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd_header [01:4324]......read command header [01:4324]......command header read, posting read for data: 168 bytes [01:4324]...../smpd_state_reading_cmd_header [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd [01:4324]......read command [01:4324]......\smpd_parse_command [01:4324]....../smpd_parse_command [01:4324]......read command: "cmd=dbput src=1 dest=1 tag=1 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E1 30D2E71F key=P0-businesscard value="port=3091 description=171.188.33.19 ifname=171.188.33.19 " " [01:4324]......\smpd_handle_command [01:4324].......handling command: [01:4324]....... src = 1 [01:4324]....... dest = 1 [01:4324]....... cmd = dbput [01:4324]....... tag = 1 [01:4324]....... ctx = pmi [01:4324]....... len = 168 [01:4324]....... str = cmd=dbput src=1 dest=1 tag=1 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E130D2E7 1F key=P0-businesscard value="port=3091 description=171.188.33.19 ifname=171.188.33.19 " [01:4324].......\smpd_command_destination [01:4324]........1 -> 1 : returning NULL context [01:4324]......./smpd_command_destination [01:4324].......\smpd_handle_dbs_command [01:4324]........\smpd_create_command [01:4324].........\smpd_init_command [01:4324]........./smpd_init_command [01:4324]......../smpd_create_command [01:4324]........\smpd_add_command_int_arg [01:4324]......../smpd_add_command_int_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........\get_name_key_value [01:4324]......../get_name_key_value [01:4324]........\smpd_dbs_put [01:4324]......../smpd_dbs_put [01:4324]........sending reply to dbs command 'cmd=dbput src=1 dest=1 tag=1 ctx_key=0 name=8623E2F2-53 0C-4ac1-A5FD-54E130D2E71F key=P0-businesscard value="port=3091 description=171.188.33.19 ifname=171.18 8.33.19 " '. [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........sending result command to pmi context: "cmd=result src=1 dest=1 tag=4 cmd_tag=1 cmd_o rig=dbput ctx_key=0 result=DBS_SUCCESS " [01:4324]........\smpd_post_write_command [01:4324].........\smpd_package_command [01:4324]........./smpd_package_command [01:4324].........smpd_post_write_command on the pmi context sock 1092: 98 bytes for command: "cmd=res ult src=1 dest=1 tag=4 cmd_tag=1 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS " [01:4324]......../smpd_post_write_command [01:4324]......./smpd_handle_dbs_command [01:4324]....../smpd_handle_command [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the pmi context, sock 1092 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_reading_cmd [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cmd [01:4324]......wrote command [01:4324]......command written to pmi: "cmd=result src=1 dest=1 tag=4 cmd_tag=1 cmd_orig=dbput ctx_key =0 result=DBS_SUCCESS " [01:4324]......\smpd_free_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_free_command [01:4324]...../smpd_state_writing_cmd [01:4324]..../smpd_handle_op_write [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd_header [01:4324]......read command header [01:4324]......command header read, posting read for data: 92 bytes [01:4324]...../smpd_state_reading_cmd_header [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_READ [01:4324]....\smpd_handle_op_read [01:4324].....\smpd_state_reading_cmd [01:4324]......read command [01:4324]......\smpd_parse_command [01:4324]....../smpd_parse_command [01:4324]......read command: "cmd=barrier src=1 dest=1 tag=2 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54 E130D2E71F value=2 " [01:4324]......\smpd_handle_command [01:4324].......handling command: [01:4324]....... src = 1 [01:4324]....... dest = 1 [01:4324]....... cmd = barrier [01:4324]....... tag = 2 [01:4324]....... ctx = pmi [01:4324]....... len = 92 [01:4324]....... str = cmd=barrier src=1 dest=1 tag=2 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E130D2 E71F value=2 [01:4324].......\smpd_command_destination [01:4324]........1 -> 1 : returning NULL context [01:4324]......./smpd_command_destination [01:4324].......\smpd_handle_barrier_command [01:4324]........incrementing barrier(8623E2F2-530C-4ac1-A5FD-54E130D2E71F) incount from 1 to 2 out of 2 [01:4324]........all in barrier, sending result back to all participators. [01:4324]........\smpd_create_command [01:4324].........\smpd_init_command [01:4324]........./smpd_init_command [01:4324]......../smpd_create_command [01:4324]........\smpd_add_command_int_arg [01:4324]......../smpd_add_command_int_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........sending reply to barrier command '8623E2F2-530C-4ac1-A5FD-54E130D2E71F'. [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........sending result command to pmi context: "cmd=result src=1 dest=2 tag=5 cmd_tag=2 cmd_o rig=barrier ctx_key=0 result=DBS_SUCCESS " [01:4324]........\smpd_post_write_command [01:4324].........\smpd_package_command [01:4324]........./smpd_package_command [01:4324].........smpd_post_write_command on the left context sock 1296: 100 bytes for command: "cmd=r esult src=1 dest=2 tag=5 cmd_tag=2 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:4324]......../smpd_post_write_command [01:4324]........\smpd_create_command [01:4324].........\smpd_init_command [01:4324]........./smpd_init_command [01:4324]......../smpd_create_command [01:4324]........\smpd_add_command_int_arg [01:4324]......../smpd_add_command_int_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........sending reply to barrier command '8623E2F2-530C-4ac1-A5FD-54E130D2E71F'. [01:4324]........\smpd_add_command_arg [01:4324]......../smpd_add_command_arg [01:4324]........sending result command to pmi context: "cmd=result src=1 dest=1 tag=6 cmd_tag=2 cmd_o rig=barrier ctx_key=0 result=DBS_SUCCESS " [01:4324]........\smpd_post_write_command [01:4324].........\smpd_package_command [01:4324]........./smpd_package_command [01:4324].........smpd_post_write_command on the pmi context sock 1092: 100 bytes for command: "cmd=re sult src=1 dest=1 tag=6 cmd_tag=2 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:4324]......../smpd_post_write_command [01:4324]......./smpd_handle_barrier_command [01:4324]....../smpd_handle_command [01:4324]......\smpd_post_read_command [01:4324].......posting a read for a command header on the pmi context, sock 1092 [01:4324]....../smpd_post_read_command [01:4324]...../smpd_state_reading_cmd [01:4324]..../smpd_handle_op_read [01:4324]....sock_waiting for the next event. [01:4324]....SOCK_OP_WRITE [01:4324]....\smpd_handle_op_write [01:4324].....\smpd_state_writing_cmd [01:4324]......wrote command [01:4324]...../smpd_easy_send [01:4324]....command written to left: "cmd=result src=1 dest=2 tag=5 cmd_tag=2 cmd_orig=barrier ctx_ke y=0 result=DBS_SUCCESS " [01:4324]..../smpd_easy_send [01:4324]....\smpd_free_command [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_free_command [01:4324].../smpd_state_writing_cmd [01:4324]../smpd_handle_op_write [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_WRITE [01:4324]..\smpd_handle_op_write [01:4324]...\smpd_state_writing_cmd [01:4324]....wrote command [01:4324]....command written to pmi: "cmd=result src=1 dest=1 tag=6 cmd_tag=2 cmd_orig=barrier ctx_key =0 result=DBS_SUCCESS " [01:4324]....\smpd_free_command [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_free_command [01:4324].../smpd_state_writing_cmd [01:4324]../smpd_handle_op_write [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_stdouterr [01:4324]....read from stderr [01:4324]....69 bytes read from stderr [01:4324]....\smpd_create_command [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_create_command [01:4324]....\smpd_add_command_int_arg [01:4324]..../smpd_add_command_int_arg [01:4324]....\smpd_add_command_int_arg [01:4324]..../smpd_add_command_int_arg [01:4324]....\smpd_add_command_arg [01:4324]..../smpd_add_command_arg [01:4324]....\smpd_post_write_command [01:4324].....\smpd_package_command [01:4324]...../smpd_package_command [01:4324].....smpd_post_write_command on the parent context sock 1340: 203 bytes for command: "cmd=std err src=1 dest=0 tag=7 rank=0 first=1 data=50726F6365737320202030206F662032206F6E204230303136333530423 33833452E636F72702E62616E6B6F66616D65726963612E636F6D20287069642032303238290D0A " [01:4324]..../smpd_post_write_command [01:4324].../smpd_state_reading_stdouterr [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd_header [01:4324]....read command header [01:4324]....command header read, posting read for data: 176 bytes [01:4324].../smpd_state_reading_cmd_header [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_WRITE [01:4324]..\smpd_handle_op_write [01:4324]...\smpd_state_writing_cmd [01:4324]....wrote command [01:4324]....command written to parent: "cmd=stderr src=1 dest=0 tag=7 rank=0 first=1 data=50726F63657 37320202030206F662032206F6E20423030313633353042333833452E636F72702E62616E6B6F66616D65726963612E636F6D2 0287069642032303238290D0A " [01:4324]....\smpd_free_command [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_free_command [01:4324].../smpd_state_writing_cmd [01:4324]../smpd_handle_op_write [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd [01:4324]....read command [01:4324]....\smpd_parse_command [01:4324]..../smpd_parse_command [01:4324]....read command: "cmd=stderr src=2 dest=0 tag=1 rank=1 first=1 data=50726F636573732020203120 6F662032206F6E2063727073637367617078742E62616E6B6F66616D65726963612E636F6D20287069642034393532290D0A " [01:4324]....\smpd_handle_command [01:4324].....handling command: [01:4324]..... src = 2 [01:4324]..... dest = 0 [01:4324]..... cmd = stderr [01:4324]..... tag = 1 [01:4324]..... ctx = left [01:4324]..... len = 176 [01:4324]..... str = cmd=stderr src=2 dest=0 tag=1 rank=1 first=1 data=50726F6365737320202031206F6620 32206F6E2063727073637367617078742E62616E6B6F66616D65726963612E636F6D20287069642034393532290D0A [01:4324].....\smpd_command_destination [01:4324]......1 -> 0 : returning parent_context: 0 < 1 [01:4324]...../smpd_command_destination [01:4324].....forwarding command to 0 [01:4324].....\smpd_forward_command [01:4324]......\smpd_create_command_copy [01:4324]....../smpd_create_command_copy [01:4324]......posting write of forwarded command: "cmd=stderr src=2 dest=0 tag=1 rank=1 first=1 data= 50726F6365737320202031206F662032206F6E2063727073637367617078742E62616E6B6F66616D65726963612E636F6D2028 7069642034393532290D0A " [01:4324]......\smpd_post_write_command [01:4324].......\smpd_package_command [01:4324]......./smpd_package_command [01:4324].......smpd_post_write_command on the parent context sock 1340: 189 bytes for command: "cmd=s tderr src=2 dest=0 tag=1 rank=1 first=1 data=50726F6365737320202031206F662032206F6E2063727073637367617 078742E62616E6B6F66616D65726963612E636F6D20287069642034393532290D0A " [01:4324]....../smpd_post_write_command [01:4324]...../smpd_forward_command [01:4324]..../smpd_handle_command [01:4324]....\smpd_post_read_command [01:4324].....posting a read for a command header on the left context, sock 1296 [01:4324]..../smpd_post_read_command [01:4324].../smpd_state_reading_cmd [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_WRITE [01:4324]..\smpd_handle_op_write [01:4324]...\smpd_state_writing_cmd [01:4324]....wrote command [01:4324]....command written to parent: "cmd=stderr src=2 dest=0 tag=1 rank=1 first=1 data=50726F63657 37320202031206F662032206F6E2063727073637367617078742E62616E6B6F66616D65726963612E636F6D202870696420343 93532290D0A " [01:4324]....\smpd_free_command [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_free_command [01:4324].../smpd_state_writing_cmd [01:4324]../smpd_handle_op_write [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd_header [01:4324]....read command header [01:4324]....command header read, posting read for data: 102 bytes [01:4324].../smpd_state_reading_cmd_header [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd [01:4324]....read command [01:4324]....\smpd_parse_command [01:4324]..../smpd_parse_command [01:4324]....read command: "cmd=dbget src=1 dest=1 tag=3 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E130 D2E71F key=P1-businesscard " [01:4324]....\smpd_handle_command [01:4324].....handling command: [01:4324]..... src = 1 [01:4324]..... dest = 1 [01:4324]..... cmd = dbget [01:4324]..... tag = 3 [01:4324]..... ctx = pmi [01:4324]..... len = 102 [01:4324]..... str = cmd=dbget src=1 dest=1 tag=3 ctx_key=0 name=8623E2F2-530C-4ac1-A5FD-54E130D2E71F key=P1-businesscard [01:4324].....\smpd_command_destination [01:4324]......1 -> 1 : returning NULL context [01:4324]...../smpd_command_destination [01:4324].....\smpd_handle_dbs_command [01:4324]......\smpd_create_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_create_command [01:4324]......\smpd_add_command_int_arg [01:4324]....../smpd_add_command_int_arg [01:4324]......\smpd_add_command_arg [01:4324]....../smpd_add_command_arg [01:4324]......\smpd_add_command_arg [01:4324]....../smpd_add_command_arg [01:4324]......\get_name_key_value [01:4324]....../get_name_key_value [01:4324]......\smpd_dbs_get [01:4324]....../smpd_dbs_get [01:4324]......\smpd_add_command_arg [01:4324]....../smpd_add_command_arg [01:4324]......sending reply to dbs command 'cmd=dbget src=1 dest=1 tag=3 ctx_key=0 name=8623E2F2-530C -4ac1-A5FD-54E130D2E71F key=P1-businesscard '. [01:4324]......\smpd_add_command_arg [01:4324]....../smpd_add_command_arg [01:4324]......sending result command to pmi context: "cmd=result src=1 dest=1 tag=8 cmd_tag=3 cmd_ori g=dbget ctx_key=0 value="port=1428 description=171.185.14.188 ifname=171.185.14.188 " result=DBS_SUCCE SS " [01:4324]......\smpd_post_write_command [01:4324].......\smpd_package_command [01:4324]......./smpd_package_command [01:4324].......smpd_post_write_command on the pmi context sock 1092: 166 bytes for command: "cmd=resu lt src=1 dest=1 tag=8 cmd_tag=3 cmd_orig=dbget ctx_key=0 value="port=1428 description=171.185.14.188 i fname=171.185.14.188 " result=DBS_SUCCESS " [01:4324]....../smpd_post_write_command [01:4324]...../smpd_handle_dbs_command [01:4324]..../smpd_handle_command [01:4324]....\smpd_post_read_command [01:4324].....posting a read for a command header on the pmi context, sock 1092 [01:4324]..../smpd_post_read_command [01:4324].../smpd_state_reading_cmd [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_WRITE [01:4324]..\smpd_handle_op_write [01:4324]...\smpd_state_writing_cmd [01:4324]....wrote command [01:4324]....command written to pmi: "cmd=result src=1 dest=1 tag=8 cmd_tag=3 cmd_orig=dbget ctx_key=0 value="port=1428 description=171.185.14.188 ifname=171.185.14.188 " result=DBS_SUCCESS " [01:4324]....\smpd_free_command [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_free_command [01:4324].../smpd_state_writing_cmd [01:4324]../smpd_handle_op_write [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd_header [01:4324]....read command header [01:4324]....command header read, posting read for data: 94 bytes [01:4324].../smpd_state_reading_cmd_header [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd [01:4324]....read command [01:4324]....\smpd_parse_command [01:4324]..../smpd_parse_command [01:4324]....read command: "cmd=exit src=2 dest=0 tag=2 rank=1 code=-1073741819 kvs=8623E2F2-530C-4ac1 -A5FD-54E130D2E71F " [01:4324]....\smpd_handle_command [01:4324].....handling command: [01:4324]..... src = 2 [01:4324]..... dest = 0 [01:4324]..... cmd = exit [01:4324]..... tag = 2 [01:4324]..... ctx = left [01:4324]..... len = 94 [01:4324]..... str = cmd=exit src=2 dest=0 tag=2 rank=1 code=-1073741819 kvs=8623E2F2-530C-4ac1-A5FD- 54E130D2E71F [01:4324].....\smpd_command_destination [01:4324]......1 -> 0 : returning parent_context: 0 < 1 [01:4324]...../smpd_command_destination [01:4324].....forwarding command to 0 [01:4324].....\smpd_forward_command [01:4324]......\smpd_create_command_copy [01:4324]....../smpd_create_command_copy [01:4324]......posting write of forwarded command: "cmd=exit src=2 dest=0 tag=2 rank=1 code=-107374181 9 kvs=8623E2F2-530C-4ac1-A5FD-54E130D2E71F " [01:4324]......\smpd_post_write_command [01:4324].......\smpd_package_command [01:4324]......./smpd_package_command [01:4324].......smpd_post_write_command on the parent context sock 1340: 107 bytes for command: "cmd=e xit src=2 dest=0 tag=2 rank=1 code=-1073741819 kvs=8623E2F2-530C-4ac1-A5FD-54E130D2E71F " [01:4324]....../smpd_post_write_command [01:4324]...../smpd_forward_command [01:4324]..../smpd_handle_command [01:4324]....\smpd_post_read_command [01:4324].....posting a read for a command header on the left context, sock 1296 [01:4324]..../smpd_post_read_command [01:4324].../smpd_state_reading_cmd [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_WRITE [01:4324]..\smpd_handle_op_write [01:4324]...\smpd_state_writing_cmd [01:4324]....wrote command [01:4324]....command written to parent: "cmd=exit src=2 dest=0 tag=2 rank=1 code=-1073741819 kvs=8623E 2F2-530C-4ac1-A5FD-54E130D2E71F " [01:4324]....\smpd_free_command [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_free_command [01:4324].../smpd_state_writing_cmd [01:4324]../smpd_handle_op_write [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd_header [01:4324]....read command header [01:4324]....command header read, posting read for data: 105 bytes [01:4324].../smpd_state_reading_cmd_header [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd [01:4324]....read command [01:4324]....\smpd_parse_command [01:4324]..../smpd_parse_command [01:4324]....read command: "cmd=suspend src=0 dest=1 tag=6 name=8623E2F2-530C-4ac1-A5FD-54E130D2E71F r ank=0 exit_code=123 ctx_key=0 " [01:4324]....\smpd_handle_command [01:4324].....handling command: [01:4324]..... src = 0 [01:4324]..... dest = 1 [01:4324]..... cmd = suspend [01:4324]..... tag = 6 [01:4324]..... ctx = parent [01:4324]..... len = 105 [01:4324]..... str = cmd=suspend src=0 dest=1 tag=6 name=8623E2F2-530C-4ac1-A5FD-54E130D2E71F rank=0 exit_code=123 ctx_key=0 [01:4324].....\smpd_command_destination [01:4324]......1 -> 1 : returning NULL context [01:4324]...../smpd_command_destination [01:4324].....\smpd_handle_suspend_command [01:4324]......\smpd_create_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_create_command [01:4324]......\smpd_add_command_int_arg [01:4324]....../smpd_add_command_int_arg [01:4324]......\smpd_suspend_process [01:4324]....../smpd_suspend_process [01:4324]......\smpd_add_command_arg [01:4324]....../smpd_add_command_arg [01:4324]......sending result command to parent context: "cmd=result src=1 dest=0 tag=9 cmd_tag=6 resu lt=SUCCESS " [01:4324]......\smpd_post_write_command [01:4324].......\smpd_package_command [01:4324]......./smpd_package_command [01:4324].......smpd_post_write_command on the parent context sock 1340: 69 bytes for command: "cmd=re sult src=1 dest=0 tag=9 cmd_tag=6 result=SUCCESS " [01:4324]....../smpd_post_write_command [01:4324]...../smpd_handle_suspend_command [01:4324]..../smpd_handle_command [01:4324]....\smpd_post_read_command [01:4324].....posting a read for a command header on the parent context, sock 1340 [01:4324]..../smpd_post_read_command [01:4324].../smpd_state_reading_cmd [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_WRITE [01:4324]..\smpd_handle_op_write [01:4324]...\smpd_state_writing_cmd [01:4324]....wrote command [01:4324]....command written to parent: "cmd=result src=1 dest=0 tag=9 cmd_tag=6 result=SUCCESS " [01:4324]....\smpd_free_command [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_free_command [01:4324].../smpd_state_writing_cmd [01:4324]../smpd_handle_op_write [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd_header [01:4324]....read command header [01:4324]....command header read, posting read for data: 53 bytes [01:4324].../smpd_state_reading_cmd_header [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd [01:4324]....read command [01:4324]....\smpd_parse_command [01:4324]..../smpd_parse_command [01:4324]....read command: "cmd=kill src=0 dest=1 tag=7 exit_code=123 ctx_key=0 " [01:4324]....\smpd_handle_command [01:4324].....handling command: [01:4324]..... src = 0 [01:4324]..... dest = 1 [01:4324]..... cmd = kill [01:4324]..... tag = 7 [01:4324]..... ctx = parent [01:4324]..... len = 53 [01:4324]..... str = cmd=kill src=0 dest=1 tag=7 exit_code=123 ctx_key=0 [01:4324].....\smpd_command_destination [01:4324]......1 -> 1 : returning NULL context [01:4324]...../smpd_command_destination [01:4324].....\smpd_handle_kill_command [01:4324]......\smpd_kill_process [01:4324].......\smpd_process_from_registry [01:4324]......./smpd_process_from_registry [01:4324].......ReadFile failed, error 109 [01:4324].......*** smpd_piothread finishing pid:2028 *** [01:4324].......ReadFile failed, error 109 [01:4324].......*** smpd_piothread finishing pid:2028 *** [01:4324]....../smpd_kill_process [01:4324]...../smpd_handle_kill_command [01:4324]..../smpd_handle_command [01:4324]....\smpd_post_read_command [01:4324].....posting a read for a command header on the parent context, sock 1340 [01:4324]..../smpd_post_read_command [01:4324].../smpd_state_reading_cmd [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_stdouterr [01:4324].../smpd_state_reading_stdouterr [01:4324]../smpd_handle_op_read [01:4324]..SOCK_OP_READ failed - result = -1, closing stderr context. [01:4324]..sock_waiting for the next event. [01:4324]..closing output socket took 0.013 seconds [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd_header [01:4324]....ERROR:unable to read the cmd header on the pmi context, generic socket failure, error sta ck: MPIDU_Sock_wait(2589): The specified network name is no longer available. (errno 64). [01:4324].../smpd_state_reading_cmd_header [01:4324]../smpd_handle_op_read [01:4324]..SOCK_OP_READ failed - result = -1, closing pmi context. [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_stdouterr [01:4324].../smpd_state_reading_stdouterr [01:4324]../smpd_handle_op_read [01:4324]..SOCK_OP_READ failed - result = -1, closing stdout context. [01:4324]..sock_waiting for the next event. [01:4324]..closing output socket took 0.024 seconds [01:4324]..SOCK_OP_CLOSE [01:4324]..\smpd_handle_op_close [01:4324]...\smpd_get_state_string [01:4324].../smpd_get_state_string [01:4324]...op_close received - SMPD_CLOSING state. [01:4324]...process refcount == 2, stderr closed. [01:4324]...\smpd_free_context [01:4324]....freeing stderr context. [01:4324]....\smpd_init_context [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_init_context [01:4324].../smpd_free_context [01:4324]../smpd_handle_op_close [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_CLOSE [01:4324]..\smpd_handle_op_close [01:4324]...\smpd_get_state_string [01:4324].../smpd_get_state_string [01:4324]...op_close received - SMPD_CLOSING state. [01:4324]...process refcount == 1, pmi closed. [01:4324]...\smpd_free_context [01:4324]....freeing pmi context. [01:4324]....\smpd_init_context [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_init_context [01:4324].../smpd_free_context [01:4324]../smpd_handle_op_close [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_CLOSE [01:4324]..\smpd_handle_op_close [01:4324]...\smpd_get_state_string [01:4324].../smpd_get_state_string [01:4324]...op_close received - SMPD_CLOSING state. [01:4324]...process refcount == 0, waiting for the process to finish exiting. [01:4324]...\smpd_process_from_registry [01:4324].../smpd_process_from_registry [01:4324]...\smpd_wait_process [01:4324].../smpd_wait_process [01:4324]...\smpd_create_command [01:4324]...*** smpd_pinthread finishing pid:2028 *** [01:4324]....\smpd_init_command [01:4324]..../smpd_init_command [01:4324].../smpd_create_command [01:4324]...\smpd_add_command_int_arg [01:4324].../smpd_add_command_int_arg [01:4324]...\smpd_add_command_int_arg [01:4324].../smpd_add_command_int_arg [01:4324]...\smpd_add_command_arg [01:4324].../smpd_add_command_arg [01:4324]...creating an exit command for rank 0, pid 2028, exit code 123. [01:4324]...\smpd_post_write_command [01:4324]....\smpd_package_command [01:4324]..../smpd_package_command [01:4324]....smpd_post_write_command on the parent context sock 1340: 100 bytes for command: "cmd=exit src=1 dest=0 tag=10 rank=0 code=123 kvs=8623E2F2-530C-4ac1-A5FD-54E130D2E71F " [01:4324].../smpd_post_write_command [01:4324]...\smpd_free_process_struct [01:4324].../smpd_free_process_struct [01:4324]...\smpd_free_context [01:4324]....freeing stdout context. [01:4324]....\smpd_init_context [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_init_context [01:4324].../smpd_free_context [01:4324]../smpd_handle_op_close [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_CLOSE [01:4324]..\smpd_handle_op_close [01:4324]...\smpd_get_state_string [01:4324].../smpd_get_state_string [01:4324]...op_close received - SMPD_CLOSING state. [01:4324]...Unaffiliated stdin context closing. [01:4324]...\smpd_free_context [01:4324]....freeing stdin context. [01:4324]....\smpd_init_context [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_init_context [01:4324].../smpd_free_context [01:4324]../smpd_handle_op_close [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_WRITE [01:4324]..\smpd_handle_op_write [01:4324]...\smpd_state_writing_cmd [01:4324]....wrote command [01:4324]....command written to parent: "cmd=exit src=1 dest=0 tag=10 rank=0 code=123 kvs=8623E2F2-530 C-4ac1-A5FD-54E130D2E71F " [01:4324]....\smpd_free_command [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_free_command [01:4324].../smpd_state_writing_cmd [01:4324]../smpd_handle_op_write [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd_header [01:4324]....read command header [01:4324]....command header read, posting read for data: 30 bytes [01:4324].../smpd_state_reading_cmd_header [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd [01:4324]....read command [01:4324]....\smpd_parse_command [01:4324]..../smpd_parse_command [01:4324]....read command: "cmd=close src=0 dest=1 tag=8 " [01:4324]....\smpd_handle_command [01:4324].....handling command: [01:4324]..... src = 0 [01:4324]..... dest = 1 [01:4324]..... cmd = close [01:4324]..... tag = 8 [01:4324]..... ctx = parent [01:4324]..... len = 30 [01:4324]..... str = cmd=close src=0 dest=1 tag=8 [01:4324].....\smpd_command_destination [01:4324]......1 -> 1 : returning NULL context [01:4324]...../smpd_command_destination [01:4324].....\smpd_handle_close_command [01:4324]......\smpd_create_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_create_command [01:4324]......sending close command to left child: "cmd=close src=1 dest=2 tag=11 " [01:4324]......\smpd_post_write_command [01:4324].......\smpd_package_command [01:4324]......./smpd_package_command [01:4324].......smpd_post_write_command on the left context sock 1296: 44 bytes for command: "cmd=clos e src=1 dest=2 tag=11 " [01:4324]....../smpd_post_write_command [01:4324]...../smpd_handle_close_command [01:4324]..../smpd_handle_command [01:4324]....\smpd_post_read_command [01:4324].....posting a read for a command header on the parent context, sock 1340 [01:4324]..../smpd_post_read_command [01:4324].../smpd_state_reading_cmd [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_WRITE [01:4324]..\smpd_handle_op_write [01:4324]...\smpd_state_writing_cmd [01:4324]....wrote command [01:4324]....command written to left: "cmd=close src=1 dest=2 tag=11 " [01:4324]....\smpd_free_command [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_free_command [01:4324].../smpd_state_writing_cmd [01:4324]../smpd_handle_op_write [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd_header [01:4324]....read command header [01:4324]....command header read, posting read for data: 31 bytes [01:4324].../smpd_state_reading_cmd_header [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd [01:4324]....read command [01:4324]....\smpd_parse_command [01:4324]..../smpd_parse_command [01:4324]....read command: "cmd=closed src=2 dest=1 tag=3 " [01:4324]....\smpd_handle_command [01:4324].....handling command: [01:4324]..... src = 2 [01:4324]..... dest = 1 [01:4324]..... cmd = closed [01:4324]..... tag = 3 [01:4324]..... ctx = left [01:4324]..... len = 31 [01:4324]..... str = cmd=closed src=2 dest=1 tag=3 [01:4324].....\smpd_command_destination [01:4324]......1 -> 1 : returning NULL context [01:4324]...../smpd_command_destination [01:4324].....\smpd_handle_closed_command [01:4324]......closed command received from left child, closing sock. [01:4324]......MPIDU_Sock_post_close(1296) [01:4324]......\smpd_create_command [01:4324].......\smpd_init_command [01:4324]......./smpd_init_command [01:4324]....../smpd_create_command [01:4324]......\smpd_post_write_command [01:4324].......\smpd_package_command [01:4324]......./smpd_package_command [01:4324].......smpd_post_write_command on the parent context sock 1340: 53 bytes for command: "cmd=cl osed_request src=1 dest=0 tag=12 " [01:4324]....../smpd_post_write_command [01:4324]...../smpd_handle_closed_command [01:4324]..../smpd_handle_command [01:4324]....not posting read for another command because SMPD_CLOSE returned [01:4324].../smpd_state_reading_cmd [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_CLOSE [01:4324]..\smpd_handle_op_close [01:4324]...\smpd_get_state_string [01:4324].../smpd_get_state_string [01:4324]...op_close received - SMPD_CLOSING state. [01:4324]...Unaffiliated left context closing. [01:4324]...\smpd_create_command [01:4324]....\smpd_init_command [01:4324]..../smpd_init_command [01:4324].../smpd_create_command [01:4324]...\smpd_post_write_command [01:4324]....\smpd_package_command [01:4324]..../smpd_package_command [01:4324]....enqueueing write at the end of the list. [01:4324].../smpd_post_write_command [01:4324]...\smpd_free_context [01:4324]....freeing left context. [01:4324]....\smpd_init_context [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_init_context [01:4324].../smpd_free_context [01:4324]../smpd_handle_op_close [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_WRITE [01:4324]..\smpd_handle_op_write [01:4324]...\smpd_state_writing_cmd [01:4324]....wrote command [01:4324]....command written to parent: "cmd=closed_request src=1 dest=0 tag=12 " [01:4324]....\smpd_free_command [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_free_command [01:4324]....smpd_handle_written: posting write(53 bytes) for command: "cmd=closed_request src=1 dest= 0 tag=13 " [01:4324].../smpd_state_writing_cmd [01:4324]../smpd_handle_op_write [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd_header [01:4324]....read command header [01:4324]....command header read, posting read for data: 31 bytes [01:4324].../smpd_state_reading_cmd_header [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_WRITE [01:4324]..\smpd_handle_op_write [01:4324]...\smpd_state_writing_cmd [01:4324]....wrote command [01:4324]....command written to parent: "cmd=closed_request src=1 dest=0 tag=13 " [01:4324]....\smpd_free_command [01:4324].....\smpd_init_command [01:4324]...../smpd_init_command [01:4324]..../smpd_free_command [01:4324].../smpd_state_writing_cmd [01:4324]../smpd_handle_op_write [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_READ [01:4324]..\smpd_handle_op_read [01:4324]...\smpd_state_reading_cmd [01:4324]....read command [01:4324]....\smpd_parse_command [01:4324]..../smpd_parse_command [01:4324]....read command: "cmd=closed src=0 dest=1 tag=9 " [01:4324]....\smpd_handle_command [01:4324].....handling command: [01:4324]..... src = 0 [01:4324]..... dest = 1 [01:4324]..... cmd = closed [01:4324]..... tag = 9 [01:4324]..... ctx = parent [01:4324]..... len = 31 [01:4324]..... str = cmd=closed src=0 dest=1 tag=9 [01:4324].....\smpd_command_destination [01:4324]......1 -> 1 : returning NULL context [01:4324]...../smpd_command_destination [01:4324].....\smpd_handle_closed_command [01:4324]......closed command received from parent, closing sock. [01:4324]......MPIDU_Sock_post_close(1340) [01:4324]...../smpd_handle_closed_command [01:4324]..../smpd_handle_command [01:4324]....not posting read for another command because SMPD_EXITING returned [01:4324].../smpd_state_reading_cmd [01:4324]../smpd_handle_op_read [01:4324]..sock_waiting for the next event. [01:4324]..SOCK_OP_CLOSE [01:4324]..\smpd_handle_op_close [01:4324]...\smpd_get_state_string [01:4324].../smpd_get_state_string [01:4324]...op_close received - SMPD_CLOSING state. [01:4324]...Unaffiliated parent context closing. [01:4324]...\smpd_free_context [01:4324]....freeing a context not in the global list - this should be impossible. [01:4324].../smpd_free_context [01:4324]...all contexts closed, exiting state machine. [01:4324]../smpd_handle_op_close [01:4324]./smpd_enter_at_state [01:4324].\smpd_exit [01:4324]..\smpd_kill_all_processes [01:4324]../smpd_kill_all_processes [01:4324]..\smpd_finalize_drive_maps [01:4324]../smpd_finalize_drive_maps [01:4324]..\smpd_dbs_finalize [01:4324]../smpd_dbs_finalize