301 #define	PR0 if (vd_msglevel > 0)	PRN  macro
306 	PR0("dst:%x op:%x st:%u nb:%lx addr:%lx ncook:%u\n",		\
386 #define	PR0(...)  macro
776 			PR0("offset (0x%lx) >= size (0x%lx)",  in vd_dskimg_io_params()
794 				PR0("Unknown disk label, can't do I/O "  in vd_dskimg_io_params()
808 			PR0("req_addr (0x%lx) >= psize (0x%lx)",  in vd_dskimg_io_params()
822 		PR0("I/O size truncated to %lu bytes from %lu bytes",  in vd_dskimg_io_params()
834 		PR0("offset + nbytes (0x%lx + 0x%lx) > "  in vd_dskimg_io_params()
1032 	PR0("requested disk size: %ld bytes\n", disk_size);  in vd_build_default_label()
1033 	PR0("setup: ncyl=%d nhead=%d nsec=%d\n", label->dkl_pcyl,  in vd_build_default_label()
1035 	PR0("provided disk size: %ld bytes\n", (uint64_t)  in vd_build_default_label()
1087 		PR0("fail to write disk label");  in vd_dskimg_set_vtoc()
1096 		PR0("no alternate cylinder, can not store backup labels");  in vd_dskimg_set_vtoc()
1115 			PR0("not enough sector to store all backup labels");  in vd_dskimg_set_vtoc()
1121 			PR0("error writing backup label at block %lu\n",  in vd_dskimg_set_vtoc()
1170 			PR0("EFI disk has no reserved slice");  in vd_dskimg_get_devid_block()
1182 		PR0("not enough alternate cylinder available for devid "  in vd_dskimg_get_devid_block()
1250 		PR0("error reading devid block at %lu", blk);  in vd_dskimg_read_devid()
1258 		PR0("invalid devid found at block %lu (bad revision)", blk);  in vd_dskimg_read_devid()
1268 		PR0("invalid devid found at block %lu (bad checksum)", blk);  in vd_dskimg_read_devid()
1275 		PR0("invalid devid found at block %lu", blk);  in vd_dskimg_read_devid()
1344 		PR0("Error writing devid block at %lu", blk);  in vd_dskimg_write_devid()
2004 		PR0("write fails because backend is opened read-only");  in vd_start_bio()
2017 		PR0("ldc_mem_map() returned err %d ", status);  in vd_start_bio()
2030 		PR0("ldc_mem_acquire() returned err %d ", status);  in vd_start_bio()
2205 		PR0("ldc_mem_release() returned err %d ", rv);  in vd_start_bio()
2210 		PR0("ldc_mem_unmap() returned err %d ", rv);  in vd_start_bio()
2237 			PR0("ldc_write() returned errno %d", status);  in send_msg()
2240 		PR0("ldc_write() performed only partial write");  in send_msg()
2275 	PR0("Resetting connection state with %s", VD_CLIENT(vd));  in vd_reset_if_needed()
2288 		PR0("flushwrite returned error %d", status);  in vd_reset_if_needed()
2293 		PR0("ldc_mem_dring_unmap() returned errno %d", status);  in vd_reset_if_needed()
2312 		PR0("taking down LDC channel");  in vd_reset_if_needed()
2314 		PR0("ldc_down() returned errno %d", status);  in vd_reset_if_needed()
2326 	PR0("calling ldc_up\n");  in vd_reset_if_needed()
2342 	PR0("vd_mark_in_reset: marking vd in reset\n");  in vd_mark_in_reset()
2347 		PR0("cannot schedule task to recv msg\n");  in vd_mark_in_reset()
2383 		PR0("element %u no longer \"accepted\"", idx);  in vd_mark_elem_done()
2393 			PR0("VIO_DRING_RELEASE() returned errno %d",  in vd_mark_elem_done()
2497 		PR0("ldc_mem_release() returned errno %d copying to "  in vd_complete_bio()
2508 		PR0("ldc_mem_unmap() returned errno %d copying to client",  in vd_complete_bio()
2557 		PR0("initiating full reset");  in vd_notify()
3394 		PR0("Fail to write devid");  in vd_do_dskimg_ioctl()
3458 		PR0("ioctl %x set rval = %d, which is not being returned"  in vd_backend_ioctl()
3487 	PR0("Performing %s", ioctl->operation_name);  in vd_do_ioctl()
3496 			PR0("ldc_mem_copy() returned errno %d "  in vd_do_ioctl()
3522 			PR0("uscsi fails because backend is opened read-only");  in vd_do_ioctl()
3534 		PR0("ioctl(%s) = errno %d", ioctl->cmd_name, request->status);  in vd_do_ioctl()
3562 			PR0("ldc_mem_copy() returned errno %d "  in vd_do_ioctl()
3596 		PR0("%s: Error %d completing task", __func__, task->status);  in vd_complete()
3674 				PR0("%s:  Expected at least nbytes = %lu, "  in vd_ioctl()
3681 				PR0("%s:  Expected nbytes = %lu, got %lu",  in vd_ioctl()
3693 		PR0("%s fails because backend is opened read-only",  in vd_ioctl()
3778 		PR0("ldc_mem_copy() returned errno %d copying to client",  in vd_get_devid()
3813 	PR0("Performing VD_OP_RESET");  in vd_reset()
3816 		PR0("VD_OP_RESET:  Expected nbytes = 0, got %lu",  in vd_reset()
3837 	PR0("Performing VD_OP_GET_CAPACITY");  in vd_get_capacity()
3842 		PR0("VD_OP_GET_CAPACITY:  Expected nbytes = %lu, got %lu",  in vd_get_capacity()
3862 		PR0("ldc_mem_copy() returned errno %d copying to client", rv);  in vd_get_capacity()
3881 	PR0("Performing VD_OP_GET_ACCESS");  in vd_get_access()
3886 		PR0("VD_OP_GET_ACCESS:  Expected nbytes = %lu, got %lu",  in vd_get_access()
3901 		PR0("ldc_mem_copy() returned errno %d copying to client", rv);  in vd_get_access()
3923 		PR0("VD_OP_SET_ACCESS:  Expected nbytes = %lu, got %lu",  in vd_set_access()
3930 		PR0("ldc_mem_copy() returned errno %d copying from client", rv);  in vd_set_access()
3935 		PR0("Performing VD_OP_SET_ACCESS (CLEAR)");  in vd_set_access()
3949 		PR0("Invalid VD_OP_SET_ACCESS flags: 0x%lx", flags);  in vd_set_access()
3962 		PR0("Performing VD_OP_SET_ACCESS (EXCLUSIVE|PREEMPT|PRESERVE)");  in vd_set_access()
3977 		PR0("Performing VD_OP_SET_ACCESS (EXCLUSIVE|PRESERVE)");  in vd_set_access()
3994 		PR0("Performing VD_OP_SET_ACCESS (EXCLUSIVE|PREEMPT)");  in vd_set_access()
4012 		PR0("Performing VD_OP_SET_ACCESS (EXCLUSIVE)");  in vd_set_access()
4022 		PR0("VD_OP_SET_ACCESS: error %d", request->status);  in vd_set_access()
4035 	PR0("Releasing disk ownership");  in vd_reset_access()
4048 	PR0("Fail to release ownership, error %d", status);  in vd_reset_access()
4054 	PR0("Resetting disk");  in vd_reset_access()
4058 		PR0("Fail to reset disk, error %d", status);  in vd_reset_access()
4069 	PR0("Fail to release ownership, error %d", status);  in vd_reset_access()
4161 		PR0("Unsupported operation %u", request->operation);  in vd_do_process_task()
4170 		PR0("Invalid \"slice\" %u (max %u) for virtual disk",  in vd_do_process_task()
4281 				PR0("Adjusting minor version from %u to %u",  in vds_supported_version()
4338 		PR0("Expected %lu-byte version message; "  in vd_process_ver_msg()
4344 		PR0("Expected device class %u (disk); received %u",  in vd_process_ver_msg()
4382 	PR0("Using major version %u, minor version %u",  in vd_process_ver_msg()
4434 		PR0("Message is not an attribute message");  in vd_process_attr_msg()
4439 		PR0("Expected %lu-byte attribute message; "  in vd_process_attr_msg()
4445 		PR0("Received maximum transfer size of 0 from client");  in vd_process_attr_msg()
4451 		PR0("Client requested unsupported transfer mode");  in vd_process_attr_msg()
4461 		PR0("Retry setting up disk (%s)", vd->device_path);  in vd_process_attr_msg()
4481 		PR0("vdisk_type = %s, volume = %s, file = %s, nslices = %u",  in vd_process_attr_msg()
4540 	PR0("%s", VD_CLIENT(vd));  in vd_process_attr_msg()
4561 		PR0("Message is not a register-dring message");  in vd_process_dring_reg_msg()
4566 		PR0("Expected at least %lu-byte register-dring message; "  in vd_process_dring_reg_msg()
4574 		PR0("Expected %lu-byte register-dring message; "  in vd_process_dring_reg_msg()
4580 		PR0("A dring was previously registered; only support one");  in vd_process_dring_reg_msg()
4585 		PR0("reg_msg->num_descriptors = %u; must be <= %u (%s)",  in vd_process_dring_reg_msg()
4604 		PR0("reg_msg->ncookies = %u != 1", reg_msg->ncookies);  in vd_process_dring_reg_msg()
4617 		PR0("ldc_mem_dring_map() returned errno %d", status);  in vd_process_dring_reg_msg()
4630 		PR0("ldc_mem_dring_info() returned errno %d", status);  in vd_process_dring_reg_msg()
4632 			PR0("ldc_mem_dring_unmap() returned errno %d", status);  in vd_process_dring_reg_msg()
4637 		PR0("Descriptor ring virtual address is NULL");  in vd_process_dring_reg_msg()
4666 			PR0("ldc_mem_alloc_handle() returned err %d ", status);  in vd_process_dring_reg_msg()
4698 		PR0("Message is not an unregister-dring message");  in vd_process_dring_unreg_msg()
4703 		PR0("Expected %lu-byte unregister-dring message; "  in vd_process_dring_unreg_msg()
4709 		PR0("Expected dring ident %lu; received %lu",  in vd_process_dring_unreg_msg()
4723 		PR0("Message is not an RDX message");  in process_rdx_msg()
4728 		PR0("Expected %lu-byte RDX message; received %lu bytes",  in process_rdx_msg()
4733 	PR0("Valid RDX message");  in process_rdx_msg()
4741 		PR0("Received seq_num %lu; expected %lu",  in vd_check_seq_num()
4743 		PR0("initiating soft reset");  in vd_check_seq_num()
4785 		PR0("Expected at least %lu-byte descriptor message; "  in vd_process_desc_msg()
4791 		PR0("Expected %lu-byte descriptor message; "  in vd_process_desc_msg()
4843 		PR0("descriptor %u not ready", idx);  in vd_process_element()
4848 		PR0("VIO_DRING_RELEASE() returned errno %d", status);  in vd_process_element()
4931 		PR0("Expected %lu-byte dring message; received %lu bytes",  in vd_process_dring_msg()
4940 		PR0("Expected dring ident %lu; received ident %lu",  in vd_process_dring_msg()
4946 		PR0("\"start_idx\" = %u; must be less than %u",  in vd_process_dring_msg()
4953 		PR0("\"end_idx\" = %u; must be >= 0 and less than %u",  in vd_process_dring_msg()
4981 		PR0("ldc_read() returned errno %d", status);  in recv_msg()
5011 		PR0("Expected SID %u, received %u", vd->sid,  in vd_do_process_msg()
5059 			PR0("Unsupported transfer mode");  in vd_do_process_msg()
5117 			PR0("Unsupported transfer mode");  in vd_do_process_msg()
5123 		PR0("Invalid client connection state");  in vd_do_process_msg()
5140 		PR0("Received short (%lu-byte) message", msglen);  in vd_process_msg()
5142 		PR0("initiating full reset");  in vd_process_msg()
5160 		PR0("Received unexpected message");  in vd_process_msg()
5202 		PR0("initiating %s reset",  in vd_process_msg()
5246 			PR0("channel not up (status=%d), exiting recv loop\n",  in vd_recv_msg()
5262 				PR0("max_msglen changed 0x%lx to 0x%lx bytes\n",  in vd_recv_msg()
5276 			PR0("initiating soft reset (ECONNRESET)\n");  in vd_recv_msg()
5283 			PR0("initiating full reset (status=0x%x)", status);  in vd_recv_msg()
5304 		PR0("LDC_EVT_DOWN: LDC channel went down");  in vd_handle_ldc_events()
5310 			PR0("cannot schedule task to recv msg\n");  in vd_handle_ldc_events()
5316 		PR0("LDC_EVT_RESET: LDC channel was reset");  in vd_handle_ldc_events()
5319 			PR0("scheduling full reset");  in vd_handle_ldc_events()
5324 				PR0("cannot schedule task to recv msg\n");  in vd_handle_ldc_events()
5329 			PR0("channel already reset, ignoring...\n");  in vd_handle_ldc_events()
5330 			PR0("doing ldc up...\n");  in vd_handle_ldc_events()
5338 		PR0("EVT_UP: LDC is up\nResetting client connection state");  in vd_handle_ldc_events()
5339 		PR0("initiating soft reset");  in vd_handle_ldc_events()
5344 			PR0("cannot schedule task to recv msg\n");  in vd_handle_ldc_events()
5359 			PR0("cannot schedule task to recv msg\n");  in vd_handle_ldc_events()
5389 		PR0("No action required for DDI_SUSPEND");  in vds_detach()
5392 		PR0("Unrecognized \"cmd\"");  in vds_detach()
5399 		PR0("Could not get state for instance %u", instance);  in vds_detach()
5407 		PR0("Not detaching because serving vdisks");  in vds_detach()
5411 	PR0("Detaching");  in vds_detach()
5505 		PR0("'variant' property exists for %s", vd->device_path);  in vd_is_atapi_device()
5513 		PR0("'atapi' property exists for %s", vd->device_path);  in vd_is_atapi_device()
5587 		PR0("Opening device major %u, minor %u = slice %u",  in vd_setup_full_disk()
5916 	PR0("underlying device of %s = (%d, %d)\n", file_path,  in vd_setup_backend_vnode()
5923 		PR0("ldi_open() returned errno %d for underlying device",  in vd_setup_backend_vnode()
5929 			PR0("ldi_ioctl(DKIOCINFO) returned errno %d for "  in vd_setup_backend_vnode()
5939 		PR0("close the underlying device");  in vd_setup_backend_vnode()
5943 	PR0("using file %s on device (%d, %d), max_xfer = %u blks",  in vd_setup_backend_vnode()
6048 			PR0("can not read devid for %s", backend_path);  in vd_setup_disk_image()
6063 		PR0("fail to create devid for %s", backend_path);  in vd_setup_disk_image()
6075 			PR0("fail to write devid for %s", backend_path);  in vd_setup_disk_image()
6117 		PR0("ldi_open_by_name(%s) = errno %d", device_path, status);  in vd_open_using_ldi_by_name()
6177 			PR0("Failed to open (%s) = errno %d",  in vd_setup_backend_ldi()
6369 			PR0("VOP_GETATTR(%s) = errno %d", vd->device_path, rv);  in vd_backend_check_size()
6381 			PR0("ldi_get_size() failed for %s", vd->device_path);  in vd_backend_check_size()
6394 			PR0("DKIOCGMEDIAINFO failed for %s (err=%d)",  in vd_backend_check_size()
6402 				PR0("ldi_get_size() failed for %s",  in vd_backend_check_size()
6465 				PR0("vd_setup_partition_vtoc() failed for %s "  in vd_backend_check_size()
6472 				PR0("vd_setup_partition_efi() failed for %s "  in vd_backend_check_size()
6511 		PR0("Failed to open (%s) = errno %d", device_path, status);  in vd_identify_dev()
6550 	PR0("driver %s identified as %s", drv_name,  in vd_identify_dev()
6574 		PR0("Cannot lookup %s errno %d", path, status);  in vd_setup_vd()
6728 	PR0("Adding vdisk for %s", device_path);  in vds_do_init_vd()
6765 		PR0("vdisk_type = %s, volume = %s, file = %s, nslices = %u",  in vds_do_init_vd()
6834 		PR0("ldc_up() returned errno %d", status);  in vds_do_init_vd()
6895 	PR0("Destroying vdisk state");  in vds_destroy_vd()
6930 				PR0("Timed out closing channel");  in vds_destroy_vd()
6973 				PR0("Closing slice %u", slice);  in vds_destroy_vd()
7096 		PR0("No options found");  in vds_get_options()
7114 			PR0("option: %s", opt);  in vds_get_options()
7204 		PR0("driver-type-list, entry %d (%s) added",  in vds_driver_types_update()
7228 	PR0("Adding vdisk ID %lu", id);  in vds_add_vd()
7261 	PR0("Removing vdisk ID %lu", id);  in vds_remove_vd()
7334 	PR0("Changing vdisk ID %lu", prev_id);  in vds_change_vd()
7469 		PR0("Attaching");  in vds_attach()
7474 		PR0("No action required for DDI_RESUME");  in vds_attach()