cancel
Showing results for 
Search instead for 
Did you mean: 

BR1380E Unexpected message sent by backup utility

Wilhelm
Level 3

Good day

 

I am trying to restore Archive logs using SAP Brtools and the Netbackup backint interface.  I receive the following error:

 

BR1380E Unexpected message '#BACKUP NS1307343703 /oracle/MEP/saparch/archMEP.log' sent by backup utility
 

I am on oracle 10, Solaris 10, NBU 7.1.

 

Any idea what this error could be ?

 

Regards

Wilhelm

3 REPLIES 3

Nicolai
Moderator
Moderator
Partner    VIP   

in /usr/openv/netbackup/logs/backint.

Makre sure the directory has 777 permissions and add "VERBOSE = 5" in bp.conf, then retry the operation.

Marianne
Moderator
Moderator
Partner    VIP    Accredited Certified

Also ensure that you have bprd log on the master. (If not, create log dir and restart NBU).

Restore request will be logged in bprd log. See if connection is received from client and how request is interpreted by the master.

Wilhelm
Level 3

Hi

 

I do not get any error code 16 in the bprd log. This is what I get from BPRD log:

 

11:17:00.398 [4516.6256] <2> logconnections: BPRD ACCEPT FROM 10.100.20.11.34873 TO 10.100.8.60.1556 fd = 520

11:17:00.398 [4516.6256] <2> process_request: setsockopt SO_LINGER on 520 succeeded.

11:17:00.398 [4516.6256] <2> connected_peer: Connection from host PERP1H03RES, 10.100.20.11, on non-reserved port 34873

11:17:00.398 [4516.6256] <2> vnet_same_host: ../../libvlibs/vnet_addrinfo.c.2912: 0: name2 is NULL: 0 0x00000000

11:17:00.398 [4516.6256] <2> vnet_same_host: ../../libvlibs/vnet_addrinfo.c.2912: 0: name2 is NULL: 0 0x00000000

11:17:00.398 [4516.6256] <2> db_valid_master_server: PERP1H03RES is not a valid server

11:17:00.398 [4516.6256] <2> process_request: command C_BPLIST_4_5 (82) received

11:17:00.398 [4516.6256] <2> process_request: list request = 329199 82 oramep dba PCDB1H03RNB perp1h03res PCDB1H03RNB PERP1H03RNB-LOGS 11 sap_oracle* 3 999 0 1308129469 4 4 1 1 1 0 17 101 102 4 0 C C C C C 0 2 0 0 0

11:17:00.414 [4516.6256] <2> get_type_of_client_list_restore: db_getCLIENT() failed: no entity was found (227)

11:17:00.414 [4516.6256] <2> get_type_of_client_list_restore: db_getCLIENT_by_hostname() failed: no entity was found (227)

11:17:00.414 [4516.6256] <2> get_type_of_client_free_browse: db_getCLIENT() failed: no entity was found (227)

11:17:00.414 [4516.6256] <2> get_type_of_client_free_browse: db_getCLIENT_by_hostname() failed: no entity was found (227)

11:17:00.414 [4516.6256] <2> db_valid_client: -all clients valid-

11:17:00.570 [4516.6256] <2> fileslist:    sockfd = 520

11:17:00.570 [4516.6256] <2> fileslist:    owner = oramep

11:17:00.570 [4516.6256] <2> fileslist:    group = dba

11:17:00.570 [4516.6256] <2> fileslist:    client = PCDB1H03RNB

11:17:00.570 [4516.6256] <2> fileslist:    sched_type = 12

11:17:00.570 [4516.6256] <2> fileslist:    starttime = 0

11:17:00.570 [4516.6256] <2> fileslist:    endtime = 1308129469

