[-1:2012]...\smpd_get_opt_int [-1:2012].../smpd_get_opt_int [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt_int [-1:2012].../smpd_get_opt_int [-1:2012]...\smpd_get_opt_int [-1:2012].../smpd_get_opt_int [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt_string [-1:2012].../smpd_get_opt_string [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt_string [-1:2012].../smpd_get_opt_string [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt_string [-1:2012].../smpd_get_opt_string [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt_string [-1:2012].../smpd_get_opt_string [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt_string [-1:2012].../smpd_get_opt_string [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt_string [-1:2012].../smpd_get_opt_string [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]...\smpd_get_opt_string [-1:2012].../smpd_get_opt_string [-1:2012]...\smpd_get_opt_string [-1:2012].../smpd_get_opt_string [-1:2012]...\smpd_get_opt [-1:2012].../smpd_get_opt [-1:2012]../smpd_parse_command_args [-1:2012]..\smpd_get_smpd_data [-1:2012]...\smpd_get_smpd_data_from_environment [-1:2012].../smpd_get_smpd_data_from_environment [-1:2012]...\smpd_get_smpd_data_default [-1:2012].../smpd_get_smpd_data_default [-1:2012]../smpd_get_smpd_data [-1:2012]..\smpd_entry_point [00:2012]...\smpd_set_smpd_data [00:2012].../smpd_set_smpd_data [00:2012]...\SMPDU_Sock_create_set [00:2012]....\SMPDU_Sock_get_sock_set_id [00:2012]..../SMPDU_Sock_get_sock_set_id [00:2012]....created a set for the listener: 504 [00:2012]....\SMPDU_Sock_listen [00:2012]..../SMPDU_Sock_listen [00:2012]....smpd listening on port 8676 [00:2012]....\smpd_create_context [00:2012].....\smpd_init_context [00:2012]......\smpd_init_command [00:2012]....../smpd_init_command [00:2012]......\SMPDU_Sock_set_user_ptr [00:2012]....../SMPDU_Sock_set_user_ptr [00:2012]...../smpd_init_context [00:2012]..../smpd_create_context [00:2012]....\SMPDU_Sock_set_user_ptr [00:2012]..../SMPDU_Sock_set_user_ptr [00:2012]....\smpd_option_on [00:2012].....\smpd_get_smpd_data [00:2012]......\smpd_get_smpd_data_from_environment [00:2012]....../smpd_get_smpd_data_from_environment [00:2012]......\smpd_get_smpd_data_default [00:2012]....../smpd_get_smpd_data_default [00:2012]......Unable to get the data for the key 'no_dynamic_hosts' [00:2012]...../smpd_get_smpd_data [00:2012]..../smpd_option_on [00:2012]....\smpd_insert_into_dynamic_hosts [00:2012]..../smpd_insert_into_dynamic_hosts [00:2012]....\smpd_enter_at_state [00:2012].....sock_waiting for the next event. [00:2012].....\SMPDU_Sock_wait [00:2012]...../SMPDU_Sock_wait [00:2012].....SOCK_OP_ACCEPT event.error = 0, result = 0, context=listener [00:2012].....\smpd_handle_op_accept [00:2012]......\smpd_state_smpd_listening [00:2012].......\SMPDU_Sock_accept [00:2012]......./SMPDU_Sock_accept [00:2012].......authenticating new connection [00:2012].......\smpd_create_context [00:2012]........\smpd_init_context [00:2012].........\smpd_init_command [00:2012]........./smpd_init_command [00:2012].........\SMPDU_Sock_set_user_ptr [00:2012]........./SMPDU_Sock_set_user_ptr [00:2012]......../smpd_init_context [00:2012]......./smpd_create_context [00:2012].......\SMPDU_Sock_set_user_ptr [00:2012]......./SMPDU_Sock_set_user_ptr [00:2012].......\smpd_gen_authentication_strings [00:2012]........\smpd_hash [00:2012]......../smpd_hash [00:2012]......./smpd_gen_authentication_strings [00:2012].......posting a write of the challenge string: 1.4.1p1 6608 [00:2012].......\SMPDU_Sock_post_write [00:2012]........\SMPDU_Sock_post_writev [00:2012]......../SMPDU_Sock_post_writev [00:2012]......./SMPDU_Sock_post_write [00:2012]....../smpd_state_smpd_listening [00:2012]...../smpd_handle_op_accept [00:2012].....sock_waiting for the next event. [00:2012].....\SMPDU_Sock_wait [00:2012]...../SMPDU_Sock_wait [00:2012].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined [00:2012].....\smpd_handle_op_write [00:2012]......\smpd_state_writing_challenge_string [00:2012].......wrote challenge string: '1.4.1p1 6608' [00:2012].......\SMPDU_Sock_post_read [00:2012]........\SMPDU_Sock_post_readv [00:2012]......../SMPDU_Sock_post_readv [00:2012]......./SMPDU_Sock_post_read [00:2012]....../smpd_state_writing_challenge_string [00:2012]...../smpd_handle_op_write [00:2012].....sock_waiting for the next event. [00:2012].....\SMPDU_Sock_wait [00:2012]...../SMPDU_Sock_wait [00:2012].....SOCK_OP_READ event.error = 0, result = 0, context=undetermined [00:2012].....\smpd_handle_op_read [00:2012]......\smpd_state_reading_challenge_response [00:2012].......read challenge response: '14b670c72cf3820a74ef87900fc1be86' [00:2012].......\SMPDU_Sock_post_write [00:2012]........\SMPDU_Sock_post_writev [00:2012]......../SMPDU_Sock_post_writev [00:2012]......./SMPDU_Sock_post_write [00:2012]....../smpd_state_reading_challenge_response [00:2012]...../smpd_handle_op_read [00:2012].....sock_waiting for the next event. [00:2012].....\SMPDU_Sock_wait [00:2012]...../SMPDU_Sock_wait [00:2012].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined [00:2012].....\smpd_handle_op_write [00:2012]......\smpd_state_writing_connect_result [00:2012].......wrote connect result: 'SUCCESS' [00:2012].......\SMPDU_Sock_post_read [00:2012]........\SMPDU_Sock_post_readv [00:2012]......../SMPDU_Sock_post_readv [00:2012]......./SMPDU_Sock_post_read [00:2012]....../smpd_state_writing_connect_result [00:2012]...../smpd_handle_op_write [00:2012].....sock_waiting for the next event. [00:2012].....\SMPDU_Sock_wait [00:2012]...../SMPDU_Sock_wait [00:2012].....SOCK_OP_READ event.error = 0, result = 0, context=undetermined [00:2012].....\smpd_handle_op_read [00:2012]......\smpd_state_reading_session_request [00:2012].......read session request: 'process' [00:2012].......\SMPDU_Sock_post_write [00:2012]........\SMPDU_Sock_post_writev [00:2012]......../SMPDU_Sock_post_writev [00:2012]......./SMPDU_Sock_post_write [00:2012]....../smpd_state_reading_session_request [00:2012]...../smpd_handle_op_read [00:2012].....sock_waiting for the next event. [00:2012].....\SMPDU_Sock_wait [00:2012]...../SMPDU_Sock_wait [00:2012].....SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined [00:2012].....\smpd_handle_op_write [00:2012]......\smpd_state_writing_no_cred_request [00:2012].......wrote no cred request: 'nocredentials' [00:2012].......\smpd_start_win_mgr [00:2012]........starting command: "C:\Program Files\MPICH2\bin\smpd.exe" -d 27 -mgr -read 00000000000002C0 -write 00000000000002BC [00:2012]........CreateProcess [00:2012]........smpd reading the port string from the manager [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt_int [-1:2628].../smpd_get_opt_int [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt_int [-1:2628].../smpd_get_opt_int [-1:2628]...\smpd_get_opt_int [-1:2628].../smpd_get_opt_int [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt [-1:2628].../smpd_get_opt [-1:2628]...\smpd_get_opt_string [-1:2628].../smpd_get_opt_string [-1:2628]...\smpd_get_opt_string [-1:2628].../smpd_get_opt_string [-1:2628]...manager creating listener and session sets. [-1:2628]...\SMPDU_Sock_create_set [-1:2628]....\SMPDU_Sock_get_sock_set_id [-1:2628]..../SMPDU_Sock_get_sock_set_id [-1:2628]....created set for manager listener, 768 [-1:2628]....\SMPDU_Sock_listen [-1:2628]..../SMPDU_Sock_listen [-1:2628]....smpd manager listening on port 63430 [-1:2628]....\smpd_create_context [-1:2628].....\smpd_init_context [-1:2628]......\smpd_init_command [-1:2628]....../smpd_init_command [-1:2628]......\SMPDU_Sock_set_user_ptr [-1:2628]....../SMPDU_Sock_set_user_ptr [-1:2628]...../smpd_init_context [-1:2628]..../smpd_create_context [-1:2628]....\SMPDU_Sock_set_user_ptr [-1:2628]..../SMPDU_Sock_set_user_ptr [-1:2628]....manager writing port back to smpd. [00:2012]........smpd sending the account to the manager [00:2012]........\smpd_hide_string_arg [00:2012].........\first_token [00:2012]........./first_token [00:2012].........\compare_token [00:2012]........./compare_token [00:2012].........\next_token [00:2012]..........\first_token [00:2012]........../first_token [00:2012]..........\first_token [00:2012]........../first_token [00:2012]........./next_token [00:2012]......../smpd_hide_string_arg [00:2012]......../smpd_hide_string_arg [00:2012]........\smpd_hide_string_arg [00:2012].........\first_token [00:2012]........./first_token [00:2012].........\compare_token [00:2012]........./compare_token [00:2012].........\next_token [00:2012]..........\first_token [00:2012]........../first_token [00:2012]..........\first_token [00:2012]........../first_token [00:2012]........./next_token [00:2012].........\compare_token [00:2012]........./compare_token [00:2012].........\compare_token [00:2012]........./compare_token [00:2012].........\next_token [00:2012]..........\first_token [00:2012]........../first_token [00:2012]..........\first_token [00:2012]........../first_token [00:2012]........./next_token [00:2012].........\compare_token [00:2012]........./compare_token [00:2012].........\next_token [00:2012]..........\first_token [00:2012]........../first_token [00:2012]..........\first_token [00:2012]........../first_token [00:2012]........./next_token [00:2012].........\compare_token [00:2012]........./compare_token [00:2012].........\next_token [00:2012]..........\first_token [00:2012]........../first_token [00:2012]..........\first_token [00:2012]........../first_token [00:2012]........./next_token [00:2012]......../smpd_hide_string_arg [00:2012]......../smpd_hide_string_arg [00:2012]........closing the pipe to the manager [00:2012]........smpd writing reconnect request: port 63430 [00:2012]........\SMPDU_Sock_post_write [00:2012].........\SMPDU_Sock_post_writev [00:2012]........./SMPDU_Sock_post_writev [00:2012]......../SMPDU_Sock_post_write [00:2012]......./smpd_state_writing_no_cred_request [00:2012]....../smpd_handle_op_write [00:2012]......sock_waiting for the next event. [00:2012]......\SMPDU_Sock_wait [00:2012]....../SMPDU_Sock_wait [00:2012]......SOCK_OP_WRITE event.error = 0, result = 0, context=undetermined [00:2012]......\smpd_handle_op_write [00:2012].......\smpd_state_writing_reconnect_request [00:2012]........wrote reconnect request: '63430' [00:2012]........\SMPDU_Sock_post_close [00:2012].........\SMPDU_Sock_post_read [00:2012]..........\SMPDU_Sock_post_readv [00:2012]........../SMPDU_Sock_post_readv [00:2012]........./SMPDU_Sock_post_read [00:2012]......../SMPDU_Sock_post_close [00:2012]......./smpd_state_writing_reconnect_request [00:2012]....../smpd_handle_op_write [00:2012]......sock_waiting for the next event. [00:2012]......\SMPDU_Sock_wait [00:2012]....../SMPDU_Sock_wait [00:2012]......SOCK_OP_CLOSE event.error = 0, result = 0, context=undetermined [00:2012]......\smpd_handle_op_close [00:2012].......\smpd_get_state_string [00:2012]......./smpd_get_state_string [00:2012].......op_close received - SMPD_CLOSING state. [00:2012].......Unaffiliated undetermined context closing. [00:2012].......\smpd_free_context [00:2012]........freeing undetermined context. [00:2012]........\smpd_init_context [00:2012].........\smpd_init_command [00:2012]........./smpd_init_command [00:2012]......../smpd_init_context [00:2012]......./smpd_free_context [00:2012]....../smpd_handle_op_close [00:2012]......sock_waiting for the next event. [00:2012]......\SMPDU_Sock_wait [-1:2628]....\smpd_hide_string_arg [-1:2628].....\first_token [-1:2628]...../first_token [-1:2628].....\compare_token [-1:2628]...../compare_token [-1:2628].....\next_token [-1:2628]......\first_token [-1:2628]....../first_token [-1:2628]......\first_token [-1:2628]....../first_token [-1:2628]...../next_token [-1:2628].....\compare_token [-1:2628]...../compare_token [-1:2628].....\compare_token [-1:2628]...../compare_token [-1:2628].....\next_token [-1:2628]......\first_token [-1:2628]....../first_token [-1:2628]......\first_token [-1:2628]....../first_token [-1:2628]...../next_token [-1:2628].....\compare_token [-1:2628]...../compare_token [-1:2628].....\next_token [-1:2628]......\first_token [-1:2628]....../first_token [-1:2628]......\first_token [-1:2628]....../first_token [-1:2628]...../next_token [-1:2628]..../smpd_hide_string_arg [-1:2628]..../smpd_hide_string_arg [-1:2628]....\smpd_enter_at_state [-1:2628].....sock_waiting for the next event. [-1:2628].....\SMPDU_Sock_wait [-1:2628]...../SMPDU_Sock_wait [-1:2628].....SOCK_OP_ACCEPT event.error = 0, result = 0, context=listener [-1:2628].....\smpd_handle_op_accept [-1:2628]......\smpd_state_mgr_listening [-1:2628].......\SMPDU_Sock_accept [-1:2628]......./SMPDU_Sock_accept [-1:2628].......accepted re-connection [-1:2628].......\smpd_create_context [-1:2628]........\smpd_init_context [-1:2628].........\smpd_init_command [-1:2628]........./smpd_init_command [-1:2628].........\SMPDU_Sock_set_user_ptr [-1:2628]........./SMPDU_Sock_set_user_ptr [-1:2628]......../smpd_init_context [-1:2628]......./smpd_create_context [-1:2628].......\SMPDU_Sock_set_user_ptr [-1:2628]......./SMPDU_Sock_set_user_ptr [-1:2628].......\SMPDU_Sock_post_read [-1:2628]........\SMPDU_Sock_post_readv [-1:2628]......../SMPDU_Sock_post_readv [-1:2628]......./SMPDU_Sock_post_read [-1:2628].......closing the mgr listener. [-1:2628].......\SMPDU_Sock_post_close [-1:2628]......./SMPDU_Sock_post_close [-1:2628]....../smpd_state_mgr_listening [-1:2628]...../smpd_handle_op_accept [-1:2628].....sock_waiting for the next event. [-1:2628].....\SMPDU_Sock_wait [-1:2628]...../SMPDU_Sock_wait [-1:2628].....SOCK_OP_READ event.error = 0, result = 0, context=undetermined [-1:2628].....\smpd_handle_op_read [-1:2628]......\smpd_state_reading_session_header [-1:2628].......read session header: 'id=1 parent=0 level=0' [-1:2628].......\smpd_interpret_session_header [-1:2628]........interpreting session header: "id=1 parent=0 level=0" [-1:2628]........ id = 1 [01:2628]........ parent = 0 [01:2628]........ level = 0 [01:2628]......./smpd_interpret_session_header [01:2628].......\smpd_post_read_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........posting a read for a command header on the parent context, sock 908 [01:2628]........\SMPDU_Sock_post_read [01:2628].........\SMPDU_Sock_post_readv [01:2628]........./SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_read [01:2628]......./smpd_post_read_command [01:2628]....../smpd_state_reading_session_header [01:2628]...../smpd_handle_op_read [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_CLOSE event.error = 0, result = 0, context=listener [01:2628].....\smpd_handle_op_close [01:2628]......\smpd_get_state_string [01:2628]....../smpd_get_state_string [01:2628]......op_close received - SMPD_MGR_LISTENING state. [01:2628]......\smpd_free_context [01:2628].......freeing listener context. [01:2628].......\smpd_init_context [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_init_context [01:2628]....../smpd_free_context [01:2628]...../smpd_handle_op_close [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]......GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628].....\smpd_handle_op_read [01:2628]......\smpd_state_reading_cmd_header [01:2628].......read command header [01:2628].......command header read, posting read for data: 55 bytes [01:2628].......\SMPDU_Sock_post_read [01:2628]........\SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_read [01:2628]....../smpd_state_reading_cmd_header [01:2628]...../smpd_handle_op_read [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628].....\smpd_handle_op_read [01:2628]......\smpd_state_reading_cmd [01:2628].......read command [01:2628].......\smpd_parse_command [01:2628]......./smpd_parse_command [01:2628].......read command: "cmd=connect src=0 dest=1 tag=0 host=10.97.51.118 id=2 " [01:2628].......\smpd_handle_command [01:2628]........handling command: [01:2628]........ src = 0 [01:2628]........ dest = 1 [01:2628]........ cmd = connect [01:2628]........ tag = 0 [01:2628]........ ctx = parent [01:2628]........ len = 55 [01:2628]........ str = cmd=connect src=0 dest=1 tag=0 host=10.97.51.118 id=2 [01:2628]........\smpd_command_destination [01:2628].........1 -> 1 : returning NULL context [01:2628]......../smpd_command_destination [01:2628]........\smpd_handle_connect_command [01:2628].........now connecting to 10.97.51.118 [01:2628].........\smpd_create_context [01:2628]..........\smpd_init_context [01:2628]...........\smpd_init_command [01:2628].........../smpd_init_command [01:2628]........../smpd_init_context [01:2628]........./smpd_create_context [01:2628].........\SMPDU_Sock_post_connect [01:2628]........./SMPDU_Sock_post_connect [01:2628].........adding new left child context [01:2628].........\smpd_init_context [01:2628]..........\smpd_init_command [01:2628]........../smpd_init_command [01:2628]..........\SMPDU_Sock_set_user_ptr [01:2628]........../SMPDU_Sock_set_user_ptr [01:2628]........./smpd_init_context [01:2628].........\SMPDU_Sock_set_user_ptr [01:2628]........./SMPDU_Sock_set_user_ptr [01:2628]......../smpd_handle_connect_command [01:2628]......./smpd_handle_command [01:2628].......\smpd_post_read_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........posting a read for a command header on the parent context, sock 908 [01:2628]........\SMPDU_Sock_post_read [01:2628].........\SMPDU_Sock_post_readv [01:2628]........./SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_read [01:2628]......./smpd_post_read_command [01:2628]....../smpd_state_reading_cmd [01:2628]...../smpd_handle_op_read [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_CONNECT event.error = 0, result = 0, context=left [01:2628].....\smpd_handle_op_connect [01:2628]......connect succeeded, posting read of the challenge string [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_handle_op_connect [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628].....\smpd_handle_op_read [01:2628]......\smpd_state_reading_challenge_string [01:2628].......read challenge string: '1.4.1p1 4479' [01:2628].......\smpd_verify_version [01:2628]......./smpd_verify_version [01:2628].......Verification of smpd version succeeded [01:2628].......\smpd_hash [01:2628]......./smpd_hash [01:2628].......\SMPDU_Sock_post_write [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./SMPDU_Sock_post_write [01:2628]....../smpd_state_reading_challenge_string [01:2628]...../smpd_handle_op_read [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_WRITE event.error = 0, result = 0, context=left [01:2628].....\smpd_handle_op_write [01:2628]......\smpd_state_writing_challenge_response [01:2628].......wrote challenge response: '38f1359a77fb2685480916aadb3ad993' [01:2628].......\SMPDU_Sock_post_read [01:2628]........\SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_read [01:2628]....../smpd_state_writing_challenge_response [01:2628]...../smpd_handle_op_write [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628].....\smpd_handle_op_read [01:2628]......\smpd_state_reading_connect_result [01:2628].......read connect result: 'SUCCESS' [01:2628].......\SMPDU_Sock_post_write [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./SMPDU_Sock_post_write [01:2628]....../smpd_state_reading_connect_result [01:2628]...../smpd_handle_op_read [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_WRITE event.error = 0, result = 0, context=left [01:2628].....\smpd_handle_op_write [01:2628]......\smpd_state_writing_process_session_request [01:2628].......wrote process session request: 'process' [01:2628].......\SMPDU_Sock_post_read [01:2628]........\SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_read [01:2628]....../smpd_state_writing_process_session_request [01:2628]...../smpd_handle_op_write [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628].....\smpd_handle_op_read [01:2628]......\smpd_state_reading_cred_request [01:2628].......read cred request: 'nocredentials' [01:2628].......\SMPDU_Sock_post_read [01:2628]........\SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_read [01:2628]....../smpd_state_reading_cred_request [01:2628]...../smpd_handle_op_read [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628].....\smpd_handle_op_read [01:2628]......\smpd_state_reading_reconnect_request [01:2628].......read re-connect request: '54295' [01:2628].......closing the old socket in the left context. [01:2628].......\SMPDU_Sock_get_sock_id [01:2628]......./SMPDU_Sock_get_sock_id [01:2628].......SMPDU_Sock_post_close(924) [01:2628].......\SMPDU_Sock_post_close [01:2628]........\SMPDU_Sock_post_read [01:2628].........\SMPDU_Sock_post_readv [01:2628]........./SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_read [01:2628]......./SMPDU_Sock_post_close [01:2628].......connecting a new socket. [01:2628].......\smpd_create_context [01:2628]........\smpd_init_context [01:2628].........\smpd_init_command [01:2628]........./smpd_init_command [01:2628]......../smpd_init_context [01:2628]......./smpd_create_context [01:2628].......posting a re-connect to 10.97.51.118:54295 in left context. [01:2628].......\SMPDU_Sock_post_connect [01:2628]......./SMPDU_Sock_post_connect [01:2628]....../smpd_state_reading_reconnect_request [01:2628]...../smpd_handle_op_read [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_CLOSE event.error = 0, result = 0, context=left [01:2628].....\smpd_handle_op_close [01:2628]......\smpd_get_state_string [01:2628]....../smpd_get_state_string [01:2628]......op_close received - SMPD_CLOSING state. [01:2628]......Unaffiliated left context closing. [01:2628]......\smpd_free_context [01:2628].......freeing left context. [01:2628].......\smpd_init_context [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_init_context [01:2628]....../smpd_free_context [01:2628]...../smpd_handle_op_close [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_CONNECT event.error = 0, result = 0, context=left [01:2628].....\smpd_handle_op_connect [01:2628]......\smpd_generate_session_header [01:2628].......session header: (id=2 parent=1 level=1) [01:2628]....../smpd_generate_session_header [01:2628]......\SMPDU_Sock_post_write [01:2628].......\SMPDU_Sock_post_writev [01:2628]......./SMPDU_Sock_post_writev [01:2628]....../SMPDU_Sock_post_write [01:2628]...../smpd_handle_op_connect [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_WRITE event.error = 0, result = 0, context=left [01:2628].....\smpd_handle_op_write [01:2628]......\smpd_state_writing_session_header [01:2628].......wrote session header: 'id=2 parent=1 level=1' [01:2628].......\smpd_post_read_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........posting a read for a command header on the left context, sock 968 [01:2628]........\SMPDU_Sock_post_read [01:2628].........\SMPDU_Sock_post_readv [01:2628]........./SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_read [01:2628]......./smpd_post_read_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_command_destination [01:2628]........1 -> 0 : returning parent_context: 0 < 1 [01:2628]......./smpd_command_destination [01:2628].......sending result command: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=connect result=SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the parent context sock 908: 86 bytes for command: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=connect result=SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_state_writing_session_header [01:2628]...../smpd_handle_op_write [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_WRITE event.error = 0, result = 0, context=parent [01:2628].....\smpd_handle_op_write [01:2628]......\smpd_state_writing_cmd [01:2628].......wrote command [01:2628].......command written to parent: "cmd=result src=1 dest=0 tag=0 cmd_tag=0 cmd_orig=connect result=SUCCESS " [01:2628].......\smpd_free_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_free_command [01:2628]....../smpd_state_writing_cmd [01:2628]...../smpd_handle_op_write [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628].....\smpd_handle_op_read [01:2628]......\smpd_state_reading_cmd_header [01:2628].......read command header [01:2628].......command header read, posting read for data: 34 bytes [01:2628].......\SMPDU_Sock_post_read [01:2628]........\SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_read [01:2628]....../smpd_state_reading_cmd_header [01:2628]...../smpd_handle_op_read [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628].....\smpd_handle_op_read [01:2628]......\smpd_state_reading_cmd [01:2628].......read command [01:2628].......\smpd_parse_command [01:2628]......./smpd_parse_command [01:2628].......read command: "cmd=start_dbs src=0 dest=1 tag=1 " [01:2628].......\smpd_handle_command [01:2628]........handling command: [01:2628]........ src = 0 [01:2628]........ dest = 1 [01:2628]........ cmd = start_dbs [01:2628]........ tag = 1 [01:2628]........ ctx = parent [01:2628]........ len = 34 [01:2628]........ str = cmd=start_dbs src=0 dest=1 tag=1 [01:2628]........\smpd_command_destination [01:2628].........1 -> 1 : returning NULL context [01:2628]......../smpd_command_destination [01:2628]........\smpd_handle_start_dbs_command [01:2628].........\smpd_dbs_init [01:2628]........./smpd_dbs_init [01:2628].........\smpd_create_command [01:2628]..........\smpd_init_command [01:2628]........../smpd_init_command [01:2628]........./smpd_create_command [01:2628].........\smpd_add_command_int_arg [01:2628]........./smpd_add_command_int_arg [01:2628].........\smpd_add_command_arg [01:2628]........./smpd_add_command_arg [01:2628].........\smpd_dbs_create [01:2628]........./smpd_dbs_create [01:2628].........\smpd_add_command_arg [01:2628]........./smpd_add_command_arg [01:2628].........\smpd_add_command_arg [01:2628]........./smpd_add_command_arg [01:2628].........\smpd_add_command_arg [01:2628]........./smpd_add_command_arg [01:2628].........sending result command to parent context: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=start_dbs kvs_name=F1703AE4-8AD8-436d-A69A-3C4915C46230 domain_name=4F32A33E-1C37-419b-ADFF-84C063ABF362 result=SUCCESS " [01:2628].........\smpd_post_write_command [01:2628]..........\smpd_package_command [01:2628]........../smpd_package_command [01:2628]..........\SMPDU_Sock_get_sock_id [01:2628]........../SMPDU_Sock_get_sock_id [01:2628]..........smpd_post_write_command on the parent context sock 908: 183 bytes for command: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=start_dbs kvs_name=F1703AE4-8AD8-436d-A69A-3C4915C46230 domain_name=4F32A33E-1C37-419b-ADFF-84C063ABF362 result=SUCCESS " [01:2628]..........\SMPDU_Sock_post_writev [01:2628]........../SMPDU_Sock_post_writev [01:2628]........./smpd_post_write_command [01:2628]......../smpd_handle_start_dbs_command [01:2628]......./smpd_handle_command [01:2628].......\smpd_post_read_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........posting a read for a command header on the parent context, sock 908 [01:2628]........\SMPDU_Sock_post_read [01:2628].........\SMPDU_Sock_post_readv [01:2628]........./SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_read [01:2628]......./smpd_post_read_command [01:2628]....../smpd_state_reading_cmd [01:2628]...../smpd_handle_op_read [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_WRITE event.error = 0, result = 0, context=parent [01:2628].....\smpd_handle_op_write [01:2628]......\smpd_state_writing_cmd [01:2628].......wrote command [01:2628].......command written to parent: "cmd=result src=1 dest=0 tag=1 cmd_tag=1 cmd_orig=start_dbs kvs_name=F1703AE4-8AD8-436d-A69A-3C4915C46230 domain_name=4F32A33E-1C37-419b-ADFF-84C063ABF362 result=SUCCESS " [01:2628].......\smpd_free_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_free_command [01:2628]....../smpd_state_writing_cmd [01:2628]...../smpd_handle_op_write [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628].....\smpd_handle_op_read [01:2628]......\smpd_state_reading_cmd_header [01:2628].......read command header [01:2628].......command header read, posting read for data: 241 bytes [01:2628].......\SMPDU_Sock_post_read [01:2628]........\SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_read [01:2628]....../smpd_state_reading_cmd_header [01:2628]...../smpd_handle_op_read [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628].....\smpd_handle_op_read [01:2628]......\smpd_state_reading_cmd [01:2628].......read command [01:2628].......\smpd_parse_command [01:2628]......./smpd_parse_command [01:2628].......read command: "cmd=launch src=0 dest=2 tag=2 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=nemesis" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=1 i=1 n=2 k=F1703AE4-8AD8-436d-A69A-3C4915C46230 kd=4F32A33E-1C37-419b-ADFF-84C063ABF362 " [01:2628].......\smpd_handle_command [01:2628]........handling command: [01:2628]........ src = 0 [01:2628]........ dest = 2 [01:2628]........ cmd = launch [01:2628]........ tag = 2 [01:2628]........ ctx = parent [01:2628]........ len = 241 [01:2628]........ str = cmd=launch src=0 dest=2 tag=2 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=nemesis" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=1 i=1 n=2 k=F1703AE4-8AD8-436d-A69A-3C4915C46230 kd=4F32A33E-1C37-419b-ADFF-84C063ABF362 [01:2628]........\smpd_command_destination [01:2628].........1 -> 2 : returning left_context [01:2628]......../smpd_command_destination [01:2628]........forwarding command to 2 [01:2628]........\smpd_forward_command [01:2628].........\smpd_create_command_copy [01:2628]........./smpd_create_command_copy [01:2628].........posting write of forwarded command: "cmd=launch src=0 dest=2 tag=2 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=nemesis" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=1 i=1 n=2 k=F1703AE4-8AD8-436d-A69A-3C4915C46230 kd=4F32A33E-1C37-419b-ADFF-84C063ABF362 " [01:2628].........\smpd_post_write_command [01:2628]..........\smpd_package_command [01:2628]........../smpd_package_command [01:2628]..........\SMPDU_Sock_get_sock_id [01:2628]........../SMPDU_Sock_get_sock_id [01:2628]..........smpd_post_write_command on the left context sock 968: 254 bytes for command: "cmd=launch src=0 dest=2 tag=2 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=nemesis" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=1 i=1 n=2 k=F1703AE4-8AD8-436d-A69A-3C4915C46230 kd=4F32A33E-1C37-419b-ADFF-84C063ABF362 " [01:2628]..........\SMPDU_Sock_post_writev [01:2628]........../SMPDU_Sock_post_writev [01:2628]........./smpd_post_write_command [01:2628]......../smpd_forward_command [01:2628]......./smpd_handle_command [01:2628].......\smpd_post_read_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........posting a read for a command header on the parent context, sock 908 [01:2628]........\SMPDU_Sock_post_read [01:2628].........\SMPDU_Sock_post_readv [01:2628]........./SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_read [01:2628]......./smpd_post_read_command [01:2628]....../smpd_state_reading_cmd [01:2628]...../smpd_handle_op_read [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_WRITE event.error = 0, result = 0, context=left [01:2628].....\smpd_handle_op_write [01:2628]......\smpd_state_writing_cmd [01:2628].......wrote command [01:2628].......command written to left: "cmd=launch src=0 dest=2 tag=2 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=nemesis" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=1 i=1 n=2 k=F1703AE4-8AD8-436d-A69A-3C4915C46230 kd=4F32A33E-1C37-419b-ADFF-84C063ABF362 " [01:2628].......\smpd_free_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_free_command [01:2628]....../smpd_state_writing_cmd [01:2628]...../smpd_handle_op_write [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628].....\smpd_handle_op_read [01:2628]......\smpd_state_reading_cmd_header [01:2628].......read command header [01:2628].......command header read, posting read for data: 241 bytes [01:2628].......\SMPDU_Sock_post_read [01:2628]........\SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_read [01:2628]....../smpd_state_reading_cmd_header [01:2628]...../smpd_handle_op_read [01:2628].....sock_waiting for the next event. [01:2628].....\SMPDU_Sock_wait [01:2628]...../SMPDU_Sock_wait [01:2628].....SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628].....\smpd_handle_op_read [01:2628]......\smpd_state_reading_cmd [01:2628].......read command [01:2628].......\smpd_parse_command [01:2628]......./smpd_parse_command [01:2628].......read command: "cmd=launch src=0 dest=1 tag=3 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=nemesis" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=0 i=0 n=2 k=F1703AE4-8AD8-436d-A69A-3C4915C46230 kd=4F32A33E-1C37-419b-ADFF-84C063ABF362 " [01:2628].......\smpd_handle_command [01:2628]........handling command: [01:2628]........ src = 0 [01:2628]........ dest = 1 [01:2628]........ cmd = launch [01:2628]........ tag = 3 [01:2628]........ ctx = parent [01:2628]........ len = 241 [01:2628]........ str = cmd=launch src=0 dest=1 tag=3 c=cpi.exe s=0 a=0 e="MPICH2_CHANNEL=nemesis" d="C:\Program Files\MPICH2\examples" p="C:\Program Files\MPICH2\examples" q=0 i=0 n=2 k=F1703AE4-8AD8-436d-A69A-3C4915C46230 kd=4F32A33E-1C37-419b-ADFF-84C063ABF362 [01:2628]........\smpd_command_destination [01:2628].........1 -> 1 : returning NULL context [01:2628]......../smpd_command_destination [01:2628]........\smpd_handle_launch_command [01:2628].........\smpd_create_process_struct [01:2628]..........\smpd_create_context [01:2628]...........\smpd_init_context [01:2628]............\smpd_init_command [01:2628]............/smpd_init_command [01:2628].........../smpd_init_context [01:2628]........../smpd_create_context [01:2628]..........\smpd_create_context [01:2628]...........\smpd_init_context [01:2628]............\smpd_init_command [01:2628]............/smpd_init_command [01:2628].........../smpd_init_context [01:2628]........../smpd_create_context [01:2628]..........\smpd_create_context [01:2628]...........\smpd_init_context [01:2628]............\smpd_init_command [01:2628]............/smpd_init_command [01:2628].........../smpd_init_context [01:2628]........../smpd_create_context [01:2628]..........\smpd_create_context [01:2628]...........\smpd_init_context [01:2628]............\smpd_init_command [01:2628]............/smpd_init_command [01:2628].........../smpd_init_context [01:2628]........../smpd_create_context [01:2628]........./smpd_create_process_struct [01:2628].........launching: 'cpi.exe' [01:2628].........\smpd_launch_process [01:2628]..........searching for 'cpi.exe' in 'C:\Program Files\MPICH2\examples' [01:2628]..........\smpd_search_path [01:2628]........../smpd_search_path [01:2628]..........\smpd_priority_class_to_win_class [01:2628]........../smpd_priority_class_to_win_class [01:2628]..........\smpd_priority_to_win_priority [01:2628]........../smpd_priority_to_win_priority [01:2628]..........\smpd_make_socket_loop_choose [01:2628]...........\smpd_get_hostname [01:2628].........../smpd_get_hostname [01:2628]........../smpd_make_socket_loop_choose [01:2628]..........\smpd_make_socket_loop_choose [01:2628]...........\smpd_get_hostname [01:2628].........../smpd_get_hostname [01:2628]........../smpd_make_socket_loop_choose [01:2628]..........\smpd_make_socket_loop_choose [01:2628]...........\smpd_get_hostname [01:2628].........../smpd_get_hostname [01:2628]........../smpd_make_socket_loop_choose [01:2628]..........\SMPDU_Sock_listen [01:2628]........../SMPDU_Sock_listen [01:2628]..........pmi listening on port 63440 [01:2628]..........\smpd_create_context [01:2628]...........\smpd_init_context [01:2628]............\smpd_init_command [01:2628]............/smpd_init_command [01:2628]............\SMPDU_Sock_set_user_ptr [01:2628]............/SMPDU_Sock_set_user_ptr [01:2628].........../smpd_init_context [01:2628]........../smpd_create_context [01:2628]..........\SMPDU_Sock_set_user_ptr [01:2628]........../SMPDU_Sock_set_user_ptr [01:2628]........./SMPDU_Sock_get_host_description [01:2628].........\SMPDU_Sock_hostname_to_host_description [01:2628]..........adding host: EC2AMAZ-BULC3HN to host list [01:2628]..........adding host: EC2AMAZ-BULC3HN.ec2.internal to host list [01:2628]..........adding host: ::1 to host list [01:2628]..........adding host: 10.190.238.8 to host list [01:2628]........./SMPDU_Sock_hostname_to_host_description [01:2628]......../SMPDU_Sock_get_host_description [01:2628]........\SetEnvironmentVariables [01:2628].........setting environment variable: = [01:2628]......../SetEnvironmentVariables [01:2628]........env: PMI_RANK=0 [01:2628]........env: PMI_SIZE=2 [01:2628]........env: PMI_KVS=F1703AE4-8AD8-436d-A69A-3C4915C46230 [01:2628]........env: PMI_DOMAIN=4F32A33E-1C37-419b-ADFF-84C063ABF362 [01:2628]........env: PMI_HOST=EC2AMAZ-BULC3HN EC2AMAZ-BULC3HN.ec2.internal ::1 10.190.238.8 [01:2628]........env: PMI_PORT=63440 [01:2628]........env: PMI_SMPD_ID=1 [01:2628]........env: PMI_SMPD_KEY=0 [01:2628]........env: PMI_CLIQUE=0 [01:2628]........env: PMI_SPAWN=0 [01:2628]........env: PMI_APPNUM=0 [01:2628]........CreateProcess(C:\Program Files\MPICH2\examples\cpi.exe) [01:2628]........\RemoveEnvironmentVariables [01:2628]......../RemoveEnvironmentVariables [01:2628]........\SMPDU_Sock_native_to_sock [01:2628]......../SMPDU_Sock_native_to_sock [01:2628]........\SMPDU_Sock_native_to_sock [01:2628]......../SMPDU_Sock_native_to_sock [01:2628]........\SMPDU_Sock_native_to_sock [01:2628]......../SMPDU_Sock_native_to_sock [01:2628]........\SMPDU_Sock_set_user_ptr [01:2628]......../SMPDU_Sock_set_user_ptr [01:2628]........\SMPDU_Sock_set_user_ptr [01:2628]......../SMPDU_Sock_set_user_ptr [01:2628]........\SMPDU_Sock_set_user_ptr [01:2628]......../SMPDU_Sock_set_user_ptr [01:2628]........\SMPDU_Sock_post_read [01:2628].........\SMPDU_Sock_post_readv [01:2628]........./SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_read [01:2628]........\SMPDU_Sock_post_read [01:2628].........\SMPDU_Sock_post_readv [01:2628]........./SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_read [01:2628]........\smpd_process_to_registry [01:2628]......../smpd_process_to_registry [01:2628]......./smpd_launch_process [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the parent context sock 908: 147 bytes for command: "cmd=result src=1 dest=0 tag=2 cmd_tag=3 cmd_orig=launch result=SUCCESS pg_id=F1703AE4-8AD8-436d-A69A-3C4915C46230 pg_rank=0 pg_ctx=0 " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_launch_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the parent context, sock 908 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to parent: "cmd=result src=1 dest=0 tag=2 cmd_tag=3 cmd_orig=launch result=SUCCESS pg_id=F1703AE4-8AD8-436d-A69A-3C4915C46230 pg_rank=0 pg_ctx=0 " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628]....*** entering smpd_pinthread pid:232 sock:780 *** [01:2628]....*** entering smpd_piothread pid:232 sock:972 *** [01:2628]....*** entering smpd_piothread pid:232 sock:980 *** [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_ACCEPT event.error = 0, result = 0, context=PMI_LISTENER [01:2628]...\smpd_handle_op_accept [01:2628]....\smpd_state_pmi_listening [01:2628].....\SMPDU_Sock_accept [01:2628]...../SMPDU_Sock_accept [01:2628].....\SMPDU_Sock_post_close [01:2628]...../SMPDU_Sock_post_close [01:2628].....\SMPDU_Sock_set_user_ptr [01:2628]...../SMPDU_Sock_set_user_ptr [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the pmi context, sock 1124 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_pmi_listening [01:2628].../smpd_handle_op_accept [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_CLOSE event.error = 0, result = 0, context=PMI_LISTENER [01:2628]...\smpd_handle_op_close [01:2628]....\smpd_get_state_string [01:2628]..../smpd_get_state_string [01:2628]....op_close received - SMPD_CLOSING state. [01:2628]....Unaffiliated PMI_LISTENER context closing. [01:2628]....\smpd_free_context [01:2628].....freeing PMI_LISTENER context. [01:2628].....\smpd_init_context [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_init_context [01:2628]..../smpd_free_context [01:2628].../smpd_handle_op_close [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 995 [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 105 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=0 value=2 node_id=1 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 1 [01:2628]...... dest = 0 [01:2628]...... cmd = init [01:2628]...... tag = 0 [01:2628]...... ctx = pmi [01:2628]...... len = 105 [01:2628]...... str = cmd=init src=1 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=0 value=2 node_id=1 [01:2628]......\smpd_command_destination [01:2628].......1 -> 0 : returning parent_context: 0 < 1 [01:2628]....../smpd_command_destination [01:2628]......forwarding command to 0 [01:2628]......\smpd_forward_command [01:2628].......\smpd_create_command_copy [01:2628]......./smpd_create_command_copy [01:2628].......posting write of forwarded command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=0 value=2 node_id=1 " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the parent context sock 908: 118 bytes for command: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=0 value=2 node_id=1 " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_forward_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the pmi context, sock 1124 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to parent: "cmd=init src=1 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=0 value=2 node_id=1 " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 66 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 0 [01:2628]...... dest = 1 [01:2628]...... cmd = result [01:2628]...... tag = 4 [01:2628]...... ctx = parent [01:2628]...... len = 66 [01:2628]...... str = cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_result [01:2628].......forwarding the dbs result command to the pmi context 0. [01:2628].......\smpd_forward_command [01:2628]........\smpd_create_command_copy [01:2628]......../smpd_create_command_copy [01:2628]........posting write of forwarded command: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS " [01:2628]........\smpd_post_write_command [01:2628].........\smpd_package_command [01:2628]........./smpd_package_command [01:2628].........\SMPDU_Sock_get_sock_id [01:2628]........./SMPDU_Sock_get_sock_id [01:2628].........smpd_post_write_command on the pmi context sock 1124: 79 bytes for command: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS " [01:2628].........\SMPDU_Sock_post_writev [01:2628]........./SMPDU_Sock_post_writev [01:2628]......../smpd_post_write_command [01:2628]......./smpd_forward_command [01:2628]....../smpd_handle_result [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the parent context, sock 908 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to pmi: "cmd=result src=0 dest=1 tag=4 cmd_tag=0 ctx_key=0 result=SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 106 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=dbget src=1 dest=1 tag=1 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=PMI_process_mapping " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 1 [01:2628]...... dest = 1 [01:2628]...... cmd = dbget [01:2628]...... tag = 1 [01:2628]...... ctx = pmi [01:2628]...... len = 106 [01:2628]...... str = cmd=dbget src=1 dest=1 tag=1 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=PMI_process_mapping [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_dbs_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\get_name_key_value [01:2628]......./get_name_key_value [01:2628].......\smpd_dbs_get [01:2628]......./smpd_dbs_get [01:2628].......sending reply to dbs command 'cmd=dbget src=1 dest=1 tag=1 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=PMI_process_mapping '. [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to pmi context: "cmd=result src=1 dest=1 tag=3 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the pmi context sock 1124: 95 bytes for command: "cmd=result src=1 dest=1 tag=3 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_dbs_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the pmi context, sock 1124 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to pmi: "cmd=result src=1 dest=1 tag=3 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 120 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=dbput src=1 dest=1 tag=2 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[0] value=EC2AMAZ-BULC3HN " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 1 [01:2628]...... dest = 1 [01:2628]...... cmd = dbput [01:2628]...... tag = 2 [01:2628]...... ctx = pmi [01:2628]...... len = 120 [01:2628]...... str = cmd=dbput src=1 dest=1 tag=2 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[0] value=EC2AMAZ-BULC3HN [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_dbs_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\get_name_key_value [01:2628]......./get_name_key_value [01:2628].......\smpd_dbs_put [01:2628]......./smpd_dbs_put [01:2628].......sending reply to dbs command 'cmd=dbput src=1 dest=1 tag=2 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[0] value=EC2AMAZ-BULC3HN '. [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to pmi context: "cmd=result src=1 dest=1 tag=4 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the pmi context sock 1124: 98 bytes for command: "cmd=result src=1 dest=1 tag=4 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_dbs_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the pmi context, sock 1124 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to pmi: "cmd=result src=1 dest=1 tag=4 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 92 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=barrier src=1 dest=1 tag=3 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 1 [01:2628]...... dest = 1 [01:2628]...... cmd = barrier [01:2628]...... tag = 3 [01:2628]...... ctx = pmi [01:2628]...... len = 92 [01:2628]...... str = cmd=barrier src=1 dest=1 tag=3 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_barrier_command [01:2628].......initializing barrier(F1703AE4-8AD8-436d-A69A-3C4915C46230): in=1 size=2 [01:2628]....../smpd_handle_barrier_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the pmi context, sock 1124 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 134 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=F1703AE4-8AD8-436d-A69A-3C4915C46230 pg_rank=1 pg_ctx=0 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 2 [01:2628]...... dest = 0 [01:2628]...... cmd = result [01:2628]...... tag = 0 [01:2628]...... ctx = left [01:2628]...... len = 134 [01:2628]...... str = cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=F1703AE4-8AD8-436d-A69A-3C4915C46230 pg_rank=1 pg_ctx=0 [01:2628]......\smpd_command_destination [01:2628].......1 -> 0 : returning parent_context: 0 < 1 [01:2628]....../smpd_command_destination [01:2628]......forwarding command to 0 [01:2628]......\smpd_forward_command [01:2628].......\smpd_create_command_copy [01:2628]......./smpd_create_command_copy [01:2628].......posting write of forwarded command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=F1703AE4-8AD8-436d-A69A-3C4915C46230 pg_rank=1 pg_ctx=0 " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the parent context sock 908: 147 bytes for command: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=F1703AE4-8AD8-436d-A69A-3C4915C46230 pg_rank=1 pg_ctx=0 " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_forward_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the left context, sock 968 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to parent: "cmd=result src=2 dest=0 tag=0 cmd_tag=2 cmd_orig=launch result=SUCCESS pg_id=F1703AE4-8AD8-436d-A69A-3C4915C46230 pg_rank=1 pg_ctx=0 " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 105 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=1 value=2 node_id=2 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 2 [01:2628]...... dest = 0 [01:2628]...... cmd = init [01:2628]...... tag = 0 [01:2628]...... ctx = left [01:2628]...... len = 105 [01:2628]...... str = cmd=init src=2 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=1 value=2 node_id=2 [01:2628]......\smpd_command_destination [01:2628].......1 -> 0 : returning parent_context: 0 < 1 [01:2628]....../smpd_command_destination [01:2628]......forwarding command to 0 [01:2628]......\smpd_forward_command [01:2628].......\smpd_create_command_copy [01:2628]......./smpd_create_command_copy [01:2628].......posting write of forwarded command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=1 value=2 node_id=2 " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the parent context sock 908: 118 bytes for command: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=1 value=2 node_id=2 " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_forward_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the left context, sock 968 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to parent: "cmd=init src=2 dest=0 tag=0 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=1 value=2 node_id=2 " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 66 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 0 [01:2628]...... dest = 2 [01:2628]...... cmd = result [01:2628]...... tag = 5 [01:2628]...... ctx = parent [01:2628]...... len = 66 [01:2628]...... str = cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS [01:2628]......\smpd_command_destination [01:2628].......1 -> 2 : returning left_context [01:2628]....../smpd_command_destination [01:2628]......forwarding command to 2 [01:2628]......\smpd_forward_command [01:2628].......\smpd_create_command_copy [01:2628]......./smpd_create_command_copy [01:2628].......posting write of forwarded command: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the left context sock 968: 79 bytes for command: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_forward_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the parent context, sock 908 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to left: "cmd=result src=0 dest=2 tag=5 cmd_tag=0 ctx_key=0 result=SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 106 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=dbget src=2 dest=1 tag=1 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=PMI_process_mapping " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 2 [01:2628]...... dest = 1 [01:2628]...... cmd = dbget [01:2628]...... tag = 1 [01:2628]...... ctx = left [01:2628]...... len = 106 [01:2628]...... str = cmd=dbget src=2 dest=1 tag=1 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=PMI_process_mapping [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_dbs_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\get_name_key_value [01:2628]......./get_name_key_value [01:2628].......\smpd_dbs_get [01:2628]......./smpd_dbs_get [01:2628].......sending reply to dbs command 'cmd=dbget src=2 dest=1 tag=1 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=PMI_process_mapping '. [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to left context: "cmd=result src=1 dest=2 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the left context sock 968: 95 bytes for command: "cmd=result src=1 dest=2 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_dbs_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the left context, sock 968 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to left: "cmd=result src=1 dest=2 tag=5 cmd_tag=1 cmd_orig=dbget ctx_key=0 result=DBS_FAIL " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 120 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=dbput src=2 dest=1 tag=2 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[1] value=EC2AMAZ-BULC3HN " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 2 [01:2628]...... dest = 1 [01:2628]...... cmd = dbput [01:2628]...... tag = 2 [01:2628]...... ctx = left [01:2628]...... len = 120 [01:2628]...... str = cmd=dbput src=2 dest=1 tag=2 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[1] value=EC2AMAZ-BULC3HN [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_dbs_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\get_name_key_value [01:2628]......./get_name_key_value [01:2628].......\smpd_dbs_put [01:2628]......./smpd_dbs_put [01:2628].......sending reply to dbs command 'cmd=dbput src=2 dest=1 tag=2 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[1] value=EC2AMAZ-BULC3HN '. [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to left context: "cmd=result src=1 dest=2 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the left context sock 968: 98 bytes for command: "cmd=result src=1 dest=2 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_dbs_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the left context, sock 968 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to left: "cmd=result src=1 dest=2 tag=6 cmd_tag=2 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 92 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=barrier src=2 dest=1 tag=3 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 2 [01:2628]...... dest = 1 [01:2628]...... cmd = barrier [01:2628]...... tag = 3 [01:2628]...... ctx = left [01:2628]...... len = 92 [01:2628]...... str = cmd=barrier src=2 dest=1 tag=3 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_barrier_command [01:2628].......incrementing barrier(F1703AE4-8AD8-436d-A69A-3C4915C46230) incount from 1 to 2 out of 2 [01:2628].......all in barrier, sending result back to all participators. [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending reply to barrier command 'F1703AE4-8AD8-436d-A69A-3C4915C46230'. [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to left context: "cmd=result src=1 dest=1 tag=7 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the pmi context sock 1124: 100 bytes for command: "cmd=result src=1 dest=1 tag=7 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending reply to barrier command 'F1703AE4-8AD8-436d-A69A-3C4915C46230'. [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to left context: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the left context sock 968: 100 bytes for command: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_barrier_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the left context, sock 968 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to pmi: "cmd=result src=1 dest=1 tag=7 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to left: "cmd=result src=1 dest=2 tag=8 cmd_tag=3 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 98 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=dbget src=1 dest=1 tag=4 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[1] " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 1 [01:2628]...... dest = 1 [01:2628]...... cmd = dbget [01:2628]...... tag = 4 [01:2628]...... ctx = pmi [01:2628]...... len = 98 [01:2628]...... str = cmd=dbget src=1 dest=1 tag=4 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[1] [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_dbs_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\get_name_key_value [01:2628]......./get_name_key_value [01:2628].......\smpd_dbs_get [01:2628]......./smpd_dbs_get [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending reply to dbs command 'cmd=dbget src=1 dest=1 tag=4 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[1] '. [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to pmi context: "cmd=result src=1 dest=1 tag=9 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the pmi context sock 1124: 120 bytes for command: "cmd=result src=1 dest=1 tag=9 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_dbs_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the pmi context, sock 1124 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to pmi: "cmd=result src=1 dest=1 tag=9 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 140 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=dbput src=1 dest=1 tag=5 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=sharedFilename[0] value=MPICH_NEM_2800_41417567968411 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 1 [01:2628]...... dest = 1 [01:2628]...... cmd = dbput [01:2628]...... tag = 5 [01:2628]...... ctx = pmi [01:2628]...... len = 140 [01:2628]...... str = cmd=dbput src=1 dest=1 tag=5 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=sharedFilename[0] value=MPICH_NEM_2800_41417567968411 [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_dbs_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\get_name_key_value [01:2628]......./get_name_key_value [01:2628].......\smpd_dbs_put [01:2628]......./smpd_dbs_put [01:2628].......sending reply to dbs command 'cmd=dbput src=1 dest=1 tag=5 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=sharedFilename[0] value=MPICH_NEM_2800_41417567968411 '. [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to pmi context: "cmd=result src=1 dest=1 tag=10 cmd_tag=5 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the pmi context sock 1124: 99 bytes for command: "cmd=result src=1 dest=1 tag=10 cmd_tag=5 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_dbs_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the pmi context, sock 1124 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 98 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to pmi: "cmd=result src=1 dest=1 tag=10 cmd_tag=5 cmd_orig=dbput ctx_key=0 result=DBS_SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=dbget src=2 dest=1 tag=4 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[0] " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 2 [01:2628]...... dest = 1 [01:2628]...... cmd = dbget [01:2628]...... tag = 4 [01:2628]...... ctx = left [01:2628]...... len = 98 [01:2628]...... str = cmd=dbget src=2 dest=1 tag=4 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[0] [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_dbs_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\get_name_key_value [01:2628]......./get_name_key_value [01:2628].......\smpd_dbs_get [01:2628]......./smpd_dbs_get [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending reply to dbs command 'cmd=dbget src=2 dest=1 tag=4 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=hostname[0] '. [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to left context: "cmd=result src=1 dest=2 tag=11 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the left context sock 968: 121 bytes for command: "cmd=result src=1 dest=2 tag=11 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_dbs_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the left context, sock 968 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to left: "cmd=result src=1 dest=2 tag=11 cmd_tag=4 cmd_orig=dbget ctx_key=0 value=EC2AMAZ-BULC3HN result=DBS_SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 92 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=barrier src=1 dest=1 tag=6 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 1 [01:2628]...... dest = 1 [01:2628]...... cmd = barrier [01:2628]...... tag = 6 [01:2628]...... ctx = pmi [01:2628]...... len = 92 [01:2628]...... str = cmd=barrier src=1 dest=1 tag=6 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_barrier_command [01:2628].......initializing barrier(F1703AE4-8AD8-436d-A69A-3C4915C46230): in=1 size=2 [01:2628]....../smpd_handle_barrier_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the pmi context, sock 1124 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 92 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=barrier src=2 dest=1 tag=5 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 2 [01:2628]...... dest = 1 [01:2628]...... cmd = barrier [01:2628]...... tag = 5 [01:2628]...... ctx = left [01:2628]...... len = 92 [01:2628]...... str = cmd=barrier src=2 dest=1 tag=5 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 value=2 [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_barrier_command [01:2628].......incrementing barrier(F1703AE4-8AD8-436d-A69A-3C4915C46230) incount from 1 to 2 out of 2 [01:2628].......all in barrier, sending result back to all participators. [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending reply to barrier command 'F1703AE4-8AD8-436d-A69A-3C4915C46230'. [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to left context: "cmd=result src=1 dest=1 tag=12 cmd_tag=6 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the pmi context sock 1124: 101 bytes for command: "cmd=result src=1 dest=1 tag=12 cmd_tag=6 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending reply to barrier command 'F1703AE4-8AD8-436d-A69A-3C4915C46230'. [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to left context: "cmd=result src=1 dest=2 tag=13 cmd_tag=5 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the left context sock 968: 101 bytes for command: "cmd=result src=1 dest=2 tag=13 cmd_tag=5 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_barrier_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the left context, sock 968 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to pmi: "cmd=result src=1 dest=1 tag=12 cmd_tag=6 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to left: "cmd=result src=1 dest=2 tag=13 cmd_tag=5 cmd_orig=barrier ctx_key=0 result=DBS_SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 104 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=dbget src=2 dest=1 tag=6 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=sharedFilename[0] " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 2 [01:2628]...... dest = 1 [01:2628]...... cmd = dbget [01:2628]...... tag = 6 [01:2628]...... ctx = left [01:2628]...... len = 104 [01:2628]...... str = cmd=dbget src=2 dest=1 tag=6 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=sharedFilename[0] [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_dbs_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......\get_name_key_value [01:2628]......./get_name_key_value [01:2628].......\smpd_dbs_get [01:2628]......./smpd_dbs_get [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending reply to dbs command 'cmd=dbget src=2 dest=1 tag=6 ctx_key=0 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 key=sharedFilename[0] '. [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to left context: "cmd=result src=1 dest=2 tag=14 cmd_tag=6 cmd_orig=dbget ctx_key=0 value=MPICH_NEM_2800_41417567968411 result=DBS_SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the left context sock 968: 135 bytes for command: "cmd=result src=1 dest=2 tag=14 cmd_tag=6 cmd_orig=dbget ctx_key=0 value=MPICH_NEM_2800_41417567968411 result=DBS_SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_dbs_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the left context, sock 968 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to left: "cmd=result src=1 dest=2 tag=14 cmd_tag=6 cmd_orig=dbget ctx_key=0 value=MPICH_NEM_2800_41417567968411 result=DBS_SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 1086 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=stderr src=2 dest=0 tag=1 rank=1 first=1 data=466174616C206572726F7220696E204D50495F496E69743A204F74686572204D5049206572726F722C206572726F7220737461636B3A0D0A4D5049525F496E69745F74687265616428333932292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F496E697428313339292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A206368616E6E656C20696E697469616C697A6174696F6E206661696C65640D0A4D504944495F4348335F496E6974283338292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F6E656D5F696E697428313936292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D504944495F434833495F5365675F636F6D6D697428333636292E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F486E645F646573657269616C697A6528333234292E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6F70656E28383633292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6372656174655F6174746163685F74656D706C28373633293A20756E61626C6520746F20616C6C6F6361746520736861726564206D656D6F7279202D204F70656E46696C654D617070696E67205468652073797374656D2063616E6E6F742066696E64207468652066696C65207370656369666965642E0D0D0A0D0A " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 2 [01:2628]...... dest = 0 [01:2628]...... cmd = stderr [01:2628]...... tag = 1 [01:2628]...... ctx = left [01:2628]...... len = 1086 [01:2628]...... str = cmd=stderr src=2 dest=0 tag=1 rank=1 first=1 data=466174616C206572726F7220696E204D50495F496E69743A204F74686572204D5049206572726F722C206572726F7220737461636B3A0D0A4D5049525F496E69745F74687265616428333932292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F496E697428313339292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A206368616E6E656C20696E697469616C697A6174696F6E206661696C65640D0A4D504944495F4348335F496E6974283338292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F6E656D5F696E697428313936292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D504944495F434833495F5365675F636F6D6D697428333636292E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F486E645F646573657269616C697A6528333234292E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6F70656E28383633292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6372656174655F6174746163685F74656D706C28373633293A20756E61626C6520746F20616C6C6F6361746520736861726564206D656D6F7279202D204F70656E46696C654D617070696E67205468652073797374656D2063616E6E6F742066696E64207468652066696C65207370656369666965642E0D0D0A0D0A [01:2628]......\smpd_command_destination [01:2628].......1 -> 0 : returning parent_context: 0 < 1 [01:2628]....../smpd_command_destination [01:2628]......forwarding command to 0 [01:2628]......\smpd_forward_command [01:2628].......\smpd_create_command_copy [01:2628]......./smpd_create_command_copy [01:2628].......posting write of forwarded command: "cmd=stderr src=2 dest=0 tag=1 rank=1 first=1 data=466174616C206572726F7220696E204D50495F496E69743A204F74686572204D5049206572726F722C206572726F7220737461636B3A0D0A4D5049525F496E69745F74687265616428333932292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F496E697428313339292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A206368616E6E656C20696E697469616C697A6174696F6E206661696C65640D0A4D504944495F4348335F496E6974283338292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F6E656D5F696E697428313936292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D504944495F434833495F5365675F636F6D6D697428333636292E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F486E645F646573657269616C697A6528333234292E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6F70656E28383633292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6372656174655F6174746163685F74656D706C28373633293A20756E61626C6520746F20616C6C6F6361746520736861726564206D656D6F7279202D204F70656E46696C654D617070696E67205468652073797374656D2063616E6E6F742066696E64207468652066696C65207370656369666965642E0D0D0A0D0A " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the parent context sock 908: 1099 bytes for command: "cmd=stderr src=2 dest=0 tag=1 rank=1 first=1 data=466174616C206572726F7220696E204D50495F496E69743A204F74686572204D5049206572726F722C206572726F7220737461636B3A0D0A4D5049525F496E69745F74687265616428333932292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F496E697428313339292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A206368616E6E656C20696E697469616C697A6174696F6E206661696C65640D0A4D504944495F4348335F496E6974283338292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F6E656D5F696E697428313936292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D504944495F434833495F5365675F636F6D6D697428333636292E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F486E645F646573657269616C697A6528333234292E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6F70656E28383633292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6372656174655F6174746163685F74656D706C28373633293A20756E61626C6520746F20616C6C6F6361746520736861726564206D656D6F7279202D204F70656E46696C654D617070696E67205468652073797374656D2063616E6E6F742066696E64207468652066696C65207370656369666965642E0D0D0A0D0A " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_forward_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the left context, sock 968 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to parent: "cmd=stderr src=2 dest=0 tag=1 rank=1 first=1 data=466174616C206572726F7220696E204D50495F496E69743A204F74686572204D5049206572726F722C206572726F7220737461636B3A0D0A4D5049525F496E69745F74687265616428333932292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F496E697428313339292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A206368616E6E656C20696E697469616C697A6174696F6E206661696C65640D0A4D504944495F4348335F496E6974283338292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049445F6E656D5F696E697428313936292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D504944495F434833495F5365675F636F6D6D697428333636292E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F486E645F646573657269616C697A6528333234292E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6F70656E28383633292E2E2E2E2E2E2E2E2E2E2E2E2E2E2E3A200D0A4D5049555F53484D575F5365675F6372656174655F6174746163685F74656D706C28373633293A20756E61626C6520746F20616C6C6F6361746520736861726564206D656D6F7279202D204F70656E46696C654D617070696E67205468652073797374656D2063616E6E6F742066696E64207468652066696C65207370656369666965642E0D0D0A0D0A " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 84 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=exit src=2 dest=0 tag=2 rank=1 code=1 kvs=F1703AE4-8AD8-436d-A69A-3C4915C46230 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 2 [01:2628]...... dest = 0 [01:2628]...... cmd = exit [01:2628]...... tag = 2 [01:2628]...... ctx = left [01:2628]...... len = 84 [01:2628]...... str = cmd=exit src=2 dest=0 tag=2 rank=1 code=1 kvs=F1703AE4-8AD8-436d-A69A-3C4915C46230 [01:2628]......\smpd_command_destination [01:2628].......1 -> 0 : returning parent_context: 0 < 1 [01:2628]....../smpd_command_destination [01:2628]......forwarding command to 0 [01:2628]......\smpd_forward_command [01:2628].......\smpd_create_command_copy [01:2628]......./smpd_create_command_copy [01:2628].......posting write of forwarded command: "cmd=exit src=2 dest=0 tag=2 rank=1 code=1 kvs=F1703AE4-8AD8-436d-A69A-3C4915C46230 " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the parent context sock 908: 97 bytes for command: "cmd=exit src=2 dest=0 tag=2 rank=1 code=1 kvs=F1703AE4-8AD8-436d-A69A-3C4915C46230 " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_forward_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the left context, sock 968 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to parent: "cmd=exit src=2 dest=0 tag=2 rank=1 code=1 kvs=F1703AE4-8AD8-436d-A69A-3C4915C46230 " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 105 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=suspend src=0 dest=1 tag=6 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 rank=0 exit_code=123 ctx_key=0 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 0 [01:2628]...... dest = 1 [01:2628]...... cmd = suspend [01:2628]...... tag = 6 [01:2628]...... ctx = parent [01:2628]...... len = 105 [01:2628]...... str = cmd=suspend src=0 dest=1 tag=6 name=F1703AE4-8AD8-436d-A69A-3C4915C46230 rank=0 exit_code=123 ctx_key=0 [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_suspend_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_add_command_int_arg [01:2628]......./smpd_add_command_int_arg [01:2628].......\smpd_suspend_process [01:2628]......./smpd_suspend_process [01:2628].......\smpd_add_command_arg [01:2628]......./smpd_add_command_arg [01:2628].......sending result command to parent context: "cmd=result src=1 dest=0 tag=15 cmd_tag=6 result=SUCCESS " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the parent context sock 908: 70 bytes for command: "cmd=result src=1 dest=0 tag=15 cmd_tag=6 result=SUCCESS " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_suspend_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the parent context, sock 908 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to parent: "cmd=result src=1 dest=0 tag=15 cmd_tag=6 result=SUCCESS " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 53 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=kill src=0 dest=1 tag=7 exit_code=123 ctx_key=0 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 0 [01:2628]...... dest = 1 [01:2628]...... cmd = kill [01:2628]...... tag = 7 [01:2628]...... ctx = parent [01:2628]...... len = 53 [01:2628]...... str = cmd=kill src=0 dest=1 tag=7 exit_code=123 ctx_key=0 [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_kill_command [01:2628].......\smpd_kill_process [01:2628]........\smpd_process_from_registry [01:2628]......../smpd_process_from_registry [01:2628]........ReadFile failed, error 109 [01:2628]........*** smpd_piothread finishing pid:232 *** [01:2628]........ReadFile failed, error 109 [01:2628]........*** smpd_piothread finishing pid:232 *** [01:2628]......./smpd_kill_process [01:2628]....../smpd_handle_kill_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the parent context, sock 908 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628]....GetQueuedCompletionStatus failed, GetLastError: 64 [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = -1, result = 0, context=pmi [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....ERROR:unable to read the cmd header on the pmi context, Error = -1 . [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...SOCK_OP_READ failed - result = -1, closing pmi context. [01:2628]...\SMPDU_Sock_post_close [01:2628]....\SMPDU_Sock_post_read [01:2628].....\SMPDU_Sock_post_readv [01:2628]......ERROR:Error posting readv, An existing connection was forcibly closed by the remote host.(10054) [01:2628]...../SMPDU_Sock_post_readv [01:2628]..../SMPDU_Sock_post_read [01:2628].../SMPDU_Sock_post_close [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = -1, result = 0, context=stdout [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_stdouterr [01:2628]..../smpd_state_reading_stdouterr [01:2628].../smpd_handle_op_read [01:2628]...SOCK_OP_READ failed - result = -1, closing stdout context. [01:2628]...\SMPDU_Sock_post_close [01:2628]....\SMPDU_Sock_post_read [01:2628].....\SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_readv [01:2628]..../SMPDU_Sock_post_read [01:2628].../SMPDU_Sock_post_close [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = -1, result = 0, context=stderr [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_stdouterr [01:2628]..../smpd_state_reading_stdouterr [01:2628].../smpd_handle_op_read [01:2628]...SOCK_OP_READ failed - result = -1, closing stderr context. [01:2628]...\SMPDU_Sock_post_close [01:2628]....\SMPDU_Sock_post_read [01:2628].....\SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_readv [01:2628]..../SMPDU_Sock_post_read [01:2628].../SMPDU_Sock_post_close [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_CLOSE event.error = 0, result = 0, context=pmi [01:2628]...\smpd_handle_op_close [01:2628]....\smpd_get_state_string [01:2628]..../smpd_get_state_string [01:2628]....op_close received - SMPD_CLOSING state. [01:2628]....process refcount == 2, pmi closed. [01:2628]....\smpd_free_context [01:2628].....freeing pmi context. [01:2628].....\smpd_init_context [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_init_context [01:2628]..../smpd_free_context [01:2628].../smpd_handle_op_close [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_CLOSE event.error = 0, result = 0, context=stdout [01:2628]...\smpd_handle_op_close [01:2628]....\smpd_get_state_string [01:2628]..../smpd_get_state_string [01:2628]....op_close received - SMPD_CLOSING state. [01:2628]....process refcount == 1, stdout closed. [01:2628]....\smpd_free_context [01:2628].....freeing stdout context. [01:2628].....\smpd_init_context [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_init_context [01:2628]..../smpd_free_context [01:2628].../smpd_handle_op_close [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_CLOSE event.error = 0, result = 0, context=stderr [01:2628]...\smpd_handle_op_close [01:2628]....\smpd_get_state_string [01:2628]..../smpd_get_state_string [01:2628]....op_close received - SMPD_CLOSING state. [01:2628]....process refcount == 0, waiting for the process to finish exiting. [01:2628]....\smpd_process_from_registry [01:2628]..../smpd_process_from_registry [01:2628]....\smpd_wait_process [01:2628]..../smpd_wait_process [01:2628]....\SMPDU_Sock_post_close [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../SMPDU_Sock_post_close [01:2628]....\smpd_create_command [01:2628].....\smpd_init_command [01:2628]...../smpd_init_command [01:2628]..../smpd_create_command [01:2628]....\smpd_add_command_int_arg [01:2628]..../smpd_add_command_int_arg [01:2628]....\smpd_add_command_int_arg [01:2628]..../smpd_add_command_int_arg [01:2628]....\smpd_add_command_arg [01:2628]..../smpd_add_command_arg [01:2628]....creating an exit command for rank 0, pid 232, exit code 123. [01:2628]....\smpd_post_write_command [01:2628].....\smpd_package_command [01:2628]...../smpd_package_command [01:2628].....\SMPDU_Sock_get_sock_id [01:2628]...../SMPDU_Sock_get_sock_id [01:2628].....smpd_post_write_command on the parent context sock 908: 100 bytes for command: "cmd=exit src=1 dest=0 tag=16 rank=0 code=123 kvs=F1703AE4-8AD8-436d-A69A-3C4915C46230 " [01:2628].....\SMPDU_Sock_post_writev [01:2628]...../SMPDU_Sock_post_writev [01:2628]..../smpd_post_write_command [01:2628]....\smpd_free_process_struct [01:2628]..../smpd_free_process_struct [01:2628]....\smpd_free_context [01:2628].....freeing stderr context. [01:2628].....\smpd_init_context [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_init_context [01:2628]..../smpd_free_context [01:2628].../smpd_handle_op_close [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to parent: "cmd=exit src=1 dest=0 tag=16 rank=0 code=123 kvs=F1703AE4-8AD8-436d-A69A-3C4915C46230 " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628]....closing output socket took 0.000 seconds [01:2628]....closing output socket took 0.000 seconds [01:2628]....*** smpd_pinthread finishing pid:232 *** [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_CLOSE event.error = 0, result = 0, context=stdin [01:2628]...\smpd_handle_op_close [01:2628]....\smpd_get_state_string [01:2628]..../smpd_get_state_string [01:2628]....op_close received - SMPD_CLOSING state. [01:2628]....Unaffiliated stdin context closing. [01:2628]....\smpd_free_context [01:2628].....freeing stdin context. [01:2628].....\smpd_init_context [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_init_context [01:2628]..../smpd_free_context [01:2628].../smpd_handle_op_close [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 30 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=close src=0 dest=1 tag=8 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 0 [01:2628]...... dest = 1 [01:2628]...... cmd = close [01:2628]...... tag = 8 [01:2628]...... ctx = parent [01:2628]...... len = 30 [01:2628]...... str = cmd=close src=0 dest=1 tag=8 [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_close_command [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......sending close command to left child: "cmd=close src=1 dest=2 tag=17 " [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the left context sock 968: 44 bytes for command: "cmd=close src=1 dest=2 tag=17 " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_close_command [01:2628]...../smpd_handle_command [01:2628].....\smpd_post_read_command [01:2628]......\SMPDU_Sock_get_sock_id [01:2628]....../SMPDU_Sock_get_sock_id [01:2628]......posting a read for a command header on the parent context, sock 908 [01:2628]......\SMPDU_Sock_post_read [01:2628].......\SMPDU_Sock_post_readv [01:2628]......./SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_read [01:2628]...../smpd_post_read_command [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to left: "cmd=close src=1 dest=2 tag=17 " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 31 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=closed src=2 dest=1 tag=3 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 2 [01:2628]...... dest = 1 [01:2628]...... cmd = closed [01:2628]...... tag = 3 [01:2628]...... ctx = left [01:2628]...... len = 31 [01:2628]...... str = cmd=closed src=2 dest=1 tag=3 [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_closed_command [01:2628].......closed command received from left child, closing sock. [01:2628].......\SMPDU_Sock_get_sock_id [01:2628]......./SMPDU_Sock_get_sock_id [01:2628].......SMPDU_Sock_post_close(968) [01:2628].......\SMPDU_Sock_post_close [01:2628]........\SMPDU_Sock_post_read [01:2628].........\SMPDU_Sock_post_readv [01:2628]........./SMPDU_Sock_post_readv [01:2628]......../SMPDU_Sock_post_read [01:2628]......./SMPDU_Sock_post_close [01:2628].......\smpd_create_command [01:2628]........\smpd_init_command [01:2628]......../smpd_init_command [01:2628]......./smpd_create_command [01:2628].......\smpd_post_write_command [01:2628]........\smpd_package_command [01:2628]......../smpd_package_command [01:2628]........\SMPDU_Sock_get_sock_id [01:2628]......../SMPDU_Sock_get_sock_id [01:2628]........smpd_post_write_command on the parent context sock 908: 53 bytes for command: "cmd=closed_request src=1 dest=0 tag=18 " [01:2628]........\SMPDU_Sock_post_writev [01:2628]......../SMPDU_Sock_post_writev [01:2628]......./smpd_post_write_command [01:2628]....../smpd_handle_closed_command [01:2628]...../smpd_handle_command [01:2628].....not posting read for another command because SMPD_CLOSE returned [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_CLOSE event.error = 0, result = 0, context=left [01:2628]...\smpd_handle_op_close [01:2628]....\smpd_get_state_string [01:2628]..../smpd_get_state_string [01:2628]....op_close received - SMPD_CLOSING state. [01:2628]....Unaffiliated left context closing. [01:2628]....\smpd_create_command [01:2628].....\smpd_init_command [01:2628]...../smpd_init_command [01:2628]..../smpd_create_command [01:2628]....\smpd_post_write_command [01:2628].....\smpd_package_command [01:2628]...../smpd_package_command [01:2628].....enqueueing write at the end of the list. [01:2628]..../smpd_post_write_command [01:2628]....\smpd_free_context [01:2628].....freeing left context. [01:2628].....\smpd_init_context [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_init_context [01:2628]..../smpd_free_context [01:2628].../smpd_handle_op_close [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to parent: "cmd=closed_request src=1 dest=0 tag=18 " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628].....smpd_handle_written: posting write(53 bytes) for command: "cmd=closed_request src=1 dest=0 tag=19 " [01:2628].....\SMPDU_Sock_post_writev [01:2628]...../SMPDU_Sock_post_writev [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_WRITE event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_write [01:2628]....\smpd_state_writing_cmd [01:2628].....wrote command [01:2628].....command written to parent: "cmd=closed_request src=1 dest=0 tag=19 " [01:2628].....\smpd_free_command [01:2628]......\smpd_init_command [01:2628]....../smpd_init_command [01:2628]...../smpd_free_command [01:2628]..../smpd_state_writing_cmd [01:2628].../smpd_handle_op_write [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd_header [01:2628].....read command header [01:2628].....command header read, posting read for data: 31 bytes [01:2628].....\SMPDU_Sock_post_read [01:2628]......\SMPDU_Sock_post_readv [01:2628]....../SMPDU_Sock_post_readv [01:2628]...../SMPDU_Sock_post_read [01:2628]..../smpd_state_reading_cmd_header [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_READ event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_read [01:2628]....\smpd_state_reading_cmd [01:2628].....read command [01:2628].....\smpd_parse_command [01:2628]...../smpd_parse_command [01:2628].....read command: "cmd=closed src=0 dest=1 tag=9 " [01:2628].....\smpd_handle_command [01:2628]......handling command: [01:2628]...... src = 0 [01:2628]...... dest = 1 [01:2628]...... cmd = closed [01:2628]...... tag = 9 [01:2628]...... ctx = parent [01:2628]...... len = 31 [01:2628]...... str = cmd=closed src=0 dest=1 tag=9 [01:2628]......\smpd_command_destination [01:2628].......1 -> 1 : returning NULL context [01:2628]....../smpd_command_destination [01:2628]......\smpd_handle_closed_command [01:2628].......closed command received from parent, closing sock. [01:2628].......\SMPDU_Sock_get_sock_id [01:2628]......./SMPDU_Sock_get_sock_id [01:2628].......SMPDU_Sock_post_close(908) [01:2628].......\SMPDU_Sock_post_close [01:2628]......./SMPDU_Sock_post_close [01:2628]....../smpd_handle_closed_command [01:2628]...../smpd_handle_command [01:2628].....not posting read for another command because SMPD_EXITING returned [01:2628]..../smpd_state_reading_cmd [01:2628].../smpd_handle_op_read [01:2628]...sock_waiting for the next event. [01:2628]...\SMPDU_Sock_wait [01:2628].../SMPDU_Sock_wait [01:2628]...SOCK_OP_CLOSE event.error = 0, result = 0, context=parent [01:2628]...\smpd_handle_op_close [01:2628]....\smpd_get_state_string [01:2628]..../smpd_get_state_string [01:2628]....op_close received - SMPD_CLOSING state. [01:2628]....Unaffiliated parent context closing. [01:2628]....\smpd_free_context [01:2628].....freeing a context not in the global list - this should be impossible. [01:2628]..../smpd_free_context [01:2628]....all contexts closed, exiting state machine. [01:2628].../smpd_handle_op_close [01:2628]../smpd_enter_at_state [01:2628]..\smpd_exit [01:2628]...\smpd_kill_all_processes [01:2628].../smpd_kill_all_processes [01:2628]...\smpd_finalize_drive_maps [01:2628].../smpd_finalize_drive_maps [01:2628]...\smpd_dbs_finalize [01:2628].../smpd_dbs_finalize [01:2628]...\SMPDU_Sock_finalize [01:2628].../SMPDU_Sock_finalize