302 #define	PR0 if (vd_msglevel > 0)	PRN  macro
307 	PR0("dst:%x op:%x st:%u nb:%lx addr:%lx ncook:%u\n",		\
387 #define	PR0(...)  macro
777 			PR0("offset (0x%lx) >= size (0x%lx)",  in vd_dskimg_io_params()
795 				PR0("Unknown disk label, can't do I/O "  in vd_dskimg_io_params()
809 			PR0("req_addr (0x%lx) >= psize (0x%lx)",  in vd_dskimg_io_params()
823 		PR0("I/O size truncated to %lu bytes from %lu bytes",  in vd_dskimg_io_params()
835 		PR0("offset + nbytes (0x%lx + 0x%lx) > "  in vd_dskimg_io_params()
1033 	PR0("requested disk size: %ld bytes\n", disk_size);  in vd_build_default_label()
1034 	PR0("setup: ncyl=%d nhead=%d nsec=%d\n", label->dkl_pcyl,  in vd_build_default_label()
1036 	PR0("provided disk size: %ld bytes\n", (uint64_t)  in vd_build_default_label()
1088 		PR0("fail to write disk label");  in vd_dskimg_set_vtoc()
1097 		PR0("no alternate cylinder, can not store backup labels");  in vd_dskimg_set_vtoc()
1116 			PR0("not enough sector to store all backup labels");  in vd_dskimg_set_vtoc()
1122 			PR0("error writing backup label at block %lu\n",  in vd_dskimg_set_vtoc()
1171 			PR0("EFI disk has no reserved slice");  in vd_dskimg_get_devid_block()
1183 		PR0("not enough alternate cylinder available for devid "  in vd_dskimg_get_devid_block()
1251 		PR0("error reading devid block at %lu", blk);  in vd_dskimg_read_devid()
1259 		PR0("invalid devid found at block %lu (bad revision)", blk);  in vd_dskimg_read_devid()
1269 		PR0("invalid devid found at block %lu (bad checksum)", blk);  in vd_dskimg_read_devid()
1276 		PR0("invalid devid found at block %lu", blk);  in vd_dskimg_read_devid()
1345 		PR0("Error writing devid block at %lu", blk);  in vd_dskimg_write_devid()
2005 		PR0("write fails because backend is opened read-only");  in vd_start_bio()
2018 		PR0("ldc_mem_map() returned err %d ", status);  in vd_start_bio()
2031 		PR0("ldc_mem_acquire() returned err %d ", status);  in vd_start_bio()
2206 		PR0("ldc_mem_release() returned err %d ", rv);  in vd_start_bio()
2211 		PR0("ldc_mem_unmap() returned err %d ", rv);  in vd_start_bio()
2238 			PR0("ldc_write() returned errno %d", status);  in send_msg()
2241 		PR0("ldc_write() performed only partial write");  in send_msg()
2276 	PR0("Resetting connection state with %s", VD_CLIENT(vd));  in vd_reset_if_needed()
2289 		PR0("flushwrite returned error %d", status);  in vd_reset_if_needed()
2294 		PR0("ldc_mem_dring_unmap() returned errno %d", status);  in vd_reset_if_needed()
2313 		PR0("taking down LDC channel");  in vd_reset_if_needed()
2315 		PR0("ldc_down() returned errno %d", status);  in vd_reset_if_needed()
2327 	PR0("calling ldc_up\n");  in vd_reset_if_needed()
2343 	PR0("vd_mark_in_reset: marking vd in reset\n");  in vd_mark_in_reset()
2348 		PR0("cannot schedule task to recv msg\n");  in vd_mark_in_reset()
2384 		PR0("element %u no longer \"accepted\"", idx);  in vd_mark_elem_done()
2394 			PR0("VIO_DRING_RELEASE() returned errno %d",  in vd_mark_elem_done()
2498 		PR0("ldc_mem_release() returned errno %d copying to "  in vd_complete_bio()
2509 		PR0("ldc_mem_unmap() returned errno %d copying to client",  in vd_complete_bio()
2558 		PR0("initiating full reset");  in vd_notify()
3396 		PR0("Fail to write devid");  in vd_do_dskimg_ioctl()
3460 		PR0("ioctl %x set rval = %d, which is not being returned"  in vd_backend_ioctl()
3489 	PR0("Performing %s", ioctl->operation_name);  in vd_do_ioctl()
3498 			PR0("ldc_mem_copy() returned errno %d "  in vd_do_ioctl()
3524 			PR0("uscsi fails because backend is opened read-only");  in vd_do_ioctl()
3536 		PR0("ioctl(%s) = errno %d", ioctl->cmd_name, request->status);  in vd_do_ioctl()
3564 			PR0("ldc_mem_copy() returned errno %d "  in vd_do_ioctl()
3598 		PR0("%s: Error %d completing task", __func__, task->status);  in vd_complete()
3676 				PR0("%s:  Expected at least nbytes = %lu, "  in vd_ioctl()
3683 				PR0("%s:  Expected nbytes = %lu, got %lu",  in vd_ioctl()
3696 		PR0("%s fails because backend is opened read-only",  in vd_ioctl()
3781 		PR0("ldc_mem_copy() returned errno %d copying to client",  in vd_get_devid()
3816 	PR0("Performing VD_OP_RESET");  in vd_reset()
3819 		PR0("VD_OP_RESET:  Expected nbytes = 0, got %lu",  in vd_reset()
3840 	PR0("Performing VD_OP_GET_CAPACITY");  in vd_get_capacity()
3845 		PR0("VD_OP_GET_CAPACITY:  Expected nbytes = %lu, got %lu",  in vd_get_capacity()
3865 		PR0("ldc_mem_copy() returned errno %d copying to client", rv);  in vd_get_capacity()
3884 	PR0("Performing VD_OP_GET_ACCESS");  in vd_get_access()
3889 		PR0("VD_OP_GET_ACCESS:  Expected nbytes = %lu, got %lu",  in vd_get_access()
3904 		PR0("ldc_mem_copy() returned errno %d copying to client", rv);  in vd_get_access()
3926 		PR0("VD_OP_SET_ACCESS:  Expected nbytes = %lu, got %lu",  in vd_set_access()
3933 		PR0("ldc_mem_copy() returned errno %d copying from client", rv);  in vd_set_access()
3938 		PR0("Performing VD_OP_SET_ACCESS (CLEAR)");  in vd_set_access()
3952 		PR0("Invalid VD_OP_SET_ACCESS flags: 0x%lx", flags);  in vd_set_access()
3965 		PR0("Performing VD_OP_SET_ACCESS (EXCLUSIVE|PREEMPT|PRESERVE)");  in vd_set_access()
3981 		PR0("Performing VD_OP_SET_ACCESS (EXCLUSIVE|PRESERVE)");  in vd_set_access()
3999 		PR0("Performing VD_OP_SET_ACCESS (EXCLUSIVE|PREEMPT)");  in vd_set_access()
4017 		PR0("Performing VD_OP_SET_ACCESS (EXCLUSIVE)");  in vd_set_access()
4027 		PR0("VD_OP_SET_ACCESS: error %d", request->status);  in vd_set_access()
4040 	PR0("Releasing disk ownership");  in vd_reset_access()
4053 	PR0("Fail to release ownership, error %d", status);  in vd_reset_access()
4059 	PR0("Resetting disk");  in vd_reset_access()
4063 		PR0("Fail to reset disk, error %d", status);  in vd_reset_access()
4074 	PR0("Fail to release ownership, error %d", status);  in vd_reset_access()
4166 		PR0("Unsupported operation %u", request->operation);  in vd_do_process_task()
4175 		PR0("Invalid \"slice\" %u (max %u) for virtual disk",  in vd_do_process_task()
4286 				PR0("Adjusting minor version from %u to %u",  in vds_supported_version()
4343 		PR0("Expected %lu-byte version message; "  in vd_process_ver_msg()
4349 		PR0("Expected device class %u (disk); received %u",  in vd_process_ver_msg()
4387 	PR0("Using major version %u, minor version %u",  in vd_process_ver_msg()
4439 		PR0("Message is not an attribute message");  in vd_process_attr_msg()
4444 		PR0("Expected %lu-byte attribute message; "  in vd_process_attr_msg()
4450 		PR0("Received maximum transfer size of 0 from client");  in vd_process_attr_msg()
4456 		PR0("Client requested unsupported transfer mode");  in vd_process_attr_msg()
4466 		PR0("Retry setting up disk (%s)", vd->device_path);  in vd_process_attr_msg()
4486 		PR0("vdisk_type = %s, volume = %s, file = %s, nslices = %u",  in vd_process_attr_msg()
4545 	PR0("%s", VD_CLIENT(vd));  in vd_process_attr_msg()
4566 		PR0("Message is not a register-dring message");  in vd_process_dring_reg_msg()
4571 		PR0("Expected at least %lu-byte register-dring message; "  in vd_process_dring_reg_msg()
4579 		PR0("Expected %lu-byte register-dring message; "  in vd_process_dring_reg_msg()
4585 		PR0("A dring was previously registered; only support one");  in vd_process_dring_reg_msg()
4590 		PR0("reg_msg->num_descriptors = %u; must be <= %u (%s)",  in vd_process_dring_reg_msg()
4609 		PR0("reg_msg->ncookies = %u != 1", reg_msg->ncookies);  in vd_process_dring_reg_msg()
4622 		PR0("ldc_mem_dring_map() returned errno %d", status);  in vd_process_dring_reg_msg()
4635 		PR0("ldc_mem_dring_info() returned errno %d", status);  in vd_process_dring_reg_msg()
4637 			PR0("ldc_mem_dring_unmap() returned errno %d", status);  in vd_process_dring_reg_msg()
4642 		PR0("Descriptor ring virtual address is NULL");  in vd_process_dring_reg_msg()
4671 			PR0("ldc_mem_alloc_handle() returned err %d ", status);  in vd_process_dring_reg_msg()
4703 		PR0("Message is not an unregister-dring message");  in vd_process_dring_unreg_msg()
4708 		PR0("Expected %lu-byte unregister-dring message; "  in vd_process_dring_unreg_msg()
4714 		PR0("Expected dring ident %lu; received %lu",  in vd_process_dring_unreg_msg()
4728 		PR0("Message is not an RDX message");  in process_rdx_msg()
4733 		PR0("Expected %lu-byte RDX message; received %lu bytes",  in process_rdx_msg()
4738 	PR0("Valid RDX message");  in process_rdx_msg()
4746 		PR0("Received seq_num %lu; expected %lu",  in vd_check_seq_num()
4748 		PR0("initiating soft reset");  in vd_check_seq_num()
4790 		PR0("Expected at least %lu-byte descriptor message; "  in vd_process_desc_msg()
4796 		PR0("Expected %lu-byte descriptor message; "  in vd_process_desc_msg()
4848 		PR0("descriptor %u not ready", idx);  in vd_process_element()
4853 		PR0("VIO_DRING_RELEASE() returned errno %d", status);  in vd_process_element()
4936 		PR0("Expected %lu-byte dring message; received %lu bytes",  in vd_process_dring_msg()
4945 		PR0("Expected dring ident %lu; received ident %lu",  in vd_process_dring_msg()
4951 		PR0("\"start_idx\" = %u; must be less than %u",  in vd_process_dring_msg()
4958 		PR0("\"end_idx\" = %u; must be >= 0 and less than %u",  in vd_process_dring_msg()
4986 		PR0("ldc_read() returned errno %d", status);  in recv_msg()
5016 		PR0("Expected SID %u, received %u", vd->sid,  in vd_do_process_msg()
5064 			PR0("Unsupported transfer mode");  in vd_do_process_msg()
5122 			PR0("Unsupported transfer mode");  in vd_do_process_msg()
5128 		PR0("Invalid client connection state");  in vd_do_process_msg()
5145 		PR0("Received short (%lu-byte) message", msglen);  in vd_process_msg()
5147 		PR0("initiating full reset");  in vd_process_msg()
5165 		PR0("Received unexpected message");  in vd_process_msg()
5207 		PR0("initiating %s reset",  in vd_process_msg()
5251 			PR0("channel not up (status=%d), exiting recv loop\n",  in vd_recv_msg()
5267 				PR0("max_msglen changed 0x%lx to 0x%lx bytes\n",  in vd_recv_msg()
5281 			PR0("initiating soft reset (ECONNRESET)\n");  in vd_recv_msg()
5288 			PR0("initiating full reset (status=0x%x)", status);  in vd_recv_msg()
5309 		PR0("LDC_EVT_DOWN: LDC channel went down");  in vd_handle_ldc_events()
5315 			PR0("cannot schedule task to recv msg\n");  in vd_handle_ldc_events()
5321 		PR0("LDC_EVT_RESET: LDC channel was reset");  in vd_handle_ldc_events()
5324 			PR0("scheduling full reset");  in vd_handle_ldc_events()
5329 				PR0("cannot schedule task to recv msg\n");  in vd_handle_ldc_events()
5334 			PR0("channel already reset, ignoring...\n");  in vd_handle_ldc_events()
5335 			PR0("doing ldc up...\n");  in vd_handle_ldc_events()
5343 		PR0("EVT_UP: LDC is up\nResetting client connection state");  in vd_handle_ldc_events()
5344 		PR0("initiating soft reset");  in vd_handle_ldc_events()
5349 			PR0("cannot schedule task to recv msg\n");  in vd_handle_ldc_events()
5364 			PR0("cannot schedule task to recv msg\n");  in vd_handle_ldc_events()
5394 		PR0("No action required for DDI_SUSPEND");  in vds_detach()
5397 		PR0("Unrecognized \"cmd\"");  in vds_detach()
5404 		PR0("Could not get state for instance %u", instance);  in vds_detach()
5412 		PR0("Not detaching because serving vdisks");  in vds_detach()
5416 	PR0("Detaching");  in vds_detach()
5510 		PR0("'variant' property exists for %s", vd->device_path);  in vd_is_atapi_device()
5518 		PR0("'atapi' property exists for %s", vd->device_path);  in vd_is_atapi_device()
5592 		PR0("Opening device major %u, minor %u = slice %u",  in vd_setup_full_disk()
5921 	PR0("underlying device of %s = (%d, %d)\n", file_path,  in vd_setup_backend_vnode()
5928 		PR0("ldi_open() returned errno %d for underlying device",  in vd_setup_backend_vnode()
5934 			PR0("ldi_ioctl(DKIOCINFO) returned errno %d for "  in vd_setup_backend_vnode()
5944 		PR0("close the underlying device");  in vd_setup_backend_vnode()
5948 	PR0("using file %s on device (%d, %d), max_xfer = %u blks",  in vd_setup_backend_vnode()
6053 			PR0("can not read devid for %s", backend_path);  in vd_setup_disk_image()
6068 		PR0("fail to create devid for %s", backend_path);  in vd_setup_disk_image()
6080 			PR0("fail to write devid for %s", backend_path);  in vd_setup_disk_image()
6122 		PR0("ldi_open_by_name(%s) = errno %d", device_path, status);  in vd_open_using_ldi_by_name()
6182 			PR0("Failed to open (%s) = errno %d",  in vd_setup_backend_ldi()
6374 			PR0("VOP_GETATTR(%s) = errno %d", vd->device_path, rv);  in vd_backend_check_size()
6386 			PR0("ldi_get_size() failed for %s", vd->device_path);  in vd_backend_check_size()
6399 			PR0("DKIOCGMEDIAINFO failed for %s (err=%d)",  in vd_backend_check_size()
6407 				PR0("ldi_get_size() failed for %s",  in vd_backend_check_size()
6470 				PR0("vd_setup_partition_vtoc() failed for %s "  in vd_backend_check_size()
6477 				PR0("vd_setup_partition_efi() failed for %s "  in vd_backend_check_size()
6516 		PR0("Failed to open (%s) = errno %d", device_path, status);  in vd_identify_dev()
6555 	PR0("driver %s identified as %s", drv_name,  in vd_identify_dev()
6579 		PR0("Cannot lookup %s errno %d", path, status);  in vd_setup_vd()
6733 	PR0("Adding vdisk for %s", device_path);  in vds_do_init_vd()
6770 		PR0("vdisk_type = %s, volume = %s, file = %s, nslices = %u",  in vds_do_init_vd()
6839 		PR0("ldc_up() returned errno %d", status);  in vds_do_init_vd()
6900 	PR0("Destroying vdisk state");  in vds_destroy_vd()
6935 				PR0("Timed out closing channel");  in vds_destroy_vd()
6978 				PR0("Closing slice %u", slice);  in vds_destroy_vd()
7101 		PR0("No options found");  in vds_get_options()
7119 			PR0("option: %s", opt);  in vds_get_options()
7209 		PR0("driver-type-list, entry %d (%s) added",  in vds_driver_types_update()
7233 	PR0("Adding vdisk ID %lu", id);  in vds_add_vd()
7266 	PR0("Removing vdisk ID %lu", id);  in vds_remove_vd()
7339 	PR0("Changing vdisk ID %lu", prev_id);  in vds_change_vd()
7474 		PR0("Attaching");  in vds_attach()
7479 		PR0("No action required for DDI_RESUME");  in vds_attach()