ash-3.2# cat root.101219_00001.log 18:57:12.220 [45481990.1] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 18:57:12.221 [45481990.1] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 18:57:12.221 [45481990.1] <2> ConnectionCache::connectAndCache: Acquiring new connection for host NetbackupA, query type 98 18:57:12.221 [45481990.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:57:12.221 [45481990.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:57:12.221 [45481990.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:57:12.221 [45481990.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24556 TO 10.100.1.235.13721 fd = 4 18:57:12.221 [45481990.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpdbm 18:57:12.221 [45481990.1] <2> logconnections: BPDBM CONNECT FROM 10.100.1.235.24556 TO 10.100.1.235.13721 fd = 4 18:57:12.221 [45481990.1] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1112] Ignoring VxSS authentication 2 0x2 18:57:12.222 [45481990.1] <2> db_end: Need to collect reply 18:57:12.331 [45481990.1] <4> listen_loop: VERBOSE changing from 0 to 5 18:57:12.351 [45481990.1] <2> listen_loop: do_schild = 1 18:57:12.351 [45481990.1] <2> childterm: pid=57213146 exit=0, signo=0 core=no 18:57:12.351 [45481990.1] <2> schild: wait2() ECHILD 18:57:42.035 [45481990.1] <2> bprd: socket fd from accept() is 4 18:57:42.040 [45875216.1] <2> logconnections: BPRD ACCEPT FROM 10.100.1.235.24569 TO 10.100.1.235.13720 fd = 4 18:57:42.040 [45875216.1] <2> process_request: setsockopt SO_LINGER on 4 succeeded. 18:57:42.040 [45875216.1] <2> vnet_pcache_init_table: [vnet_private.c:214] starting cache size 200 0xc8 18:57:42.040 [45875216.1] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:2023] found via getnameinfo OUR_HOST=netbackupa.thermaxdomain.com IPSTR=10.100.1.235 18:57:42.040 [45875216.1] <2> connected_peer: Connection from host netbackupa.thermaxdomain.com, 10.100.1.235, on non-reserved port 24569 18:57:42.041 [45481990.1] <2> listen_loop: request complete 18:57:42.041 [45875216.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:638] VxSS magic=329199, remote_vxss=0 18:57:42.041 [45875216.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:695] Ignoring VxSS authentication 2 0x2 18:57:42.041 [45875216.1] <2> process_request: command C_REREAD_CONFIG (0) received 18:57:42.044 [45875216.1] <2> bprd_connect_need_auth: Ignoring reserved port request. 18:57:42.044 [45875216.1] <2> bprd_connect_need_auth: Ignoring local_name NetbackupA. 18:57:42.044 [45875216.1] <2> bprd_connect_need_auth: Ignoring owner_name root. 18:57:42.048 [45875216.1] <2> retry_getaddrinfo: [vnet_addrinfo.c:910] using SVC=bprd PORT=13720 18:57:42.048 [45875216.1] <2> retry_getaddrinfo: [vnet_addrinfo.c:914] adjusted service name 13720 18:57:42.048 [45875216.1] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1738] found via getaddrinfo NAME=localhost SVC=bprd 18:57:42.049 [45875216.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:57:42.051 [45875216.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:57:42.051 [45875216.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 127.0.0.1.24572 TO 127.0.0.1.13720 fd = 6 18:57:42.051 [45875216.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO localhost 127.0.0.1 bprd 18:57:42.051 [45875216.1] <2> logconnections: BPRD CONNECT FROM 127.0.0.1.24572 TO 127.0.0.1.13720 fd = 6 18:57:42.052 [45481990.1] <2> bprd: socket fd from accept() is 4 18:57:42.053 [59768994.1] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.24572 TO 127.0.0.1.13720 fd = 4 18:57:42.054 [59768994.1] <2> process_request: setsockopt SO_LINGER on 4 succeeded. 18:57:42.054 [59768994.1] <2> vnet_pcache_init_table: [vnet_private.c:214] starting cache size 200 0xc8 18:57:42.052 [45875216.1] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1112] Ignoring VxSS authentication 2 0x2 18:57:42.054 [45481990.1] <2> listen_loop: request complete 18:57:42.054 [59768994.1] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:2023] found via getnameinfo OUR_HOST=localhost IPSTR=127.0.0.1 18:57:42.054 [59768994.1] <2> connected_peer: Connection from host localhost, 127.0.0.1, on non-reserved port 24572 18:57:42.055 [59768994.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:57:42.056 [59768994.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:57:42.057 [59768994.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:638] VxSS magic=329199, remote_vxss=1 18:57:42.057 [59768994.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:695] Ignoring VxSS authentication 2 0x2 18:57:42.057 [59768994.1] <2> process_request: command C_ALIVE (1) received 18:57:42.121 [45481990.1] <2> listen_loop: do_schild = 1 18:57:42.121 [45481990.1] <2> childterm: pid=59768994 exit=0, signo=0 core=no 18:57:42.121 [45481990.1] <2> schild: child(ren) not ready to terminate 18:57:42.130 [45481990.1] <2> listen_loop: do_schild = 1 18:57:42.130 [45481990.1] <2> childterm: pid=45875216 exit=0, signo=0 core=no 18:57:42.130 [45481990.1] <2> schild: wait2() ECHILD 18:57:43.622 [45481990.1] <2> bprd: socket fd from accept() is 4 18:57:43.626 [40173676.1] <2> logconnections: BPRD ACCEPT FROM 10.100.1.235.24573 TO 10.100.1.235.13720 fd = 4 18:57:43.626 [40173676.1] <2> process_request: setsockopt SO_LINGER on 4 succeeded. 18:57:43.627 [40173676.1] <2> vnet_pcache_init_table: [vnet_private.c:214] starting cache size 200 0xc8 18:57:43.627 [40173676.1] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:2023] found via getnameinfo OUR_HOST=netbackupa.thermaxdomain.com IPSTR=10.100.1.235 18:57:43.627 [40173676.1] <2> connected_peer: Connection from host netbackupa.thermaxdomain.com, 10.100.1.235, on non-reserved port 24573 18:57:43.628 [45481990.1] <2> listen_loop: request complete 18:57:43.628 [40173676.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:638] VxSS magic=329199, remote_vxss=0 18:57:43.628 [40173676.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:695] Ignoring VxSS authentication 2 0x2 18:57:43.628 [40173676.1] <2> process_request: command C_REREAD_CONFIG (0) received 18:57:43.631 [40173676.1] <2> bprd_connect_need_auth: Ignoring reserved port request. 18:57:43.631 [40173676.1] <2> bprd_connect_need_auth: Ignoring local_name NetbackupA. 18:57:43.631 [40173676.1] <2> bprd_connect_need_auth: Ignoring owner_name root. 18:57:43.632 [40173676.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:57:43.634 [40173676.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:57:43.634 [40173676.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 127.0.0.1.24575 TO 127.0.0.1.13720 fd = 6 18:57:43.634 [40173676.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO localhost 127.0.0.1 bprd 18:57:43.634 [40173676.1] <2> logconnections: BPRD CONNECT FROM 127.0.0.1.24575 TO 127.0.0.1.13720 fd = 6 18:57:43.634 [40173676.1] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1112] Ignoring VxSS authentication 2 0x2 18:57:43.635 [45481990.1] <2> bprd: socket fd from accept() is 4 18:57:43.637 [59768996.1] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.24575 TO 127.0.0.1.13720 fd = 4 18:57:43.637 [59768996.1] <2> process_request: setsockopt SO_LINGER on 4 succeeded. 18:57:43.637 [59768996.1] <2> vnet_pcache_init_table: [vnet_private.c:214] starting cache size 200 0xc8 18:57:43.637 [45481990.1] <2> listen_loop: request complete 18:57:43.638 [59768996.1] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:2023] found via getnameinfo OUR_HOST=localhost IPSTR=127.0.0.1 18:57:43.638 [59768996.1] <2> connected_peer: Connection from host localhost, 127.0.0.1, on non-reserved port 24575 18:57:43.638 [59768996.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:57:43.640 [59768996.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:57:43.640 [59768996.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:638] VxSS magic=329199, remote_vxss=1 18:57:43.640 [59768996.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:695] Ignoring VxSS authentication 2 0x2 18:57:43.640 [59768996.1] <2> process_request: command C_ALIVE (1) received 18:57:43.704 [45481990.1] <2> listen_loop: do_schild = 1 18:57:43.704 [45481990.1] <2> childterm: pid=59768996 exit=0, signo=0 core=no 18:57:43.704 [45481990.1] <2> schild: child(ren) not ready to terminate 18:57:43.713 [45481990.1] <2> listen_loop: do_schild = 1 18:57:43.713 [45481990.1] <2> childterm: pid=40173676 exit=0, signo=0 core=no 18:57:43.713 [45481990.1] <2> schild: wait2() ECHILD 18:57:45.012 [45481990.1] <2> bprd: socket fd from accept() is 4 18:57:45.017 [40173678.1] <2> logconnections: BPRD ACCEPT FROM 10.100.1.235.24576 TO 10.100.1.235.13720 fd = 4 18:57:45.017 [40173678.1] <2> process_request: setsockopt SO_LINGER on 4 succeeded. 18:57:45.017 [40173678.1] <2> vnet_pcache_init_table: [vnet_private.c:214] starting cache size 200 0xc8 18:57:45.017 [40173678.1] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:2023] found via getnameinfo OUR_HOST=netbackupa.thermaxdomain.com IPSTR=10.100.1.235 18:57:45.017 [40173678.1] <2> connected_peer: Connection from host netbackupa.thermaxdomain.com, 10.100.1.235, on non-reserved port 24576 18:57:45.017 [45481990.1] <2> listen_loop: request complete 18:57:45.018 [40173678.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:638] VxSS magic=329199, remote_vxss=0 18:57:45.018 [40173678.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:695] Ignoring VxSS authentication 2 0x2 18:57:45.018 [40173678.1] <2> process_request: command C_REREAD_CONFIG (0) received 18:57:45.021 [40173678.1] <2> bprd_connect_need_auth: Ignoring reserved port request. 18:57:45.021 [40173678.1] <2> bprd_connect_need_auth: Ignoring local_name NetbackupA. 18:57:45.021 [40173678.1] <2> bprd_connect_need_auth: Ignoring owner_name root. 18:57:45.022 [40173678.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:57:45.024 [40173678.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:57:45.024 [40173678.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 127.0.0.1.24578 TO 127.0.0.1.13720 fd = 6 18:57:45.024 [40173678.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO localhost 127.0.0.1 bprd 18:57:45.024 [40173678.1] <2> logconnections: BPRD CONNECT FROM 127.0.0.1.24578 TO 127.0.0.1.13720 fd = 6 18:57:45.024 [40173678.1] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1112] Ignoring VxSS authentication 2 0x2 18:57:45.025 [45481990.1] <2> bprd: socket fd from accept() is 4 18:57:45.027 [59768998.1] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.24578 TO 127.0.0.1.13720 fd = 4 18:57:45.027 [59768998.1] <2> process_request: setsockopt SO_LINGER on 4 succeeded. 18:57:45.027 [59768998.1] <2> vnet_pcache_init_table: [vnet_private.c:214] starting cache size 200 0xc8 18:57:45.027 [45481990.1] <2> listen_loop: request complete 18:57:45.027 [59768998.1] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:2023] found via getnameinfo OUR_HOST=localhost IPSTR=127.0.0.1 18:57:45.027 [59768998.1] <2> connected_peer: Connection from host localhost, 127.0.0.1, on non-reserved port 24578 18:57:45.028 [59768998.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:57:45.050 [59768998.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:57:45.050 [59768998.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:638] VxSS magic=329199, remote_vxss=1 18:57:45.050 [59768998.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:695] Ignoring VxSS authentication 2 0x2 18:57:45.050 [59768998.1] <2> process_request: command C_ALIVE (1) received 18:57:45.114 [45481990.1] <2> listen_loop: do_schild = 1 18:57:45.114 [45481990.1] <2> childterm: pid=59768998 exit=0, signo=0 core=no 18:57:45.114 [45481990.1] <2> schild: child(ren) not ready to terminate 18:57:45.124 [45481990.1] <2> listen_loop: do_schild = 1 18:57:45.124 [45481990.1] <2> childterm: pid=40173678 exit=0, signo=0 core=no 18:57:45.124 [45481990.1] <2> schild: wait2() ECHILD 18:57:46.713 [45481990.1] <2> bprd: socket fd from accept() is 4 18:57:46.718 [40173680.1] <2> logconnections: BPRD ACCEPT FROM 10.100.1.235.24580 TO 10.100.1.235.13720 fd = 4 18:57:46.718 [40173680.1] <2> process_request: setsockopt SO_LINGER on 4 succeeded. 18:57:46.718 [40173680.1] <2> vnet_pcache_init_table: [vnet_private.c:214] starting cache size 200 0xc8 18:57:46.719 [40173680.1] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:2023] found via getnameinfo OUR_HOST=netbackupa.thermaxdomain.com IPSTR=10.100.1.235 18:57:46.719 [40173680.1] <2> connected_peer: Connection from host netbackupa.thermaxdomain.com, 10.100.1.235, on non-reserved port 24580 18:57:46.719 [45481990.1] <2> listen_loop: request complete 18:57:46.720 [40173680.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:638] VxSS magic=329199, remote_vxss=0 18:57:46.720 [40173680.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:695] Ignoring VxSS authentication 2 0x2 18:57:46.720 [40173680.1] <2> process_request: command C_REREAD_CONFIG (0) received 18:57:46.723 [40173680.1] <2> bprd_connect_need_auth: Ignoring reserved port request. 18:57:46.723 [40173680.1] <2> bprd_connect_need_auth: Ignoring local_name NetbackupA. 18:57:46.723 [40173680.1] <2> bprd_connect_need_auth: Ignoring owner_name root. 18:57:46.724 [40173680.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:57:46.725 [40173680.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:57:46.725 [40173680.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 127.0.0.1.24582 TO 127.0.0.1.13720 fd = 6 18:57:46.725 [40173680.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO localhost 127.0.0.1 bprd 18:57:46.725 [40173680.1] <2> logconnections: BPRD CONNECT FROM 127.0.0.1.24582 TO 127.0.0.1.13720 fd = 6 18:57:46.725 [40173680.1] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1112] Ignoring VxSS authentication 2 0x2 18:57:46.727 [45481990.1] <2> bprd: socket fd from accept() is 4 18:57:46.728 [59769000.1] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.24582 TO 127.0.0.1.13720 fd = 4 18:57:46.728 [59769000.1] <2> process_request: setsockopt SO_LINGER on 4 succeeded. 18:57:46.728 [59769000.1] <2> vnet_pcache_init_table: [vnet_private.c:214] starting cache size 200 0xc8 18:57:46.729 [45481990.1] <2> listen_loop: request complete 18:57:46.729 [59769000.1] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:2023] found via getnameinfo OUR_HOST=localhost IPSTR=127.0.0.1 18:57:46.729 [59769000.1] <2> connected_peer: Connection from host localhost, 127.0.0.1, on non-reserved port 24582 18:57:46.729 [59769000.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:57:46.731 [59769000.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:57:46.731 [59769000.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:638] VxSS magic=329199, remote_vxss=1 18:57:46.731 [59769000.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:695] Ignoring VxSS authentication 2 0x2 18:57:46.731 [59769000.1] <2> process_request: command C_ALIVE (1) received 18:57:46.795 [45481990.1] <2> listen_loop: do_schild = 1 18:57:46.795 [45481990.1] <2> childterm: pid=59769000 exit=0, signo=0 core=no 18:57:46.795 [45481990.1] <2> schild: child(ren) not ready to terminate 18:57:46.805 [45481990.1] <2> listen_loop: do_schild = 1 18:57:46.805 [45481990.1] <2> childterm: pid=40173680 exit=0, signo=0 core=no 18:57:46.805 [45481990.1] <2> schild: wait2() ECHILD 18:59:14.125 [45481990.1] <2> vnet_pbxAcceptSocket: Accepted sock[4] from 10.100.1.146:51226 18:59:14.125 [45481990.1] <2> bprd: socket fd from accept() is 4 18:59:14.131 [40173730.1] <2> logconnections: BPRD ACCEPT FROM 10.100.1.146.51226 TO 10.100.1.235.1556 fd = 4 18:59:14.131 [40173730.1] <2> process_request: setsockopt SO_LINGER on 4 succeeded. 18:59:14.131 [40173730.1] <2> vnet_pcache_init_table: [vnet_private.c:214] starting cache size 200 0xc8 18:59:14.131 [40173730.1] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:2023] found via getnameinfo OUR_HOST=developement IPSTR=10.100.1.146 18:59:14.131 [40173730.1] <2> connected_peer: Connection from host developement, 10.100.1.146, on non-reserved port 51226 18:59:14.132 [45481990.1] <2> listen_loop: request complete 18:59:14.133 [40173730.1] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1691] in failed file cache ERR=8 NAME=tmxnbkmst SVC=NULL 18:59:14.133 [40173730.1] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1360] vnet_cached_getaddrinfo_and_update() failed 6 0x6 18:59:14.133 [40173730.1] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2978] vnet_cached_getaddrinfo() failed STAT=6 RV=8 NAME2=tmxnbkmst 18:59:14.133 [40173730.1] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1637] in failed cache ERR=8 NAME=tmxnbkmst SVC=NULL 18:59:14.133 [40173730.1] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1360] vnet_cached_getaddrinfo_and_update() failed 6 0x6 18:59:14.133 [40173730.1] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2978] vnet_cached_getaddrinfo() failed STAT=6 RV=8 NAME2=tmxnbkmst 18:59:14.133 [40173730.1] <2> db_valid_master_server: developement is not a valid server 18:59:14.134 [40173730.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:638] VxSS magic=329199, remote_vxss=119 18:59:14.134 [40173730.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:695] Ignoring VxSS authentication 2 0x2 18:59:14.134 [40173730.1] <2> process_request: command C_GET_EMM_SERVER_NAME (119) received 18:59:14.134 [40173730.1] <2> process_request: get host info request = 329199 119 18:59:14.134 [40173730.1] <2> process_request: EXIT STATUS 0 18:59:14.183 [45481990.1] <2> listen_loop: do_schild = 1 18:59:14.183 [45481990.1] <2> childterm: pid=40173730 exit=0, signo=0 core=no 18:59:14.183 [45481990.1] <2> schild: wait2() ECHILD 18:59:15.171 [45481990.1] <2> bprd: socket fd from accept() is 4 18:59:15.176 [45875314.1] <2> logconnections: BPRD ACCEPT FROM 10.100.1.235.24613 TO 10.100.1.235.13720 fd = 4 18:59:15.176 [45875314.1] <2> process_request: setsockopt SO_LINGER on 4 succeeded. 18:59:15.176 [45875314.1] <2> vnet_pcache_init_table: [vnet_private.c:214] starting cache size 200 0xc8 18:59:15.176 [45875314.1] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:2023] found via getnameinfo OUR_HOST=netbackupa.thermaxdomain.com IPSTR=10.100.1.235 18:59:15.176 [45875314.1] <2> connected_peer: Connection from host netbackupa.thermaxdomain.com, 10.100.1.235, on non-reserved port 24613 18:59:15.177 [45875314.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:638] VxSS magic=329199, remote_vxss=169 18:59:15.177 [45875314.1] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:695] Ignoring VxSS authentication 2 0x2 18:59:15.177 [45875314.1] <2> process_request: command C_BPRESTORE_7_6 (169) received 18:59:15.177 [45875314.1] <2> get_ccname: determine configured name for netbackupa.thermaxdomain.com 18:59:15.178 [45875314.1] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 18:59:15.178 [45875314.1] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 18:59:15.178 [45875314.1] <2> ConnectionCache::connectAndCache: Acquiring new connection for host NetbackupA, query type 84 18:59:15.178 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:15.178 [45875314.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:15.178 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:15.178 [45875314.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24614 TO 10.100.1.235.13721 fd = 6 18:59:15.180 [45875314.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpdbm 18:59:15.180 [45875314.1] <2> logconnections: BPDBM CONNECT FROM 10.100.1.235.24614 TO 10.100.1.235.13721 fd = 6 18:59:15.180 [45875314.1] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1112] Ignoring VxSS authentication 2 0x2 18:59:15.183 [45481990.1] <2> listen_loop: request complete 18:59:15.203 [45875314.1] <2> db_end: Need to collect reply 18:59:15.209 [45875314.1] <2> get_ccname: configured name is: NetbackupA 18:59:15.209 [45875314.1] <2> process_request: restore request 169, bufr = 329199 169 root system PROD_DB_BOOT NetbackupA tmxdroradb1.thermaxindia.com NetbackupA /usr/openv/netbackup/logs/user_ops/root/logs/jbp-78348570886954915264000000115-Gfo7bP.log /usr/openv/netbackup/logs/user_ops/root/logs/jbp-78348570886954915264000000115-Gfo7bP.chg NONE 0 1570886955 1570392000 1570392000 1570886955 4 1 0 0 1 12 0 0 0 0 0 3 0 1 C C C C C 0 1 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 NONE -1 0 0 0 0 NONE NONE NONE 0 0 0 0 4 NONE 0 18:59:15.209 [45875314.1] <2> process_request: pre vm_fields 0 0 0 0 0 0 0 0 0 0 NONE -1 0 0 0 0 NONE NONE NONE 0 0 0 0 4 NONE 0 18:59:15.209 [45875314.1] <2> process_request: vm_fields 0 NONE -1 0 0 0 0 NONE NONE NONE 0 0 0 0 4 NONE 0 18:59:15.209 [45875314.1] <2> process_request: As rcvd from client: 18:59:15.209 [45875314.1] <2> process_request: browse_clnt: PROD_DB_BOOT 18:59:15.209 [45875314.1] <2> process_request: requesting_clnt: NetbackupA 18:59:15.209 [45875314.1] <2> process_request: destination_clnt: tmxdroradb1.thermaxindia.com 18:59:15.209 [45875314.1] <2> process_request: clnt_bp_conf_name: NetbackupA 18:59:15.209 [45875314.1] <2> process_request: peername: netbackupa.thermaxdomain.com 18:59:15.209 [45875314.1] <2> process_request: ccname: NetbackupA 18:59:15.209 [45875314.1] <2> process_request: keyword = 18:59:15.209 [45875314.1] <2> process_request: restore_format: 3 18:59:15.209 [45875314.1] <2> process_request: true_image: 0 18:59:15.209 [45875314.1] <2> process_request: mpx_restore_possible: 1 18:59:15.209 [45875314.1] <2> process_request: secondary_restore: 0 18:59:15.209 [45875314.1] <2> process_request: priority = -1 18:59:15.209 [45875314.1] <2> process_request: disk_media_server = NONE 18:59:15.209 [45875314.1] <2> process_request: spsrestoreoptions = 0 18:59:15.209 [45875314.1] <2> process_request: vhd_file_name = NONE 18:59:15.209 [45875314.1] <2> process_request: vhd_type = 0 18:59:15.209 [45875314.1] <2> process_request: vhd_disk_size = 0 18:59:15.209 [45875314.1] <2> process_request: vhd_delete_on_failure = 0 18:59:15.209 [45875314.1] <2> process_request: optimized_backup = 0 18:59:15.209 [45875314.1] <2> process_request: Number of parallel restore streams: 1 18:59:15.209 [45875314.1] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.209 [45875314.1] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.209 [45875314.1] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.209 [45875314.1] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.209 [45875314.1] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.209 [45875314.1] <2> get_type_of_client_port: connect_options = 0x01030202 18:59:15.211 [45875314.1] <2> process_request: After get_client_and_host_names: 18:59:15.211 [45875314.1] <2> process_request: destination_clnt: tmxdroradb1.thermaxindia.com 18:59:15.211 [45875314.1] <2> process_request: destination_bp_conf_name: tmxdroradb1.thermaxindia.com 18:59:15.211 [45875314.1] <2> process_request: retval: 0 18:59:15.211 [45875314.1] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.211 [45875314.1] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.211 [45875314.1] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.211 [45875314.1] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.211 [45875314.1] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.214 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:59:15.214 [45875314.1] <2> local_bpcr_connect: bpcr.c.345: connect_opts = 0x01030202 connect_opts2 = 0x01020001 18:59:15.214 [45875314.1] <2> local_bpcr_connect: bpcr.c.352: connect_opts = 0x01020001 18:59:15.214 [45875314.1] <2> local_bpcr_connect: bpcr.c.391: daemon_port_type = 2 18:59:15.214 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:15.214 [45875314.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:15.214 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:15.217 [45875314.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24615 TO 10.100.1.235.13782 fd = 6 18:59:15.217 [45875314.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpcd 18:59:15.218 [45875314.1] <2> logconnections: BPCD CONNECT FROM 10.100.1.235.24615 TO 10.100.1.235.13782 fd = 6 18:59:15.220 [45875314.1] <2> bpcr_get_version_rqst: bpcd version: 07720000 18:59:15.227 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:59:15.227 [45875314.1] <2> local_bpcr_connect: bpcr.c.345: connect_opts = 0x01030202 connect_opts2 = 0x01020001 18:59:15.227 [45875314.1] <2> local_bpcr_connect: bpcr.c.352: connect_opts = 0x01020001 18:59:15.227 [45875314.1] <2> local_bpcr_connect: bpcr.c.391: daemon_port_type = 2 18:59:15.227 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:15.227 [45875314.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:15.227 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:15.230 [45875314.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24617 TO 10.100.1.235.13782 fd = 6 18:59:15.230 [45875314.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpcd 18:59:15.230 [45875314.1] <2> logconnections: BPCD CONNECT FROM 10.100.1.235.24617 TO 10.100.1.235.13782 fd = 6 18:59:15.233 [45875314.1] <2> bpcr_get_version_rqst: bpcd version: 07720000 18:59:15.238 [45875314.1] <2> process_request: cmd = 169 Job id = 45112 18:59:15.238 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:15.239 [45875314.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:15.239 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:15.239 [45875314.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24619 TO 10.100.1.235.13723 fd = 6 18:59:15.239 [45875314.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpjobd 18:59:15.239 [45875314.1] <2> job_connect: SO_KEEPALIVE set on socket 6 for client NetbackupA 18:59:15.239 [45875314.1] <2> logconnections: BPJOBD CONNECT FROM 10.100.1.235.24619 TO 10.100.1.235.13723 fd = 6 18:59:15.239 [45875314.1] <2> job_authenticate_connection: VxSS authentication check for now... 18:59:15.240 [45875314.1] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 18:59:15.240 [45875314.1] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 18:59:15.240 [45875314.1] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1112] Ignoring VxSS authentication 2 0x2 18:59:15.240 [45875314.1] <2> job_connect: Connected to the host NetbackupA contype 53 jobid <45112> socket <6> 18:59:15.240 [45875314.1] <2> job_connect: Connected on port 24619 18:59:15.240 [45875314.1] <2> set_job_details: jobData (45112) 18:59:15.240 [45875314.1] <2> send_structure_data: Index 0 Field m_nJobid Value <45112> 18:59:15.240 [45875314.1] <2> send_structure_data: Index 2 Field m_nParentJobid Value <45112> 18:59:15.240 [45875314.1] <2> send_structure_data: Index 4 Field m_nMainPid Value <45875314> 18:59:15.240 [45875314.1] <2> send_structure_data: Index 7 Field m_nType Value <2> 18:59:15.240 [45875314.1] <2> send_structure_data: Index 14 Field m_szClient Value 18:59:15.240 [45875314.1] <2> send_structure_data: Index 16 Field m_szOwner Value 18:59:15.240 [45875314.1] <2> send_structure_data: Index 17 Field m_szGroup Value 18:59:15.240 [45875314.1] <2> send_structure_data: Index 21 Field m_nState Value <0> 18:59:15.240 [45875314.1] <2> send_structure_data: Index 29 Field m_dtStarted Value <1570886955> 18:59:15.240 [45875314.1] <2> send_structure_data: Index 35 Field m_dtLastInfo Value <1570886955> 18:59:15.242 [45875314.1] <2> set_job_details: jobRunData (45112) 18:59:15.242 [45875314.1] <2> send_structure_data: Index 69 Field m_nKillable Value <1> 18:59:15.242 [45875314.1] <2> set_job_details: Tfile (45112): BEGIN_OPERATION 1570886955 RESTORE 18:59:15.243 [45875314.1] <2> send_job_file: job ID 45112, ftype = 3 msg len = 35, msg = BEGIN_OPERATION 1570886955 RESTORE 18:59:15.243 [45875314.1] <2> process_request: After get_client_and_host_names: 18:59:15.243 [45875314.1] <2> process_request: browse_clnt: PROD_DB_BOOT 18:59:15.243 [45875314.1] <2> process_request: clnt_bp_conf_name: NetbackupA 18:59:15.243 [45875314.1] <2> process_request: retval: 0 18:59:15.243 [45875314.1] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.243 [45875314.1] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.243 [45875314.1] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.243 [45875314.1] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.243 [45875314.1] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 18:59:15.243 [45875314.1] <2> adjust_clientname: adjust_clientname(NetbackupA, netbackupa.thermaxdomain.com, NetbackupA, NetbackupA, 1) 18:59:15.243 [45875314.1] <2> get_ccname: determine configured name for NetbackupA 18:59:15.243 [45875314.1] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 18:59:15.243 [45875314.1] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 18:59:15.243 [45875314.1] <2> ConnectionCache::connectAndCache: Acquiring new connection for host NetbackupA, query type 84 18:59:15.243 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:15.243 [45875314.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:15.243 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:15.246 [45875314.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24620 TO 10.100.1.235.13721 fd = 7 18:59:15.246 [45875314.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpdbm 18:59:15.246 [45875314.1] <2> logconnections: BPDBM CONNECT FROM 10.100.1.235.24620 TO 10.100.1.235.13721 fd = 7 18:59:15.246 [45875314.1] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1112] Ignoring VxSS authentication 2 0x2 18:59:15.293 [45875314.1] <2> db_end: Need to collect reply 18:59:15.299 [45875314.1] <2> get_ccname: configured name is: NetbackupA 18:59:15.299 [45875314.1] <2> adjust_clientname: adjusted clientname = NetbackupA, client_bp_conf_name = NetbackupA 18:59:15.299 [45875314.1] <2> process_request: Before restorefiles 18:59:15.299 [45875314.1] <2> process_request: requesting_clnt_hostname = ccname NetbackupA 18:59:15.299 [45875314.1] <2> restorefiles: mpx_restore_possible = 1 18:59:15.299 [45875314.1] <2> restorefiles: browse_client = PROD_DB_BOOT 18:59:15.299 [45875314.1] <2> restorefiles: requesting_client = NetbackupA 18:59:15.299 [45875314.1] <2> restorefiles: destination_client = tmxdroradb1.thermaxindia.com 18:59:15.299 [45875314.1] <2> restorefiles: requesting_client_hostname = NetbackupA 18:59:15.299 [45875314.1] <2> restorefiles: destination_client_hostname = tmxdroradb1.thermaxindia.com 18:59:15.299 [45875314.1] <2> restorefiles: requesting_user = root 18:59:15.299 [45875314.1] <2> restorefiles: requesting_group = system 18:59:15.299 [45875314.1] <2> restorefiles: progress_file = /usr/openv/netbackup/logs/user_ops/root/logs/jbp-78348570886954915264000000115-Gfo7bP.log 18:59:15.300 [45875314.1] <2> restorefiles: rename_file = /usr/openv/netbackup/logs/user_ops/root/logs/jbp-78348570886954915264000000115-Gfo7bP.chg 18:59:15.300 [45875314.1] <2> restorefiles: policy = NONE 18:59:15.300 [45875314.1] <2> restorefiles: keyword = 18:59:15.300 [45875314.1] <2> restorefiles: sched_type = 12 18:59:15.300 [45875314.1] <2> restorefiles: keep = 1 18:59:15.300 [45875314.1] <2> restorefiles: restore_retries = 0 18:59:15.300 [45875314.1] <2> restorefiles: raw_restore = 0 18:59:15.300 [45875314.1] <2> restorefiles: max_apollo_restore_arg_chars = 9000 18:59:15.300 [45875314.1] <2> restorefiles: rename_linktos = 1 18:59:15.300 [45875314.1] <2> restorefiles: client_type = 0 18:59:15.300 [45875314.1] <2> restorefiles: rest_start_time = 1570886955 18:59:15.300 [45875314.1] <2> restorefiles: cmd_start_date = 1570392000 18:59:15.300 [45875314.1] <2> restorefiles: cmd_end_date = 1570392000 18:59:15.300 [45875314.1] <2> restorefiles: unixtime = 1570886955 18:59:15.300 [45875314.1] <2> restorefiles: timetype = 4 18:59:15.300 [45875314.1] <2> restorefiles: user_interface = 0 18:59:15.300 [45875314.1] <2> restorefiles: monitor_restore_job = 1 18:59:15.300 [45875314.1] <2> restorefiles: restore_jobidstr = 45112 18:59:15.300 [45875314.1] <2> restorefiles: client_locales_available = 1 18:59:15.300 [45875314.1] <2> restorefiles: clnt_lc_messages = C 18:59:15.300 [45875314.1] <2> restorefiles: clnt_lc_time = C 18:59:15.300 [45875314.1] <2> restorefiles: clnt_lc_ctype = C 18:59:15.300 [45875314.1] <2> restorefiles: clnt_lc_collate = C 18:59:15.300 [45875314.1] <2> restorefiles: clnt_lc_numeric = C 18:59:15.300 [45875314.1] <2> restorefiles: cverbose = 0 18:59:15.300 [45875314.1] <2> restorefiles: true_image = 0 18:59:15.300 [45875314.1] <2> restorefiles: restorable_format = 3 18:59:15.300 [45875314.1] <2> restorefiles: bpcd_connect_opts = 16974338 18:59:15.300 [45875314.1] <2> restorefiles: filesystem_only = 0 18:59:15.300 [45875314.1] <2> restorefiles: clntuid = 0 18:59:15.300 [45875314.1] <2> restorefiles: clntgid = 0 18:59:15.300 [45875314.1] <2> restorefiles: acknowledge_status = 0 18:59:15.300 [45875314.1] <2> restorefiles: keep_progress_log_open = 1 18:59:15.300 [45875314.1] <2> restorefiles: clnt_flops = 0 18:59:15.300 [45875314.1] <2> restorefiles: proxy_copy = 0 18:59:15.300 [45875314.1] <2> restorefiles: clnt_v2_1_or_later = 1 18:59:15.300 [45875314.1] <2> restorefiles: commit = 0 18:59:15.300 [45875314.1] <2> restorefiles: dirByteCount = 0 18:59:15.300 [45875314.1] <2> restorefiles: tempDirectory = NULL 18:59:15.300 [45875314.1] <2> restorefiles: mountDatabase = 0 18:59:15.300 [45875314.1] <2> restorefiles: bePathByteCount = 0 18:59:15.300 [45875314.1] <2> restorefiles: beRedirectionPath = NULL 18:59:15.300 [45875314.1] <2> restorefiles: restore_sync_command = 0 18:59:15.300 [45875314.1] <2> restorefiles: use_alt_buf = 0 18:59:15.300 [45875314.1] <2> restorefiles: generate_english_logs = 0 18:59:15.300 [45875314.1] <2> restorefiles: dont_restore_security = 0 18:59:15.300 [45875314.1] <2> restorefiles: redirWebSrvrByteCount = 0 18:59:15.300 [45875314.1] <2> restorefiles: redirWebSrvr = NULL 18:59:15.300 [45875314.1] <2> restorefiles: granular_restore = 0 18:59:15.300 [45875314.1] <2> restorefiles: alt_rest_copy_num = 0 18:59:15.300 [45875314.1] <2> restorefiles: vm_opts = 0 18:59:15.300 [45875314.1] <2> restorefiles: vm_server_string = NONE 18:59:15.300 [45875314.1] <2> restorefiles: priority = -1 18:59:15.300 [45875314.1] <2> restorefiles: disk_media_server = NONE 18:59:15.300 [45875314.1] <2> restorefiles: spsrestoreoptions = 0 18:59:15.300 [45875314.1] <2> restorefiles: vhd_file_name = NONE 18:59:15.300 [45875314.1] <2> restorefiles: vhd_type = 0 18:59:15.300 [45875314.1] <2> restorefiles: vhd_disk_size = 0 18:59:15.300 [45875314.1] <2> restorefiles: vhd_delete_on_failure = 0 18:59:15.300 [45875314.1] <2> restorefiles: vm_db_uri_str_len = 0 18:59:15.300 [45875314.1] <2> restorefiles: optimized_backup = 0 18:59:15.300 [45875314.1] <2> restorefiles: restore_streams = 1 18:59:15.300 [45875314.1] <2> restorefiles: protocol_bufr = NULL 18:59:15.300 [45875314.1] <2> restorefiles: multi_stream_restore_child = 0 18:59:15.300 [45875314.1] <2> open_job_info_file: open restore job params /usr/openv/netbackup/db/jobs/restart/45112.parms for w 18:59:15.301 [45875314.1] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 18:59:15.301 [45875314.1] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 18:59:15.301 [45875314.1] <2> ConnectionCache::connectAndCache: Acquiring new connection for host NetbackupA, query type 76 18:59:15.301 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:15.301 [45875314.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:15.301 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:15.305 [45875314.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24621 TO 10.100.1.235.13721 fd = 8 18:59:15.305 [45875314.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpdbm 18:59:15.305 [45875314.1] <2> logconnections: BPDBM CONNECT FROM 10.100.1.235.24621 TO 10.100.1.235.13721 fd = 8 18:59:15.305 [45875314.1] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1112] Ignoring VxSS authentication 2 0x2 18:59:15.373 [45875314.1] <2> add_image_to_list: Null query->keyword detected. Backup_time = 1570392000 18:59:15.373 [45875314.1] <2> add_image_to_list: Null query->obj_desc detected. Backup_time = 1570392000 18:59:15.383 [45875314.1] <2> get_next_13_tuple: received string: 0 0 0 0 0 0 0 2 0 34 1570392000 1570392000 /orabackup/PROD_tmxoradb1/current/ 18:59:15.383 [45875314.1] <2> find_files: file_path = /orabackup/PROD_tmxoradb1/current/ 18:59:15.383 [45875314.1] <2> find_files: start_date = 1570392000 18:59:15.383 [45875314.1] <2> find_files: end_date = 1570392000 18:59:15.383 [45875314.1] <2> find_files: allow_virtual_browse = 0 18:59:15.383 [45875314.1] <2> find_files: include_no_display = 0 18:59:15.383 [45875314.1] <2> find_files: granular_restore = 0 18:59:15.383 [45875314.1] <2> find_files: sched_type = 12 18:59:15.383 [45875314.1] <2> find_files: client_type = 0 18:59:15.383 [45875314.1] <2> setup_query: database host = NetbackupA 18:59:15.384 [45875314.1] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 18:59:15.384 [45875314.1] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 18:59:15.384 [45875314.1] <2> ConnectionCache::connectAndCache: Acquiring new connection for host NetbackupA, query type 79 18:59:15.384 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:15.384 [45875314.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:15.384 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:15.387 [45875314.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24622 TO 10.100.1.235.13721 fd = 8 18:59:15.387 [45875314.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpdbm 18:59:15.387 [45875314.1] <2> logconnections: BPDBM CONNECT FROM 10.100.1.235.24622 TO 10.100.1.235.13721 fd = 8 18:59:15.387 [45875314.1] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1112] Ignoring VxSS authentication 2 0x2 18:59:15.388 [45875314.1] <2> setup_query: begin db communication 18:59:15.398 [45875314.1] <2> setup_query: criteria sent to db mgr 18:59:15.398 [45875314.1] <2> set_job_details: jobData (45112) 18:59:15.398 [45875314.1] <2> send_structure_data: Index 3 Field m_nActivePid Value <45875314> 18:59:15.398 [45875314.1] <2> send_structure_data: Index 21 Field m_nState Value <1> 18:59:15.398 [45875314.1] <2> send_structure_data: Index 31 Field m_dtActiveStart Value <1570886955> 18:59:15.398 [45875314.1] <2> send_structure_data: Index 35 Field m_dtLastInfo Value <1570886955> 18:59:15.399 [45875314.1] <2> set_job_details: jobRunData (45112) 18:59:15.399 [45875314.1] <2> send_structure_data: Index 67 Field m_nSuspendable Value <1> 18:59:15.399 [45875314.1] <2> send_structure_data: Index 69 Field m_nKillable Value <1> 18:59:15.412 [45875314.1] <2> send_operation_to_monitor: Sending Operation [31] to activity monitor 18:59:15.412 [45875314.1] <2> set_job_details: jobData (45112) 18:59:15.412 [45875314.1] <2> send_structure_data: Index 35 Field m_dtLastInfo Value <1570886955> 18:59:15.412 [45875314.1] <2> set_job_details: jobRunData (45112) 18:59:15.412 [45875314.1] <2> send_structure_data: Index 66 Field m_nOperation Value <31> 18:59:15.580 [45875314.1] <2> set_job_details: Tfile (45112): LOG 1570886955 4 bprd 45875314 Found (8,813) files in (1) images for Restore Job ID 45112.xxx 18:59:15.580 [45875314.1] <2> send_job_file: job ID 45112, ftype = 3 msg len = 95, msg = LOG 1570886955 4 bprd 45875314 Found (8,813) files in (1) images for Restore Job ID 45112.xxx 18:59:15.580 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:59:15.580 [45875314.1] <2> local_bpcr_connect: bpcr.c.345: connect_opts = 0x01030202 connect_opts2 = 0x01020001 18:59:15.580 [45875314.1] <2> local_bpcr_connect: bpcr.c.352: connect_opts = 0x01020001 18:59:15.580 [45875314.1] <2> local_bpcr_connect: bpcr.c.391: daemon_port_type = 2 18:59:15.580 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:15.580 [45875314.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:15.580 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:15.583 [45875314.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24623 TO 10.100.1.235.13782 fd = 9 18:59:15.583 [45875314.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpcd 18:59:15.583 [45875314.1] <2> logconnections: BPCD CONNECT FROM 10.100.1.235.24623 TO 10.100.1.235.13782 fd = 9 18:59:15.588 [45875314.1] <2> bpcr_get_version_rqst: bpcd version: 07720000 18:59:15.588 [45875314.1] <2> add_msgs_to_progress_file: CLIENT_CMD_SOCK from bpcr = 9 18:59:15.588 [45875314.1] <2> add_msgs_to_progress_file: CLIENT_STAT_SOCK from bpcr = 12 18:59:15.814 [45875314.1] <2> set_job_details: jobData (45112) 18:59:15.814 [45875314.1] <2> send_structure_data: Index 35 Field m_dtLastInfo Value <1570886955> 18:59:15.815 [45875314.1] <2> set_job_details: jobRunData (45112) 18:59:15.815 [45875314.1] <2> send_structure_data: Index 66 Field m_nOperation Value <-10> 18:59:15.815 [45875314.1] <2> find_files: Got CONTINUE from bpdbm 18:59:15.815 [45875314.1] <2> get_next_13_tuple: received string: CONTINUE RESTORE 18:59:15.815 [45875314.1] <2> set_job_details: Ffile (45112): /orabackup/PROD_tmxoradb1/current/ 18:59:15.815 [45875314.1] <2> send_job_file: job ID 45112, ftype = 4 msg len = 35, msg = /orabackup/PROD_tmxoradb1/current/ 18:59:15.820 [45875314.1] <2> db_end: Need to collect reply 18:59:15.868 [45875314.1] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:801) 18:59:15.869 [45875314.1] <2> Orb::init: initializing ORB EMMlib_Orb with: bprd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:1015) 18:59:15.870 [45875314.1] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1029) 18:59:15.870 [45875314.1] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1380) 18:59:15.870 [45875314.1] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1389) 18:59:15.871 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:15.879 [45875314.1] <2> get_media_server_version: NBU version of tmxdroradb1.thermaxindia.com is 772000 18:59:15.880 [45875314.1] <2> set_job_details: jobData (45112) 18:59:15.880 [45875314.1] <2> send_structure_data: Index 3 Field m_nActivePid Value <45875314> 18:59:15.880 [45875314.1] <2> send_structure_data: Index 21 Field m_nState Value <1> 18:59:15.880 [45875314.1] <2> send_structure_data: Index 31 Field m_dtActiveStart Value <1570886955> 18:59:15.880 [45875314.1] <2> send_structure_data: Index 35 Field m_dtLastInfo Value <1570886955> 18:59:15.882 [45875314.1] <2> set_job_details: jobRunData (45112) 18:59:15.882 [45875314.1] <2> send_structure_data: Index 67 Field m_nSuspendable Value <1> 18:59:15.882 [45875314.1] <2> send_structure_data: Index 69 Field m_nKillable Value <1> 18:59:15.888 [45875314.1] <2> set_job_details: Tfile (45112): NUMBER_OF_IMAGES_REQUIRED 1570886955 1 18:59:15.888 [45875314.1] <2> send_job_file: job ID 45112, ftype = 3 msg len = 39, msg = NUMBER_OF_IMAGES_REQUIRED 1570886955 1 18:59:15.889 [45875314.1] <2> set_job_details: Tfile (45112): MEDIA_ID_NEEDED 1570886955 J216L5 18:59:15.889 [45875314.1] <2> send_job_file: job ID 45112, ftype = 3 msg len = 34, msg = MEDIA_ID_NEEDED 1570886955 J216L5 18:59:15.889 [45875314.1] <2> set_job_details: Tfile (45112): MEDIA_ID_NEEDED 1570886955 5087L5 18:59:15.889 [45875314.1] <2> send_job_file: job ID 45112, ftype = 3 msg len = 34, msg = MEDIA_ID_NEEDED 1570886955 5087L5 18:59:15.890 [45875314.1] <2> restorefiles: User Identity (Name/Domain/Type): root/NetbackupA/7. Restore IMG id list: PROD_DB_BOOT_1570392000 18:59:15.890 [45875314.1] <2> set_job_details: jobData (45112) 18:59:15.890 [45875314.1] <2> send_structure_data: Index 54 Field m_szUsername Value 18:59:15.890 [45875314.1] <2> send_structure_data: Index 55 Field m_szDomainName Value 18:59:15.890 [45875314.1] <2> send_structure_data: Index 56 Field m_nDomainType Value <7> 18:59:15.890 [45875314.1] <2> send_structure_data: Index 57 Field m_szRestoreBackupIDs Value 18:59:15.898 [45875314.1] <2> start_mpx_main_bprd: server = NetbackupA 18:59:15.898 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:59:15.898 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:59:15.899 [45875314.1] <2> local_bpcr_connect: bpcr.c.345: connect_opts = 0x01020001 connect_opts2 = 0x01020001 18:59:15.899 [45875314.1] <2> local_bpcr_connect: bpcr.c.352: connect_opts = 0x01020001 18:59:15.899 [45875314.1] <2> local_bpcr_connect: bpcr.c.391: daemon_port_type = 2 18:59:15.899 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:15.899 [45875314.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:15.899 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:15.901 [45875314.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24626 TO 10.100.1.235.13782 fd = 11 18:59:15.903 [45875314.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpcd 18:59:15.904 [45875314.1] <2> logconnections: BPCD CONNECT FROM 10.100.1.235.24626 TO 10.100.1.235.13782 fd = 11 18:59:15.907 [45875314.1] <2> bpcr_get_version_rqst: bpcd version: 07720000 18:59:15.907 [45875314.1] <2> start_mpx_main_bprd: MPX_MAIN_CMD_SOCK from bpcr = 11 18:59:15.907 [45875314.1] <2> start_mpx_main_bprd: MPX_MAIN_STAT_SOCK from bpcr = 14 18:59:15.907 [45875314.1] <2> start_mpx_main_bprd: /usr/openv/netbackup/bin/bprd bprd -dontfork -mpxmain 18:59:15.998 [45875314.1] <2> start_mpx_main_bprd: Received BPCD success message 18:59:16.437 [11468970.1] <2> SetMaxDataLimit: maximum data size: current=9223372036854775807 max=9223372036854775807 18:59:16.437 [11468970.1] <4> bprd: VERBOSE changing from 0 to 5 18:59:16.438 [11468970.1] <2> run_mpx_main_bprd: Bprd becoming mpx main. 18:59:16.438 [11468970.1] <2> create_msgQ: 18:59:16.438 [11468970.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:16.438 [11468970.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:16.438 [11468970.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:16.439 [11468970.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24628 TO 10.100.1.235.13723 fd = 7 18:59:16.439 [11468970.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpjobd 18:59:16.439 [11468970.1] <2> job_connect: SO_KEEPALIVE set on socket 7 for client NetbackupA 18:59:16.439 [11468970.1] <2> init_resilient_cache: [vnet_nbrntd.c:865] Initialize resilient cache. 0 0x0 18:59:16.439 [11468970.1] <2> logconnections: BPJOBD CONNECT FROM 10.100.1.235.24628 TO 10.100.1.235.13723 fd = 7 18:59:16.439 [11468970.1] <2> job_authenticate_connection: VxSS authentication check for now... 18:59:16.439 [11468970.1] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 18:59:16.439 [11468970.1] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 18:59:16.439 [11468970.1] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1112] Ignoring VxSS authentication 2 0x2 18:59:16.439 [11468970.1] <2> job_connect: Connected to the host NetbackupA contype 53 jobid <0> socket <7> 18:59:16.439 [11468970.1] <2> job_connect: Connected on port 24628 18:59:16.439 [11468970.1] <2> clean_neededimage_files: 0 neededimage temp files removed 18:59:16.439 [11468970.1] <2> wait_for_new_restore_msg: Wait up to 120 seconds for new restore msg 18:59:16.598 [45875314.1] <2> send_msgQ: dest_pid=1 send_pid=45875314 rqtyp=8 backup_time=0 portnum=0 portipc= stat=0 18:59:16.639 [11468970.1] <2> process_msgQ_msg: Got is mpx main up request from pid 45875314 18:59:16.640 [11468970.1] <2> send_msgQ: dest_pid=45875314 send_pid=1 rqtyp=9 backup_time=0 portnum=0 portipc= stat=0 18:59:16.640 [11468970.1] <2> wait_for_new_restore_msg: Recvd BPRD_IS_MPX_MAIN_UP, reset timer to 1200 18:59:16.698 [45875314.1] <2> restorefiles: Got mpx main is up from MPX main bprd 18:59:16.698 [45875314.1] <2> write_needed_images_to_file: writing needed images to /usr/openv/netbackup/bin/bprd.d/neededimage.45875314 18:59:16.699 [45875314.1] <2> write_needed_images_to_file: need image PROD_DB_BOOT_1570392000 18:59:16.699 [45875314.1] <2> set_job_details: jobRunData (45112) 18:59:16.699 [45875314.1] <2> send_structure_data: Index 63 Field m_nCompletion Value <0> 18:59:16.699 [45875314.1] <2> send_structure_data: Index 65 Field m_nEstFiles Value <8813> 18:59:16.700 [45875314.1] <2> send_msgQ: dest_pid=1 send_pid=45875314 rqtyp=2 backup_time=0 portnum=0 portipc= stat=0 18:59:16.740 [11468970.1] <2> process_msgQ_msg: Got new restore request from pid 45875314 18:59:16.740 [11468970.1] <2> read_needed_images_from_file: reading /usr/openv/netbackup/bin/bprd.d/neededimage.45875314 for work to do 18:59:16.740 [11468970.1] <2> read_image_info_from_file: frag 1 file 5 of J216L5 has blocks 0 to 2097151999 for PROD_DB_BOOT_1570392000 18:59:16.740 [11468970.1] <2> read_image_info_from_file: frag 1 file 5 of J216L5 is the first need fragment for PROD_DB_BOOT_1570392000 18:59:16.740 [11468970.1] <2> add_to_tape_list: J216L5 tmxdroradb1.thermaxindia.com 18:59:16.740 [11468970.1] <2> read_image_info_from_file: frag 2 file 6 of J216L5 has blocks 2097152000 to 2704527487 for PROD_DB_BOOT_1570392000 18:59:16.740 [11468970.1] <2> read_image_info_from_file: frag 3 file 1 of 5087L5 has blocks 2704527488 to 3987886591 for PROD_DB_BOOT_1570392000 18:59:16.740 [11468970.1] <2> add_to_tape_list: 5087L5 tmxdroradb1.thermaxindia.com 18:59:16.740 [11468970.1] <2> open_checkpoint_file: open restore job ckpts /usr/openv/netbackup/db/jobs/restart/45112.ckpts for a+ 18:59:16.755 [11468970.1] <2> combine_into_mpx_group: J216L5 added to new MPX group 18:59:16.755 [11468970.1] <2> combine_into_mpx_group: 5087L5 added to end of MPX group containing: 18:59:16.755 [11468970.1] <2> combine_into_mpx_group: J216L5 on server tmxdroradb1.thermaxindia.com 18:59:16.755 [11468970.1] <2> group_tapes_in_frag_lists: Setting brm_pid = 0 for PROD_DB_BOOT_1570392000 restfiles pid 45875314 18:59:16.755 [11468970.1] <2> wait_for_new_restore_msg: Done waiting for new restore msg 18:59:16.755 [11468970.1] <2> run_restores: Non-MPX image restore 18:59:16.755 [11468970.1] <2> start_indep_grp: (pid 45875314) p_image->backup_id = PROD_DB_BOOT_1570392000 18:59:16.755 [11468970.1] <2> start_indep_grp: (pid 45875314) oldest_time = 0x7fffffff 18:59:16.755 [11468970.1] <2> block_incr_dependency: (PROD_DB_BOOT_1570392000) not dependent on unfinished prev block incr, start 18:59:16.755 [11468970.1] <2> start_indep_grp: (pid 45875314) NO other_restore_dependencies 18:59:16.769 [11468970.1] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:801) 18:59:16.770 [11468970.1] <2> Orb::init: initializing ORB EMMlib_Orb with: bprd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:1015) 18:59:16.771 [11468970.1] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1029) 18:59:16.771 [11468970.1] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1380) 18:59:16.771 [11468970.1] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1389) 18:59:16.771 [11468970.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:16.779 [11468970.1] <2> get_media_server_version: NBU version of tmxdroradb1.thermaxindia.com is 772000 18:59:16.779 [11468970.1] <2> start_mpx_group: media server tmxdroradb1.thermaxindia.com version from db = 0x000bc7a0 18:59:16.779 [11468970.1] <2> start_mpx_group: /usr/openv/netbackup/bin/bpbrm bpbrm -restore -mpx_restore -S NetbackupA -mt 2 -to 0 -mud 180 -mediasvr tmxdroradb1.thermaxindia.com -masterversion 770000 18:59:16.823 [11468970.1] <2> local_bpcr_connect: bpcr.c.345: connect_opts = 0x01000100 connect_opts2 = 0x01000100 18:59:16.823 [11468970.1] <2> local_bpcr_connect: bpcr.c.352: connect_opts = 0x01000100 18:59:16.823 [11468970.1] <2> local_bpcr_connect: bpcr.c.391: daemon_port_type = 0 18:59:16.823 [11468970.1] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1691] in failed file cache ERR=8 NAME=CHNFILESRV.Thermaxdomain.com SVC=NULL 18:59:16.823 [11468970.1] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1360] vnet_cached_getaddrinfo_and_update() failed 6 0x6 18:59:16.823 [11468970.1] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8938] Ignoring network spec. vnet_cached_getaddrinfo() failed STAT=6 SPEC=CHNFILESRV.Thermaxdomain.com 18:59:16.824 [11468970.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:16.824 [11468970.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4897] using interface ANY 18:59:16.824 [11468970.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:16.824 [11468970.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4897] using interface ANY 18:59:16.824 [11468970.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:16.824 [11468970.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4897] using interface ANY 18:59:16.824 [11468970.1] <2> async_connect: [vnet_connect.c:1570] connect in progress 1 0x1 18:59:16.874 [11468970.1] <2> vnet_pbxConnect: pbxConnectEx Succeeded 18:59:16.874 [11468970.1] <2> do_pbx_service: [vnet_connect.c:2235] via PBX bpcd CONNECT FROM 10.100.1.235.24630 TO 10.111.1.196.1556 fd = 11 18:59:16.874 [11468970.1] <2> async_connect: [vnet_connect.c:1762] connect async CONNECT FROM 10.100.1.235.24630 TO 10.111.1.196.1556 fd = 11 18:59:16.874 [11468970.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO tmxdroradb1.thermaxindia.com 10.111.1.196 bpcd VIA pbx 18:59:16.874 [11468970.1] <2> logconnections: BPCD CONNECT FROM 10.100.1.235.24630 TO 10.111.1.196.1556 fd = 11 18:59:16.874 [11468970.1] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1637] in failed cache ERR=8 NAME=CHNFILESRV.Thermaxdomain.com SVC=NULL 18:59:16.874 [11468970.1] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1360] vnet_cached_getaddrinfo_and_update() failed 6 0x6 18:59:16.874 [11468970.1] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8938] Ignoring network spec. vnet_cached_getaddrinfo() failed STAT=6 SPEC=CHNFILESRV.Thermaxdomain.com 18:59:16.906 [11468970.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:16.906 [11468970.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4897] using interface ANY 18:59:16.906 [11468970.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:16.906 [11468970.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4897] using interface ANY 18:59:16.906 [11468970.1] <2> async_connect: [vnet_connect.c:1570] connect in progress 1 0x1 18:59:16.955 [11468970.1] <2> vnet_pbxConnect: pbxConnectEx Succeeded 18:59:16.979 [11468970.1] <8> do_pbx_service: [vnet_connect.c:2227] via PBX VNETD CONNECT FROM 10.100.1.235.24631 TO 10.111.1.196.1556 fd = 12 18:59:16.980 [11468970.1] <2> async_connect: [vnet_connect.c:1762] connect async CONNECT FROM 10.100.1.235.24631 TO 10.111.1.196.1556 fd = 12 18:59:16.980 [11468970.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.111.1.196 10.111.1.196 vnetd VIA pbx 18:59:16.980 [11468970.1] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:440] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 18:59:17.030 [11468970.1] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:457] ipc_string /usr/openv/var/tmp/vnet-11670570886507386954000000388-tXe7ge 18:59:17.055 [11468970.1] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:468] hash_str1 6e874b91c2ea841ff802d7a5f6c88d7b 18:59:17.129 [11468970.1] <2> bpcr_get_version_rqst: bpcd version: 07720000 18:59:17.129 [11468970.1] <2> start_mpx_group: bpbrm stdout from bpcr = 11 18:59:17.129 [11468970.1] <2> start_mpx_group: bpbrm stderr from bpcr = 12 18:59:17.685 [11468970.1] <2> read_bpbrm_msg: (pid 0) PID of bpbrm = 12845086 18:59:17.685 [11468970.1] <2> start_mpx_group: bpbrm pid = 12845086 18:59:17.685 [11468970.1] <2> start_indep_grp: (pid 45875314) started mpx group with bpbrm pid 12845086 18:59:17.685 [11468970.1] <2> set_mpx_group_image_brm_pid: Setting brm_pid = 12845086 for PROD_DB_BOOT_1570392000 restfiles pid 45875314 18:59:17.685 [11468970.1] <2> next_image_using_first_blks: first is backup_id = PROD_DB_BOOT_1570392000, tape = J216L5, fileno = 5, firstblk = 3 18:59:17.685 [11468970.1] <2> next_image_using_first_blks: returning backup_id = PROD_DB_BOOT_1570392000, tape = J216L5, fileno = 5, firstblk = 3 18:59:17.685 [11468970.1] <2> start_image_restore: Sending listen msg to restfiles 45875314 for image with backup time 1570392000 18:59:17.685 [11468970.1] <2> send_msgQ: dest_pid=45875314 send_pid=1 rqtyp=5 backup_time=1570392000 portnum=0 portipc= stat=0 18:59:17.700 [45875314.1] <2> restorefiles: Received listen message for backup_time 1570392000 backup_id PROD_DB_BOOT_1570392000 18:59:17.701 [45875314.1] <2> restorefiles: file_list_ipc: /usr/openv/var/tmp/vnet-75314570886957700590000000023-X9Laax 18:59:17.702 [45875314.1] <2> restorefiles: File list port = 0, socket = 11 for backup_id PROD_DB_BOOT_1570392000 18:59:17.702 [45875314.1] <2> send_msgQ: dest_pid=1 send_pid=45875314 rqtyp=6 backup_time=1570392000 portnum=0 portipc=/usr/openv/var/tmp/vnet-75314570886957700590000000023-X9Laax stat=0 18:59:17.702 [45875314.1] <2> set_job_details: Tfile (45112): RESTORING_FROM_IMAGE 1570886957 PROD_DB_BOOT_1570392000 18:59:17.702 [45875314.1] <2> send_job_file: job ID 45112, ftype = 3 msg len = 56, msg = RESTORING_FROM_IMAGE 1570886957 PROD_DB_BOOT_1570392000 18:59:17.702 [45875314.1] <2> set_job_details: jobRunData (45112) 18:59:17.702 [45875314.1] <2> send_structure_data: Index 62 Field m_szPathname Value 18:59:17.702 [45875314.1] <2> set_job_details: jobData (45112) 18:59:17.702 [45875314.1] <2> send_structure_data: Index 24 Field m_szSrcMedia_server Value 18:59:17.702 [45875314.1] <2> send_structure_data: Index 35 Field m_dtLastInfo Value <1570886957> 18:59:17.785 [11468970.1] <2> process_msgQ_msg: Setting portnum = 0 for PROD_DB_BOOT_1570392000 18:59:17.785 [11468970.1] <2> start_image_restore: restfiles pid 45875314 brm pid 12845086 PROD_DB_BOOT_1570392000 18:59:17.785 [11468970.1] <2> start_image_restore: start_msg = START RESTORE -k 1 -c tmxdroradb1.thermaxindia.com -hostname tmxdroradb1.thermaxindia.com -rclnt NetbackupA -rclnthostname NetbackupA -browse_clnt PROD_DB_BOOT -ru root -firstblk 3 -cl Thermax_Daily_Tmaxoradb1RMAN -bt 1570392000 -st 0 -secure 1 -b PROD_DB_BOOT_1570392000 -cn 1 -rst 1570886955 -ct 0 -flport 0 -flipc /usr/openv/var/tmp/vnet-75314570886957700590000000023-X9Laax -restoreid 45112.001 -jobid 45112 -job_total 1 -m J216L5 -rg system -L /usr/openv/netbackup/logs/user_ops/root/logs/jbp-78348570886954915264000000115-Gfo7bP.log -R /usr/openv/netbackup/logs/user_ops/root/logs/jbp-78348570886954915264000000115-Gfo7bP.chg -rename_linktos -clnt_lc_messages C -clnt_lc_time C -clnt_lc_ctype C -clnt_lc_collate C -clnt_lc_numeric C 18:59:17.785 [11468970.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:59:17.785 [11468970.1] <2> local_bpcr_connect: bpcr.c.345: connect_opts = 0x00000000 connect_opts2 = 0x01020001 18:59:17.785 [11468970.1] <2> local_bpcr_connect: bpcr.c.352: connect_opts = 0x01020001 18:59:17.785 [11468970.1] <2> local_bpcr_connect: bpcr.c.391: daemon_port_type = 2 18:59:17.786 [11468970.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:17.786 [11468970.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:17.786 [11468970.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:17.788 [11468970.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24633 TO 10.100.1.235.13782 fd = 13 18:59:17.788 [11468970.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpcd 18:59:17.788 [11468970.1] <2> logconnections: BPCD CONNECT FROM 10.100.1.235.24633 TO 10.100.1.235.13782 fd = 13 18:59:17.813 [11468970.1] <2> bpcr_get_version_rqst: bpcd version: 07720000 18:59:17.813 [11468970.1] <2> add_msgs_to_progress_file: CLIENT_CMD_SOCK from bpcr = 13 18:59:17.813 [11468970.1] <2> add_msgs_to_progress_file: CLIENT_STAT_SOCK from bpcr = 15 18:59:17.817 [11468970.1] <2> start_indep_grp: (pid 45875314) p_image = NULL 18:59:17.817 [11468970.1] <2> start_indep_grp: (pid 45875314) oldest_time = 1570392000 18:59:17.817 [11468970.1] <2> start_indep_grp: active_mpx_groups = 1 18:59:17.817 [11468970.1] <2> start_indep_grp: p_active_restore_for_first_grp = NULL 18:59:17.817 [11468970.1] <2> start_indep_grp: p_first_mpx_group_for_restore = NULL 18:59:18.118 [11468970.1] <2> read_bpbrm_msg: (pid 12845086) ERROR 11 18:59:18.118 [11468970.1] <2> handle_image_status: PROD_DB_BOOT_1570392000 restfiles pid 45875314 bpbrm pid 12845086 status = 11 18:59:18.118 [11468970.1] <2> send_msgQ: dest_pid=45875314 send_pid=1 rqtyp=3 backup_time=1570392000 portnum=0 portipc= stat=11 18:59:18.118 [11468970.1] <2> send_msgQ: dest_pid=45875314 send_pid=1 rqtyp=4 backup_time=0 portnum=0 portipc= stat=0 18:59:18.118 [11468970.1] <2> remove_completed_restore: unlink record with restfiles pid 45875314 18:59:18.118 [11468970.1] <2> remove_completed_restore: no more active restores 18:59:18.118 [11468970.1] <2> done_with_bpbrm: brm pid 12845086 p_first_active_restore is NULL 18:59:18.118 [11468970.1] <2> done_with_bpbrm: bpbrm pid 12845086 has no more images to process 18:59:18.118 [11468970.1] <2> send_bpbrm_msg: (pid 12845086) TERMINATE 18:59:18.118 [11468970.1] <2> start_indep_grp: active_mpx_groups = -1 18:59:18.118 [11468970.1] <2> start_indep_grp: p_active_restore_for_first_grp = NULL 18:59:18.118 [11468970.1] <2> start_indep_grp: p_first_mpx_group_for_restore = NULL 18:59:18.118 [11468970.1] <2> job_monitoring_exex: ACK disconnect 18:59:18.118 [11468970.1] <2> job_disconnect: Disconnected 18:59:18.118 [11468970.1] <2> wait_for_new_restore_msg: Wait up to 120 seconds for new restore msg 18:59:18.217 [45875314.1] <2> restorefiles: Done with port 0 backup_id PROD_DB_BOOT_1570392000. Status = 11 18:59:18.217 [45875314.1] <2> set_job_details: Tfile (45112): RESTORED_FROM_IMAGE 1570886958 PROD_DB_BOOT_1570392000 11 18:59:18.217 [45875314.1] <2> send_job_file: job ID 45112, ftype = 3 msg len = 58, msg = RESTORED_FROM_IMAGE 1570886958 PROD_DB_BOOT_1570392000 11 18:59:18.247 [45875314.1] <2> restorefiles: Received finished from MPX main bprd. Stat = 0 18:59:18.247 [45875314.1] <2> set_exit_status: entered; status = 0 18:59:18.247 [45875314.1] <2> set_exit_status: client_type = -1 18:59:18.247 [45875314.1] <2> mail_msg_and_set_exit_status: Attempting to send mail to root on NetbackupA 18:59:18.247 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:59:18.247 [45875314.1] <2> local_bpcr_connect: bpcr.c.345: connect_opts = 0x01030202 connect_opts2 = 0x01020001 18:59:18.247 [45875314.1] <2> local_bpcr_connect: bpcr.c.352: connect_opts = 0x01020001 18:59:18.247 [45875314.1] <2> local_bpcr_connect: bpcr.c.391: daemon_port_type = 2 18:59:18.247 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:18.247 [45875314.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:18.247 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:18.251 [45875314.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24635 TO 10.100.1.235.13782 fd = 9 18:59:18.251 [45875314.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpcd 18:59:18.251 [45875314.1] <2> logconnections: BPCD CONNECT FROM 10.100.1.235.24635 TO 10.100.1.235.13782 fd = 9 18:59:18.254 [45875314.1] <2> bpcr_get_version_rqst: bpcd version: 07720000 18:59:18.254 [45875314.1] <2> mail_msg_and_set_exit_status: CLIENT_CMD_SOCK from bpcr = 9 18:59:18.254 [45875314.1] <2> mail_msg_and_set_exit_status: CLIENT_STAT_SOCK from bpcr = 12 18:59:18.254 [45875314.1] <2> bpcr_get_version_rqst: bpcd version: 07720000 18:59:18.255 [45875314.1] <2> bpcr_get_version_rqst: bpcd version: 07720000 18:59:18.266 [45875314.1] <2> bpcr_get_version_rqst: bpcd version: 07720000 18:59:18.276 [45875314.1] <2> OpenSendmailPipe: /usr/lib/sendmail -t >/dev/null 2>/dev/null 18:59:18.306 [45875314.1] <2> mail_msg_and_set_exit_status: RESTORE EXIT STATUS = 2850 18:59:18.306 [45875314.1] <2> set_job_details: jobData (45112) 18:59:18.306 [45875314.1] <2> send_structure_data: Index 9 Field m_nStatus Value <2850> 18:59:18.306 [45875314.1] <2> send_structure_data: Index 21 Field m_nState Value <5> 18:59:18.306 [45875314.1] <2> send_structure_data: Index 35 Field m_dtLastInfo Value <1570886958> 18:59:18.309 [45875314.1] <2> set_job_details: jobRunData (45112) 18:59:18.309 [45875314.1] <2> send_structure_data: Index 68 Field m_nResumable Value <1> 18:59:18.309 [45875314.1] <2> set_job_details: Tfile (45112): LOG 1570886958 8 bprd 45875314 Restore must be resumed prior to first image expiration on Sun Dec 8 01:30:00 2019 18:59:18.309 [45875314.1] <2> send_job_file: job ID 45112, ftype = 3 msg len = 115, msg = LOG 1570886958 8 bprd 45875314 Restore must be resumed prior to first image expiration on Sun Dec 8 01:30:00 2019 18:59:18.309 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:59:18.309 [45875314.1] <2> local_bpcr_connect: bpcr.c.345: connect_opts = 0x01030202 connect_opts2 = 0x01020001 18:59:18.309 [45875314.1] <2> local_bpcr_connect: bpcr.c.352: connect_opts = 0x01020001 18:59:18.309 [45875314.1] <2> local_bpcr_connect: bpcr.c.391: daemon_port_type = 2 18:59:18.310 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:18.310 [45875314.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:18.310 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:18.312 [45875314.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24638 TO 10.100.1.235.13782 fd = 9 18:59:18.312 [45875314.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpcd 18:59:18.313 [45875314.1] <2> logconnections: BPCD CONNECT FROM 10.100.1.235.24638 TO 10.100.1.235.13782 fd = 9 18:59:18.315 [45875314.1] <2> bpcr_get_version_rqst: bpcd version: 07720000 18:59:18.490 [45875314.1] <2> set_job_details: Tfile (45112): END_OPERATION 1570886958 18:59:18.490 [45875314.1] <2> send_job_file: job ID 45112, ftype = 3 msg len = 25, msg = END_OPERATION 1570886958 18:59:18.493 [45875314.1] <2> job_monitoring_exex: ACK disconnect 18:59:18.493 [45875314.1] <2> job_disconnect: Disconnected 18:59:18.493 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=localhost NAME2=NetbackupA 18:59:18.493 [45875314.1] <2> local_bpcr_connect: bpcr.c.345: connect_opts = 0x01030202 connect_opts2 = 0x01020001 18:59:18.493 [45875314.1] <2> local_bpcr_connect: bpcr.c.352: connect_opts = 0x01020001 18:59:18.493 [45875314.1] <2> local_bpcr_connect: bpcr.c.391: daemon_port_type = 2 18:59:18.493 [45875314.1] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3015] matched as locals NAME1=NetbackupA NAME2=localhost 18:59:18.493 [45875314.1] <2> vnet_sortaddrs: [vnet_addrinfo.c:3966] sorted addrs: 1 0x1 18:59:18.493 [45875314.1] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4864] Local [strong] check, using interface ANY 18:59:18.496 [45875314.1] <2> async_connect: [vnet_connect.c:1526] connect immediate CONNECT FROM 10.100.1.235.24640 TO 10.100.1.235.13782 fd = 6 18:59:18.496 [45875314.1] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO NetbackupA 10.100.1.235 bpcd 18:59:18.496 [45875314.1] <2> logconnections: BPCD CONNECT FROM 10.100.1.235.24640 TO 10.100.1.235.13782 fd = 6 18:59:18.500 [45875314.1] <2> bpcr_get_version_rqst: bpcd version: 07720000 18:59:18.500 [45875314.1] <2> add_msgs_to_progress_file: CLIENT_CMD_SOCK from bpcr = 6 18:59:18.500 [45875314.1] <2> add_msgs_to_progress_file: CLIENT_STAT_SOCK from bpcr = 11 18:59:18.505 [45875314.1] <2> terminate_restore: EXIT STATUS 2850 18:59:18.505 [45875314.1] <2> bprd: /usr/openv/netbackup/bin/userreq_notify restore tmxdroradb1.thermaxindia.com root 18:59:18.599 [45481990.1] <2> listen_loop: do_schild = 1 18:59:18.599 [45481990.1] <2> childterm: pid=45875314 exit=34, signo=0 core=no 18:59:18.599 [45481990.1] <2> schild: wait2() ECHILD