I want to make 3-way copy with ndmp, too, but have no success. Is there any
stable old version of ndmp daemon without snapshots feature? I tried only
latest versions of ndmpd, libndmp, ndmpcopy.
Which versions should I use for testing and where I can get them?
I debugged "[12][traverse_level:859]:Error 2 on fs_getstat(/opt/a/)" and found
that fs_getstat function receive name of snapshot
(legacy/.zfs/snapshot/bk-ndmp.0/ in my case) instead of path. I suppose there
are many other places to check.
--
This message posted from opensolaris.org
BACKUP:
4/24 18:29:52 DEBUG: [4][ndmp_run:307]:connection fd: 11
4/24 18:29:52 DEBUG: [4][ndmp_run:317]:css: 65_KB
4/24 18:29:52 DEBUG: [4][ndmp_run:324]:crs: 80_KB
4/24 18:29:52 DEBUG: [10][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [10][connection_handler:894]:connection_fd: 11
4/24 18:29:52 DEBUG: [10][hardlink_q_init:66]:qhead = 8106f68
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x900
4/24 18:29:52 DEBUG: [10][ndmpd_connect_open_v2:169]:set ver to: 4
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:52 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x901
4/24 18:29:52 DEBUG: [10][ndmpd_connect_client_auth_v3:448]:auth_type Text
4/24 18:29:52 DEBUG: [10][ndmpd_connect_auth_text:1185]:Authorization granted.
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:52 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x40a
4/24 18:29:52 DEBUG: [10][ndmp_connect_sock_v3:2073]:addr 177.1.168.192:57233
4/24 18:29:52 DEBUG: [10][ndmp_connect_sock_v3:2099]:sock 13
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:52 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x401
4/24 18:29:52 DEBUG: [10][ndmpd_save_env:547]:env(UPDATE): "Y"
4/24 18:29:52 DEBUG: [10][ndmpd_save_env:547]:env(HIST): "Y"
4/24 18:29:52 DEBUG: [10][ndmpd_save_env:547]:env(FILESYSTEM): "/opt/a/"
4/24 18:29:52 DEBUG: [10][ndmpd_save_env:547]:env(LEVEL): "0"
4/24 18:29:52 DEBUG: [10][ndmpd_save_env:547]:env(DIRECT): "Y"
4/24 18:29:52 DEBUG: [10][get_bk_path_v3:651]:bkpath: "/opt/a/"
4/24 18:29:52 DEBUG: [10][ndmp_backup_get_params_v3:3657]:flags 10
4/24 18:29:52 DEBUG: [10][get_hist_env_v3:909]:env(HIST): "Y"
4/24 18:29:52 DEBUG: [10][get_exc_env_v3:939]:env(EXCLUDE) not defined
4/24 18:29:52 DEBUG: [10][get_inc_env_v3:971]:env(FILES) not defined
4/24 18:29:52 DEBUG: [10][get_direct_env_v3:1020]:backup default env(DIRECT):
YES
4/24 18:29:52 DEBUG: [10][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [10][ndmpd_get_dumptime:843]:[/opt/a/] level 0
4/24 18:29:52 DEBUG: [10][get_update_env_v3:876]:env(UPDATE): "Y"
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:52 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:52 DEBUG: [11][ndmp_new_job_name:2219]:jname: "ndmp.2"
4/24 18:29:52 DEBUG: [11][ndmpd_tar_backup_starter_v3:3707]:err 0, chkpnted N
4/24 18:29:52 DEBUG: [11][traverse_level:940]:pl 7 "/opt/a"
4/24 18:29:52 DEBUG: [11][traverse_level_nondir:769]:i 106787 dname:
"ddd.tar.gz"
4/24 18:29:52 DEBUG: [11][traverse_level_nondir:769]:i 106754 dname: "af"
4/24 18:29:52 DEBUG: [11][traverse_level_nondir:769]:i 106753 dname: "asdfasdf"
4/24 18:29:52 DEBUG: [11][traverse_level:1000]:281801602 dname: "ddd.tar.gz"
4/24 18:29:52 DEBUG: [11][traverse_level:1000]:318090584 dname: "af"
4/24 18:29:52 DEBUG: [11][traverse_level:1000]:498820677 dname: "asdfasdf"
4/24 18:29:52 DEBUG: [11][cstack_pop:155]:cstack_pop: underflow
4/24 18:29:52 DEBUG: [11][cstack_pop:155]:cstack_pop: underflow
4/24 18:29:52 DEBUG: [11][cstack_delete:98]:cstack_delete: 0x81072c0
4/24 18:29:52 DEBUG: [11][get_backup_size:2560]:bksize 26557450, 25935KB, 25MB
4/24 18:29:52 DEBUG: [11][tlm_get_chkpnt_time:655]:path [/opt/a/]
auto_checkpoint: 1
4/24 18:29:52 DEBUG: [11][tlm_get_chkpnt_time:665]:volname
[syspool/rootfs-nmu-002]
4/24 18:29:52 DEBUG: [11][ndmp_get_cur_bk_time:2487]:Fri Apr 24 18:29:52 2009
4/24 18:29:52 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [11][ndmp_get_bk_dir_ino:2409]:nlp_bkdirino: 106752
4/24 18:29:52 DEBUG: [11][ndmp_buffer_get_size:1034]:Local operation: 64512
4/24 18:29:52 DEBUG: [11][ndmp_buffer_get_size:1039]:xfer_size: 64512
4/24 18:29:52 DEBUG: [11][backup_alloc_structs_v3:1498]:Adjusted read size:
516096
4/24 18:29:52 DEBUG: [11][create_bitmap:455]:path "/opt/a/"
4/24 18:29:52 DEBUG: [11][ndmp_new_job_name:2219]:jname: "ndmp.3"
4/24 18:29:52 DEBUG: [11][create_bitmap:467]:path "/opt/a/"ninode 1073741824
bm_fname "/var/ndmp/ndmp.3"
4/24 18:29:57 DEBUG: [11][create_allset_bitmap:491]:nlp_bkmap 0
4/24 18:29:57 DEBUG: [11][tar_backup_v3:2437]:Backing up "/opt/a/" started.
4/24 18:29:57 DEBUG: [12][backup_reader_v3:2320]:path /opt/a/ lpath
legacy/.zfs/snapshot/bk-ndmp.2/
4/24 18:29:57 DEBUG: [12][traverse_level:859]:Error 2 on fs_getstat(/opt/a/)
4/24 18:29:57 DEBUG: [11][ndmp_tar_writer:1009]:tc_writer!=TLM_BACKUP_RUN;
time to exit
4/24 18:29:57 DEBUG: [11][ndmp_tar_writer:1018]:nw: 0
4/24 18:29:57 DEBUG: [11][ndmp_tar_writer:1020]:tcs_writer != TLM_ABORT
4/24 18:29:57 DEBUG: [11][ndmp_tar_writer:1026]:tc_writer != TLM_ABORT
4/24 18:29:57 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:57 DEBUG: [11][ndmp_wait_for_reader:1508]:reader_count: 0
4/24 18:29:57 DEBUG: [11][tar_backup_v3:2500]:Backing up "/opt/a/" Finished.
4/24 18:29:57 DEBUG: [11][ndmpd_tar_backup_starter_v3:3726]:err 0, update Y
4/24 18:29:57 DEBUG: [11][ndmpd_put_dumptime:957]:[/opt/a/][0][1240583392]
4/24 18:29:57 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x400
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:59 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x407
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:59 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x400
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:59 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1210]:detected eof
4/24 18:29:59 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:59 DEBUG: [10][hardlink_q_cleanup:77]:(1): qhead = 8106f68
4/24 18:29:59 DEBUG: [10][connection_handler:922]:Connection terminated
4/24 18:29:59 DEBUG: [10][nlp_event_rv_set:1276]:rv: -2
4/24 18:29:59 DEBUG: [10][ndmp_stop_buffer_worker:1725]:cmds->tcs_command ==
NULL
4/24 18:29:59 DEBUG: [10][ndmp_connect_list_find:646]:connection: 0x8105ff8
RESTORE:
4/24 18:29:52 DEBUG: [4][ndmp_run:307]:connection fd: 11
4/24 18:29:52 DEBUG: [4][ndmp_run:317]:css: 65_KB
4/24 18:29:52 DEBUG: [4][ndmp_run:324]:crs: 80_KB
4/24 18:29:52 DEBUG: [10][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [10][connection_handler:894]:connection_fd: 11
4/24 18:29:52 DEBUG: [10][hardlink_q_init:66]:qhead = 8106f68
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x900
4/24 18:29:52 DEBUG: [10][ndmpd_connect_open_v2:169]:set ver to: 4
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:52 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x901
4/24 18:29:52 DEBUG: [10][ndmpd_connect_client_auth_v3:448]:auth_type Text
4/24 18:29:52 DEBUG: [10][ndmpd_connect_auth_text:1185]:Authorization granted.
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:52 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x40a
4/24 18:29:52 DEBUG: [10][ndmp_connect_sock_v3:2073]:addr 177.1.168.192:57233
4/24 18:29:52 DEBUG: [10][ndmp_connect_sock_v3:2099]:sock 13
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:52 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x401
4/24 18:29:52 DEBUG: [10][ndmpd_save_env:547]:env(UPDATE): "Y"
4/24 18:29:52 DEBUG: [10][ndmpd_save_env:547]:env(HIST): "Y"
4/24 18:29:52 DEBUG: [10][ndmpd_save_env:547]:env(FILESYSTEM): "/opt/a/"
4/24 18:29:52 DEBUG: [10][ndmpd_save_env:547]:env(LEVEL): "0"
4/24 18:29:52 DEBUG: [10][ndmpd_save_env:547]:env(DIRECT): "Y"
4/24 18:29:52 DEBUG: [10][get_bk_path_v3:651]:bkpath: "/opt/a/"
4/24 18:29:52 DEBUG: [10][ndmp_backup_get_params_v3:3657]:flags 10
4/24 18:29:52 DEBUG: [10][get_hist_env_v3:909]:env(HIST): "Y"
4/24 18:29:52 DEBUG: [10][get_exc_env_v3:939]:env(EXCLUDE) not defined
4/24 18:29:52 DEBUG: [10][get_inc_env_v3:971]:env(FILES) not defined
4/24 18:29:52 DEBUG: [10][get_direct_env_v3:1020]:backup default env(DIRECT):
YES
4/24 18:29:52 DEBUG: [10][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [10][ndmpd_get_dumptime:843]:[/opt/a/] level 0
4/24 18:29:52 DEBUG: [10][get_update_env_v3:876]:env(UPDATE): "Y"
4/24 18:29:52 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:52 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:52 DEBUG: [11][ndmp_new_job_name:2219]:jname: "ndmp.2"
4/24 18:29:52 DEBUG: [11][ndmpd_tar_backup_starter_v3:3707]:err 0, chkpnted N
4/24 18:29:52 DEBUG: [11][traverse_level:940]:pl 7 "/opt/a"
4/24 18:29:52 DEBUG: [11][traverse_level_nondir:769]:i 106787 dname:
"ddd.tar.gz"
4/24 18:29:52 DEBUG: [11][traverse_level_nondir:769]:i 106754 dname: "af"
4/24 18:29:52 DEBUG: [11][traverse_level_nondir:769]:i 106753 dname: "asdfasdf"
4/24 18:29:52 DEBUG: [11][traverse_level:1000]:281801602 dname: "ddd.tar.gz"
4/24 18:29:52 DEBUG: [11][traverse_level:1000]:318090584 dname: "af"
4/24 18:29:52 DEBUG: [11][traverse_level:1000]:498820677 dname: "asdfasdf"
4/24 18:29:52 DEBUG: [11][cstack_pop:155]:cstack_pop: underflow
4/24 18:29:52 DEBUG: [11][cstack_pop:155]:cstack_pop: underflow
4/24 18:29:52 DEBUG: [11][cstack_delete:98]:cstack_delete: 0x81072c0
4/24 18:29:52 DEBUG: [11][get_backup_size:2560]:bksize 26557450, 25935KB, 25MB
4/24 18:29:52 DEBUG: [11][tlm_get_chkpnt_time:655]:path [/opt/a/]
auto_checkpoint: 1
4/24 18:29:52 DEBUG: [11][tlm_get_chkpnt_time:665]:volname
[syspool/rootfs-nmu-002]
4/24 18:29:52 DEBUG: [11][ndmp_get_cur_bk_time:2487]:Fri Apr 24 18:29:52 2009
4/24 18:29:52 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:52 DEBUG: [11][ndmp_get_bk_dir_ino:2409]:nlp_bkdirino: 106752
4/24 18:29:52 DEBUG: [11][ndmp_buffer_get_size:1034]:Local operation: 64512
4/24 18:29:52 DEBUG: [11][ndmp_buffer_get_size:1039]:xfer_size: 64512
4/24 18:29:52 DEBUG: [11][backup_alloc_structs_v3:1498]:Adjusted read size:
516096
4/24 18:29:52 DEBUG: [11][create_bitmap:455]:path "/opt/a/"
4/24 18:29:52 DEBUG: [11][ndmp_new_job_name:2219]:jname: "ndmp.3"
4/24 18:29:52 DEBUG: [11][create_bitmap:467]:path "/opt/a/"ninode 1073741824
bm_fname "/var/ndmp/ndmp.3"
4/24 18:29:57 DEBUG: [11][create_allset_bitmap:491]:nlp_bkmap 0
4/24 18:29:57 DEBUG: [11][tar_backup_v3:2437]:Backing up "/opt/a/" started.
4/24 18:29:57 DEBUG: [12][backup_reader_v3:2320]:path /opt/a/ lpath
legacy/.zfs/snapshot/bk-ndmp.2/
4/24 18:29:57 DEBUG: [12][traverse_level:859]:Error 2 on fs_getstat(/opt/a/)
4/24 18:29:57 DEBUG: [11][ndmp_tar_writer:1009]:tc_writer!=TLM_BACKUP_RUN;
time to exit
4/24 18:29:57 DEBUG: [11][ndmp_tar_writer:1018]:nw: 0
4/24 18:29:57 DEBUG: [11][ndmp_tar_writer:1020]:tcs_writer != TLM_ABORT
4/24 18:29:57 DEBUG: [11][ndmp_tar_writer:1026]:tc_writer != TLM_ABORT
4/24 18:29:57 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:57 DEBUG: [11][ndmp_wait_for_reader:1508]:reader_count: 0
4/24 18:29:57 DEBUG: [11][tar_backup_v3:2500]:Backing up "/opt/a/" Finished.
4/24 18:29:57 DEBUG: [11][ndmpd_tar_backup_starter_v3:3726]:err 0, update Y
4/24 18:29:57 DEBUG: [11][ndmpd_put_dumptime:957]:[/opt/a/][0][1240583392]
4/24 18:29:57 DEBUG: [11][ndmp_send_request:480]:handler->mh_xdr_reply == 0
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x400
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:59 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x407
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:59 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1280]:received request: 0x400
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1308]:no more messages in
stream buffer
4/24 18:29:59 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1200]:reply_expected: FALSE
4/24 18:29:59 DEBUG: [10][ndmp_process_messages:1210]:detected eof
4/24 18:29:59 DEBUG: [10][nlp_event_rv_set:1276]:rv: 1
4/24 18:29:59 DEBUG: [10][hardlink_q_cleanup:77]:(1): qhead = 8106f68
4/24 18:29:59 DEBUG: [10][connection_handler:922]:Connection terminated
4/24 18:29:59 DEBUG: [10][nlp_event_rv_set:1276]:rv: -2
4/24 18:29:59 DEBUG: [10][ndmp_stop_buffer_worker:1725]:cmds->tcs_command ==
NULL
4/24 18:29:59 DEBUG: [10][ndmp_connect_list_find:646]:connection: 0x8105ff8
_______________________________________________
storage-discuss mailing list
[email protected]
http://mail.opensolaris.org/mailman/listinfo/storage-discuss