11:17:00.570 [4516.6256] <2> fileslist:    filepath = /*

11:17:00.570 [4516.6256] <2> fileslist:    recursion_level = 999

11:17:00.570 [4516.6256] <2> fileslist:    timetype = 4

11:17:00.570 [4516.6256] <2> fileslist:    rqtimetype = 4

11:17:00.570 [4516.6256] <2> fileslist:    user_interface = 1

11:17:00.570 [4516.6256] <2> fileslist:    full_listing = 1

11:17:00.570 [4516.6256] <2> fileslist:    pc1_listing = 0

11:17:00.570 [4516.6256] <2> fileslist:    input_long_listing = 0x1

11:17:00.570 [4516.6256] <2> fileslist:    list_seconds = 0

11:17:00.570 [4516.6256] <2> fileslist:    list_policy = 0

11:17:00.570 [4516.6256] <2> fileslist:    long_listing = 1

11:17:00.570 [4516.6256] <2> fileslist:    raw_partition_search = 0

11:17:00.570 [4516.6256] <2> fileslist:    client_type = 17

11:17:00.570 [4516.6256] <2> fileslist:    policy = PERP1H03RNB-LOGS

11:17:00.570 [4516.6256] <2> fileslist:    keyword = sap_oracle*

11:17:00.570 [4516.6256] <2> fileslist:    bplist_format = 4

11:17:00.570 [4516.6256] <2> fileslist:    true_image = 0

11:17:00.570 [4516.6256] <2> fileslist:    list_lc_messages = C

11:17:00.570 [4516.6256] <2> fileslist:    list_lc_time = C

11:17:00.570 [4516.6256] <2> fileslist:    list_lc_ctype = C

11:17:00.570 [4516.6256] <2> fileslist:    list_lc_collate = C

11:17:00.570 [4516.6256] <2> fileslist:    list_lc_numeric = C

11:17:00.570 [4516.6256] <2> fileslist:    directories_only = 0

11:17:00.570 [4516.6256] <2> fileslist:    include_extra_info = 2

11:17:00.570 [4516.6256] <2> fileslist:    client_uid = 101

11:17:00.570 [4516.6256] <2> fileslist:    client_gid = 102

11:17:00.570 [4516.6256] <2> fileslist:    ignore_case = 0

11:17:00.570 [4516.6256] <2> fileslist:    list_seconds_pre_4_5 = 0

11:17:00.570 [4516.6256] <2> fileslist:    client_flops = 0

11:17:00.570 [4516.6256] <2> fileslist:    query.fl_peername = PCDB1H03RNB

11:17:00.570 [4516.6256] <2> fileslist: 03rnb-prd-bck01.za.ds.naspers.com

11:17:00.570 [4516.6256] <2> ConnectionCache::connectAndCache: Acquiring new connection for host 03rnb-prd-bck01.za.ds.naspers.com, query type 79

11:17:00.570 [4516.6256] <2> logconnections: BPDBM CONNECT FROM 10.100.8.60.56526 TO 10.100.8.60.13721 fd = 756

11:17:00.616 [4516.6256] <2> fileslist: begin db communication

11:17:00.616 [4516.6256] <2> fileslist: criteria sent to db mgr

11:17:01.646 [4516.6256] <2> process_request: EXIT STATUS 0

11:17:01.677 [4248.4252] <2> vnet_pbxAcceptSocket: Accepted sock[520] from 10.100.20.11:34874

11:17:01.677 [4248.4252] <2> bprd: socket fd from accept() is 520

11:17:01.677 [4248.4252] <2> listen_loop: request complete

11:17:01.724 [8448.8036] <2> logconnections: BPRD ACCEPT FROM 10.100.20.11.34874 TO 10.100.8.60.1556 fd = 520

11:17:01.724 [8448.8036] <2> process_request: setsockopt SO_LINGER on 520 succeeded.

11:17:01.724 [8448.8036] <2> connected_peer: Connection from host PERP1H03RES, 10.100.20.11, on non-reserved port 34874

11:17:01.724 [8448.8036] <2> vnet_same_host: ../../libvlibs/vnet_addrinfo.c.2912: 0: name2 is NULL: 0 0x00000000

11:17:01.724 [8448.8036] <2> vnet_same_host: ../../libvlibs/vnet_addrinfo.c.2912: 0: name2 is NULL: 0 0x00000000

11:17:01.724 [8448.8036] <2> db_valid_master_server: PERP1H03RES is not a valid server

11:17:01.724 [8448.8036] <2> process_request: command C_BPLIST_4_5 (82) received

11:17:01.724 [8448.8036] <2> process_request: list request = 329199 82 oramep dba PCDB1H03RNB perp1h03res PCDB1H03RNB NONE 0  3 999 1 1308129469 4 4 1 0 1 0 17 101 102 0 0 C C C C C 0 0 0 0 0

11:17:01.724 [8448.8036] <2> get_type_of_client_list_restore: db_getCLIENT() failed: no entity was found (227)

11:17:01.724 [8448.8036] <2> get_type_of_client_list_restore: db_getCLIENT_by_hostname() failed: no entity was found (227)

11:17:01.724 [8448.8036] <2> get_type_of_client_free_browse: db_getCLIENT() failed: no entity was found (227)

11:17:01.724 [8448.8036] <2> get_type_of_client_free_browse: db_getCLIENT_by_hostname() failed: no entity was found (227)

11:17:01.724 [8448.8036] <2> db_valid_client: -all clients valid-

11:17:01.771 [4516.6256] <2> bprd: G:\Veritas\NetBackup\bin\userreq_notify.cmd list PCDB1H03RNB oramep

11:17:01.896 [8448.8036] <2> fileslist:    sockfd = 520

11:17:01.896 [8448.8036] <2> fileslist:    owner = oramep

11:17:01.896 [8448.8036] <2> fileslist:    group = dba

11:17:01.896 [8448.8036] <2> fileslist:    client = PCDB1H03RNB

11:17:01.896 [8448.8036] <2> fileslist:    sched_type = 12

11:17:01.896 [8448.8036] <2> fileslist:    starttime = 1

11:17:01.896 [8448.8036] <2> fileslist:    endtime = 1308129469

11:17:01.896 [8448.8036] <2> fileslist:    filepath = /

11:17:01.896 [8448.8036] <2> fileslist:    recursion_level = 999

11:17:01.896 [8448.8036] <2> fileslist:    timetype = 4

11:17:01.896 [8448.8036] <2> fileslist:    rqtimetype = 4

11:17:01.896 [8448.8036] <2> fileslist:    user_interface = 1

11:17:01.896 [8448.8036] <2> fileslist:    full_listing = 0

11:17:01.896 [8448.8036] <2> fileslist:    pc1_listing = 0

11:17:01.896 [8448.8036] <2> fileslist:    input_long_listing = 0x1

11:17:01.896 [8448.8036] <2> fileslist:    list_seconds = 0

11:17:01.896 [8448.8036] <2> fileslist:    list_policy = 0

11:17:01.896 [8448.8036] <2> fileslist:    long_listing = 1

11:17:01.896 [8448.8036] <2> fileslist:    raw_partition_search = 0

11:17:01.896 [8448.8036] <2> fileslist:    client_type = 17

11:17:01.896 [8448.8036] <2> fileslist:    policy = NONE

11:17:01.896 [8448.8036] <2> fileslist:    keyword =

11:17:01.896 [8448.8036] <2> fileslist:    bplist_format = 1

11:17:01.896 [8448.8036] <2> fileslist:    true_image = 0

11:17:01.896 [8448.8036] <2> fileslist:    list_lc_messages = C

11:17:01.896 [8448.8036] <2> fileslist:    list_lc_time = C

11:17:01.896 [8448.8036] <2> fileslist:    list_lc_ctype = C

11:17:01.896 [8448.8036] <2> fileslist:    list_lc_collate = C

11:17:01.896 [8448.8036] <2> fileslist:    list_lc_numeric = C

11:17:01.896 [8448.8036] <2> fileslist:    directories_only = 0

11:17:01.896 [8448.8036] <2> fileslist:    include_extra_info = 0

11:17:01.896 [8448.8036] <2> fileslist:    client_uid = 101

11:17:01.896 [8448.8036] <2> fileslist:    client_gid = 102

11:17:01.896 [8448.8036] <2> fileslist:    ignore_case = 0

11:17:01.896 [8448.8036] <2> fileslist:    list_seconds_pre_4_5 = 0

11:17:01.896 [8448.8036] <2> fileslist:    client_flops = 0

11:17:01.896 [8448.8036] <2> fileslist:    query.fl_peername = PCDB1H03RNB

11:17:01.896 [8448.8036] <2> fileslist: 03rnb-prd-bck01.za.ds.naspers.com

11:17:01.896 [8448.8036] <2> ConnectionCache::connectAndCache: Acquiring new connection for host 03rnb-prd-bck01.za.ds.naspers.com, query type 79

11:17:01.896 [8448.8036] <2> logconnections: BPDBM CONNECT FROM 10.100.8.60.56527 TO 10.100.8.60.13721 fd = 756

11:17:01.942 [8448.8036] <2> fileslist: begin db communication

11:17:01.942 [8448.8036] <2> fileslist: criteria sent to db mgr

11:17:03.534 [8448.8036] <2> process_request: EXIT STATUS 0

11:17:03.659 [8448.8036] <2> bprd: G:\Veritas\NetBackup\bin\userreq_notify.cmd list PCDB1H03RNB oramep