Opened 10 years ago
Closed 8 years ago
#13214 closed defect (invalid)
Possible source of panics (definitely in *BSD guests, possibly others)
| Reported by: | Michelle Sullivan | Owned by: | |
|---|---|---|---|
| Component: | virtual disk | Version: | VirtualBox 4.3.12 |
| Keywords: | kernel panic, rootfs, zfs, drive not ready | Cc: | |
| Guest type: | BSD | Host type: | all |
Description
Marked OS type as 'all' because found on FreeBSD, reproduced on OSX.
Pertinent Config: IDE controller (any - tested with ICH6), HostIO cache on Not tested: Other controllers (eg SATA), HostIO cache off (it's very difficult to catch)
Guest OS: FreeBSD (not tested OSX guest or linux)
A guest drive becomes unavailable during write (loading cache etc, slow to respond OS etc)
Guest gets (correctly) 'Drive Not Ready' from host. Host retries command Guest gets 'Drive Not Ready' from host. (Immediately) Host retries command Guest gets 'Drive Not Ready' from host. (Immediately) Host retries command Guest gets 'Drive Not Ready' from host. (Immediately) Host retries command Guest gets 'Drive Not Ready' from host. (Immediately) Host retries command Guest gets 'Drive Not Ready' from host. (Immediately)
Kernel panics (rootfs unavailable), vnode/inode get corrupt, zfs panics kernel.
Drive corrupted on reboot - causes subsequent panics.
This was caught on my VM (finally - the retries were in a split second, real hardware would have taken over a minute):
ZFS filesystem version: 5 ZFS storage pool version: features support (5000) (ada1:ata0:0:1:0): WRITE_DMA. ACB: ca 00 bf 8d b0 40 00 00 00 00 06 00 (ada1:ata0:0:1:0): CAM status: ATA Status Error (ada1:ata0:0:1:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF ) (ada1:ata0:0:1:0): RES: 41 10 bf 8d b0 00 00 00 00 06 00 (ada1:ata0:0:1:0): Retrying command (ada1:ata0:0:1:0): WRITE_DMA. ACB: ca 00 bf 8d b0 40 00 00 00 00 06 00 (ada1:ata0:0:1:0): CAM status: ATA Status Error (ada1:ata0:0:1:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF ) (ada1:ata0:0:1:0): RES: 41 10 bf 8d b0 00 00 00 00 06 00 (ada1:ata0:0:1:0): Retrying command (ada1:ata0:0:1:0): WRITE_DMA. ACB: ca 00 bf 8d b0 40 00 00 00 00 06 00 (ada1:ata0:0:1:0): CAM status: ATA Status Error (ada1:ata0:0:1:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF ) (ada1:ata0:0:1:0): RES: 41 10 bf 8d b0 00 00 00 00 06 00 (ada1:ata0:0:1:0): Retrying command (ada1:ata0:0:1:0): WRITE_DMA. ACB: ca 00 bf 8d b0 40 00 00 00 00 06 00 (ada1:ata0:0:1:0): CAM status: ATA Status Error (ada1:ata0:0:1:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF ) (ada1:ata0:0:1:0): RES: 41 10 bf 8d b0 00 00 00 00 06 00 (ada1:ata0:0:1:0): Retrying command (ada1:ata0:0:1:0): WRITE_DMA. ACB: ca 00 bf 8d b0 40 00 00 00 00 06 00 (ada1:ata0:0:1:0): CAM status: ATA Status Error (ada1:ata0:0:1:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF ) (ada1:ata0:0:1:0): RES: 41 10 bf 8d b0 00 00 00 00 06 00 (ada1:ata0:0:1:0): Error 5, Retries exhausted (ada1:ata0:0:1:0): WRITE_DMA. ACB: ca 00 bf 8d b0 40 00 00 00 00 06 00 (ada1:ata0:0:1:0): CAM status: ATA Status Error (ada1:ata0:0:1:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF ) (ada1:ata0:0:1:0): RES: 41 10 bf 8d b0 00 00 00 00 06 00 (ada1:ata0:0:1:0): Retrying command (ada1:ata0:0:1:0): WRITE_DMA. ACB: ca 00 bf 8d b0 40 00 00 00 00 06 00 (ada1:ata0:0:1:0): CAM status: ATA Status Error (ada1:ata0:0:1:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF ) (ada1:ata0:0:1:0): RES: 41 10 bf 8d b0 00 00 00 00 06 00 (ada1:ata0:0:1:0): Retrying command (ada1:ata0:0:1:0): WRITE_DMA. ACB: ca 00 bf 8d b0 40 00 00 00 00 06 00 (ada1:ata0:0:1:0): CAM status: ATA Status Error (ada1:ata0:0:1:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF ) (ada1:ata0:0:1:0): RES: 41 10 bf 8d b0 00 00 00 00 06 00 (ada1:ata0:0:1:0): Retrying command (ada1:ata0:0:1:0): WRITE_DMA. ACB: ca 00 bf 8d b0 40 00 00 00 00 06 00 (ada1:ata0:0:1:0): CAM status: ATA Status Error (ada1:ata0:0:1:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF ) (ada1:ata0:0:1:0): RES: 41 10 bf 8d b0 00 00 00 00 06 00 (ada1:ata0:0:1:0): Retrying command (ada1:ata0:0:1:0): WRITE_DMA. ACB: ca 00 bf 8d b0 40 00 00 00 00 06 00 (ada1:ata0:0:1:0): CAM status: ATA Status Error (ada1:ata0:0:1:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF ) (ada1:ata0:0:1:0): RES: 41 10 bf 8d b0 00 00 00 00 06 00 (ada1:ata0:0:1:0): Error 5, Retries exhausted
(Retries by default in FreeBSD are set to '5' - other OSes vary - then will give up the operation)
Real hardware/drives & controllers will pause retrying the command - Server Drives 7 seconds before failing, Desktopdrives 30 seconds before failing. (Well actually they will pause before failing, continually re-trying)
If the fault is caused by a Host OS hardware/drive error the pause is correct. If the cause is not a host/hardware drive error (eg slow software load/seek of the drive image) this error presents as vbox doesn't get blocked and it presents the immediate 'drive not ready' to the guest as real hardware would.
I am not a C++ programmer so have not looked at the code, it's possible racecondition related, but certainly one would expect a fix would be to suspend hardware emulation replies whilst retrying the operation (loading sectors, scanning cache etc) before presenting 'drive not ready' to the guest.
Attachments (2)
Change History (20)
comment:2 by , 10 years ago
Please attach VBox.log for a VM run where this happened. Without this information there's not enough information to get our investigation started.
comment:3 by , 10 years ago
First Vbox log (its not shut down yet - but doing it regularly..)
root@colossus:/Storage/VirtualBox VMs/FreeBSD 9.2 i386/Logs # more VBox.log
VirtualBox VM 4.3.12_OSE r93733 freebsd.amd64 (Jul 17 2014 19:33:27) release log
00:00:00.062294 Log opened 2014-08-05T22:36:45.756891000Z
00:00:00.062295 Build Type: release
00:00:00.062304 OS Product: FreeBSD
00:00:00.062308 OS Release: 9.2-RELEASE-p7
00:00:00.062311 OS Version: FreeBSD 9.2-RELEASE-p7 #0: Tue Jun 3 11:05:13 UTC 2014 root@amd64-builder.daemonology.net:/usr/obj/usr/src/sys/GENERIC
00:00:00.062316 Host RAM: 32681MB total, 28439MB available
00:00:00.062318 Executable: /usr/local/lib/virtualbox/VBoxHeadless
00:00:00.062318 Process ID: 8761
00:00:00.062319 Package type: BSD_64BITS_GENERIC (OSE)
00:00:00.063176 Installed Extension Packs:
00:00:00.063192 VNC (Version: 4.3.12_OSE r93733; VRDE Module: VBoxVNC)
00:00:00.180434 VRDE: loaded version 3 of the server.
00:00:00.183176 SUP: Loaded VMMR0.r0 (/usr/local/lib/virtualbox/VMMR0.r0) at 0xffffffff81b89020 - ModuleInit at ffffffff81ba6c10 and ModuleTerm at ffffffff81ba6be0
00:00:00.183198 SUP: VMMR0EntryEx located at ffffffff81ba6b10, VMMR0EntryFast at ffffffff81ba6f60 and VMMR0EntryInt at ffffffff81ba5ab0
00:00:00.184140 Guest OS type: 'FreeBSD'
00:00:00.186591 fHMForced=true - Lots of RAM
00:00:00.186606 fHMForced=true - SMP
00:00:00.186612 fHMForced=true - 64-bit guest
00:00:00.189256 File system of '/Storage/VirtualBox VMs/FreeBSD 9.2 i386/Snapshots' (snapshots) is unknown
00:00:00.189269 File system of '/Storage/VirtualBox VMs/FreeBSD 9.2 i386/FreeBSD 9.2 i386.vdi' is zfs
00:00:00.190386 File system of '/Storage/VirtualBox VMs/FreeBSD 9.2 i386/build 9.2 i386.vdi' is zfs
00:00:00.197028 Shared clipboard mode: Off
00:00:00.198997 ************************* CFGM dump *************************
00:00:00.199001 [/] (level 0)
00:00:00.199010 CSAMEnabled <integer> = 0x0000000000000001 (1)
00:00:00.199013 CpuExecutionCap <integer> = 0x0000000000000064 (100)
00:00:00.199014 EnablePAE <integer> = 0x0000000000000000 (0)
00:00:00.199016 HMEnabled <integer> = 0x0000000000000001 (1)
00:00:00.199016 MemBalloonSize <integer> = 0x0000000000000000 (0)
00:00:00.199017 Name <string> = "FreeBSD 9.2 i386" (cb=17)
00:00:00.199019 NumCPUs <integer> = 0x0000000000000004 (4)
00:00:00.199020 PATMEnabled <integer> = 0x0000000000000001 (1)
00:00:00.199021 PageFusion <integer> = 0x0000000000000000 (0)
00:00:00.199022 RamHoleSize <integer> = 0x0000000020000000 (536 870 912, 512 MB)
00:00:00.199023 RamSize <integer> = 0x0000000200000000 (8 589 934 592, 8 GB)
00:00:00.199025 RawR0Enabled <integer> = 0x0000000000000001 (1)
00:00:00.199026 RawR3Enabled <integer> = 0x0000000000000001 (1)
00:00:00.199027 TimerMillies <integer> = 0x000000000000000a (10)
00:00:00.199028 UUID <bytes> = "a5 41 31 a8 2a 95 d0 4f a3 94 0d e8 68 95 8e 3b" (cb=16)
00:00:00.199032
00:00:00.199034 [/CPUM/] (level 1)
00:00:00.199036 SyntheticCpu <integer> = 0x0000000000000000 (0)
00:00:00.199036
00:00:00.199038 [/DBGF/] (level 1)
00:00:00.199039 Path <string> = "/Storage/VirtualBox VMs/FreeBSD 9.2 i386/debug/;/Storage/VirtualBox VMs/FreeBSD 9.2 i386/;/root/" (cb=97)
00:00:00.199040
00:00:00.199041 [/Devices/] (level 1)
00:00:00.199042
00:00:00.199042 [/Devices/8237A/] (level 2)
00:00:00.199043
00:00:00.199043 [/Devices/8237A/0/] (level 3)
00:00:00.199045 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199046
00:00:00.199046 [/Devices/AudioSniffer/] (level 2)
00:00:00.199047
00:00:00.199047 [/Devices/AudioSniffer/0/] (level 3)
00:00:00.199049
00:00:00.199049 [/Devices/AudioSniffer/0/Config/] (level 4)
00:00:00.199050
00:00:00.199051 [/Devices/AudioSniffer/0/LUN#0/] (level 4)
00:00:00.199052 Driver <string> = "MainAudioSniffer" (cb=17)
00:00:00.199053
00:00:00.199053 [/Devices/AudioSniffer/0/LUN#0/Config/] (level 5)
00:00:00.199054 Object <integer> = 0x000000080190ab00 (34 385 996 544)
00:00:00.199056
00:00:00.199056 [/Devices/VMMDev/] (level 2)
00:00:00.199057
00:00:00.199057 [/Devices/VMMDev/0/] (level 3)
00:00:00.199059 PCIBusNo <integer> = 0x0000000000000000 (0)
00:00:00.199060 PCIDeviceNo <integer> = 0x0000000000000004 (4)
00:00:00.199061 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:00.199061 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199062
00:00:00.199063 [/Devices/VMMDev/0/Config/] (level 4)
00:00:00.199064 GuestCoreDumpDir <string> = "/Storage/VirtualBox VMs/FreeBSD 9.2 i386/Snapshots" (cb=51)
00:00:00.199065 RamSize <integer> = 0x0000000200000000 (8 589 934 592, 8 GB)
00:00:00.199066
00:00:00.199067 [/Devices/VMMDev/0/LUN#0/] (level 4)
00:00:00.199068 Driver <string> = "HGCM" (cb=5)
00:00:00.199069
00:00:00.199069 [/Devices/VMMDev/0/LUN#0/Config/] (level 5)
00:00:00.199071 Object <integer> = 0x0000000807462040 (34 481 774 656)
00:00:00.199072
00:00:00.199072 [/Devices/VMMDev/0/LUN#999/] (level 4)
00:00:00.199073 Driver <string> = "MainStatus" (cb=11)
00:00:00.199074
00:00:00.199074 [/Devices/VMMDev/0/LUN#999/Config/] (level 5)
00:00:00.199076 First <integer> = 0x0000000000000000 (0)
00:00:00.199077 Last <integer> = 0x0000000000000000 (0)
00:00:00.199078 papLeds <integer> = 0x000000080508fdf0 (34 444 213 744)
00:00:00.199079
00:00:00.199079 [/Devices/acpi/] (level 2)
00:00:00.199080
00:00:00.199080 [/Devices/acpi/0/] (level 3)
00:00:00.199082 PCIBusNo <integer> = 0x0000000000000000 (0)
00:00:00.199083 PCIDeviceNo <integer> = 0x0000000000000007 (7)
00:00:00.199083 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:00.199084 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199085
00:00:00.199085 [/Devices/acpi/0/Config/] (level 4)
00:00:00.199087 CpuHotPlug <integer> = 0x0000000000000000 (0)
00:00:00.199091 FdcEnabled <integer> = 0x0000000000000000 (0)
00:00:00.199092 HostBusPciAddress <integer> = 0x0000000000000000 (0)
00:00:00.199093 HpetEnabled <integer> = 0x0000000000000000 (0)
00:00:00.199094 IOAPIC <integer> = 0x0000000000000001 (1)
00:00:00.199095 IocPciAddress <integer> = 0x0000000000010000 (65 536)
00:00:00.199096 NumCPUs <integer> = 0x0000000000000004 (4)
00:00:00.199097 RamHoleSize <integer> = 0x0000000020000000 (536 870 912, 512 MB)
00:00:00.199099 RamSize <integer> = 0x0000000200000000 (8 589 934 592, 8 GB)
00:00:00.199100 Serial0IoPortBase <integer> = 0x0000000000000000 (0)
00:00:00.199101 Serial0Irq <integer> = 0x0000000000000000 (0)
00:00:00.199102 Serial1IoPortBase <integer> = 0x0000000000000000 (0)
00:00:00.199103 Serial1Irq <integer> = 0x0000000000000000 (0)
00:00:00.199104 ShowCpu <integer> = 0x0000000000000001 (1)
00:00:00.199105 ShowRtc <integer> = 0x0000000000000000 (0)
00:00:00.199106 SmcEnabled <integer> = 0x0000000000000000 (0)
00:00:00.199107
00:00:00.199107 [/Devices/acpi/0/LUN#0/] (level 4)
00:00:00.199109 Driver <string> = "ACPIHost" (cb=9)
00:00:00.199109
00:00:00.199110 [/Devices/acpi/0/LUN#0/Config/] (level 5)
00:00:00.199111
00:00:00.199111 [/Devices/acpi/0/LUN#1/] (level 4)
00:00:00.199113 Driver <string> = "ACPICpu" (cb=8)
00:00:00.199113
00:00:00.199114 [/Devices/acpi/0/LUN#1/Config/] (level 5)
00:00:00.199115
00:00:00.199115 [/Devices/acpi/0/LUN#2/] (level 4)
00:00:00.199117 Driver <string> = "ACPICpu" (cb=8)
00:00:00.199117
00:00:00.199118 [/Devices/acpi/0/LUN#2/Config/] (level 5)
00:00:00.199119
00:00:00.199119 [/Devices/acpi/0/LUN#3/] (level 4)
00:00:00.199121 Driver <string> = "ACPICpu" (cb=8)
00:00:00.199121
00:00:00.199122 [/Devices/acpi/0/LUN#3/Config/] (level 5)
00:00:00.199123
00:00:00.199123 [/Devices/apic/] (level 2)
00:00:00.199124
00:00:00.199125 [/Devices/apic/0/] (level 3)
00:00:00.199126 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199127
00:00:00.199127 [/Devices/apic/0/Config/] (level 4)
00:00:00.199128 IOAPIC <integer> = 0x0000000000000001 (1)
00:00:00.199129 NumCPUs <integer> = 0x0000000000000004 (4)
00:00:00.199130
00:00:00.199130 [/Devices/e1000/] (level 2)
00:00:00.199131
00:00:00.199131 [/Devices/e1000/0/] (level 3)
00:00:00.199133 PCIBusNo <integer> = 0x0000000000000000 (0)
00:00:00.199134 PCIDeviceNo <integer> = 0x0000000000000011 (17)
00:00:00.199135 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:00.199135 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199136
00:00:00.199137 [/Devices/e1000/0/Config/] (level 4)
00:00:00.199138 AdapterType <integer> = 0x0000000000000002 (2)
00:00:00.199139 CableConnected <integer> = 0x0000000000000001 (1)
00:00:00.199140 LineSpeed <integer> = 0x0000000000000000 (0)
00:00:00.199140 MAC <bytes> = "08 00 27 30 f8 48" (cb=6)
00:00:00.199142
00:00:00.199143 [/Devices/e1000/0/LUN#0/] (level 4)
00:00:00.199144 Driver <string> = "IntNet" (cb=7)
00:00:00.199145
00:00:00.199145 [/Devices/e1000/0/LUN#0/Config/] (level 5)
00:00:00.199147 IfPolicyPromisc <string> = "deny" (cb=5)
00:00:00.199147 IgnoreConnectFailure <integer> = 0x0000000000000000 (0)
00:00:00.199148 Network <string> = "HostInterfaceNetworking-em0" (cb=28)
00:00:00.199149 Trunk <string> = "em0" (cb=4)
00:00:00.199150 TrunkType <integer> = 0x0000000000000003 (3)
00:00:00.199152
00:00:00.199152 [/Devices/e1000/0/LUN#999/] (level 4)
00:00:00.199153 Driver <string> = "MainStatus" (cb=11)
00:00:00.199154
00:00:00.199154 [/Devices/e1000/0/LUN#999/Config/] (level 5)
00:00:00.199156 First <integer> = 0x0000000000000000 (0)
00:00:00.199156 Last <integer> = 0x0000000000000000 (0)
00:00:00.199157 papLeds <integer> = 0x000000080508fcd0 (34 444 213 456)
00:00:00.199158
00:00:00.199159 [/Devices/i8254/] (level 2)
00:00:00.199160
00:00:00.199161 [/Devices/i8254/0/] (level 3)
00:00:00.199162
00:00:00.199163 [/Devices/i8254/0/Config/] (level 4)
00:00:00.199164
00:00:00.199164 [/Devices/i8259/] (level 2)
00:00:00.199165
00:00:00.199166 [/Devices/i8259/0/] (level 3)
00:00:00.199167 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199168
00:00:00.199168 [/Devices/i8259/0/Config/] (level 4)
00:00:00.199169
00:00:00.199170 [/Devices/ioapic/] (level 2)
00:00:00.199171
00:00:00.199171 [/Devices/ioapic/0/] (level 3)
00:00:00.199172 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199173
00:00:00.199173 [/Devices/ioapic/0/Config/] (level 4)
00:00:00.199175 NumCPUs <integer> = 0x0000000000000004 (4)
00:00:00.199175
00:00:00.199176 [/Devices/mc146818/] (level 2)
00:00:00.199177
00:00:00.199177 [/Devices/mc146818/0/] (level 3)
00:00:00.199178
00:00:00.199179 [/Devices/mc146818/0/Config/] (level 4)
00:00:00.199180 UseUTC <integer> = 0x0000000000000000 (0)
00:00:00.199181
00:00:00.199181 [/Devices/parallel/] (level 2)
00:00:00.199182
00:00:00.199182 [/Devices/pcarch/] (level 2)
00:00:00.199183
00:00:00.199184 [/Devices/pcarch/0/] (level 3)
00:00:00.199185 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199185
00:00:00.199186 [/Devices/pcarch/0/Config/] (level 4)
00:00:00.199187
00:00:00.199187 [/Devices/pcbios/] (level 2)
00:00:00.199188
00:00:00.199189 [/Devices/pcbios/0/] (level 3)
00:00:00.199190 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199191
00:00:00.199191 [/Devices/pcbios/0/Config/] (level 4)
00:00:00.199192 BootDevice0 <string> = "IDE" (cb=4)
00:00:00.199193 BootDevice1 <string> = "NONE" (cb=5)
00:00:00.199194 BootDevice2 <string> = "NONE" (cb=5)
00:00:00.199195 BootDevice3 <string> = "NONE" (cb=5)
00:00:00.199195 FloppyDevice <string> = "i82078" (cb=7)
00:00:00.199196 HardDiskDevice <string> = "piix3ide" (cb=9)
00:00:00.199197 IOAPIC <integer> = 0x0000000000000001 (1)
00:00:00.199198 McfgBase <integer> = 0x0000000000000000 (0)
00:00:00.199199 McfgLength <integer> = 0x0000000000000000 (0)
00:00:00.199200 NumCPUs <integer> = 0x0000000000000004 (4)
00:00:00.199201 PXEDebug <integer> = 0x0000000000000000 (0)
00:00:00.199202 RamHoleSize <integer> = 0x0000000020000000 (536 870 912, 512 MB)
00:00:00.199203 RamSize <integer> = 0x0000000200000000 (8 589 934 592, 8 GB)
00:00:00.199205 UUID <bytes> = "a5 41 31 a8 2a 95 d0 4f a3 94 0d e8 68 95 8e 3b" (cb=16)
00:00:00.199208
00:00:00.199208 [/Devices/pcbios/0/Config/NetBoot/] (level 5)
00:00:00.199209
00:00:00.199210 [/Devices/pcbios/0/Config/NetBoot/0/] (level 6)
00:00:00.199211 NIC <integer> = 0x0000000000000000 (0)
00:00:00.199212 PCIBusNo <integer> = 0x0000000000000000 (0)
00:00:00.199213 PCIDeviceNo <integer> = 0x0000000000000011 (17)
00:00:00.199214 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:00.199215
00:00:00.199215 [/Devices/pci/] (level 2)
00:00:00.199216
00:00:00.199217 [/Devices/pci/0/] (level 3)
00:00:00.199218 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199219
00:00:00.199219 [/Devices/pci/0/Config/] (level 4)
00:00:00.199220 IOAPIC <integer> = 0x0000000000000001 (1)
00:00:00.199221
00:00:00.199221 [/Devices/pckbd/] (level 2)
00:00:00.199222
00:00:00.199223 [/Devices/pckbd/0/] (level 3)
00:00:00.199224 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199225
00:00:00.199225 [/Devices/pckbd/0/Config/] (level 4)
00:00:00.199226
00:00:00.199227 [/Devices/pckbd/0/LUN#0/] (level 4)
00:00:00.199228 Driver <string> = "KeyboardQueue" (cb=14)
00:00:00.199229
00:00:00.199229 [/Devices/pckbd/0/LUN#0/AttachedDriver/] (level 5)
00:00:00.199230 Driver <string> = "MainKeyboard" (cb=13)
00:00:00.199231
00:00:00.199232 [/Devices/pckbd/0/LUN#0/AttachedDriver/Config/] (level 6)
00:00:00.199233 Object <integer> = 0x0000000805087300 (34 444 178 176)
00:00:00.199234
00:00:00.199235 [/Devices/pckbd/0/LUN#0/Config/] (level 5)
00:00:00.199236 QueueSize <integer> = 0x0000000000000040 (64)
00:00:00.199237
00:00:00.199238 [/Devices/pckbd/0/LUN#1/] (level 4)
00:00:00.199240 Driver <string> = "MouseQueue" (cb=11)
00:00:00.199240
00:00:00.199241 [/Devices/pckbd/0/LUN#1/AttachedDriver/] (level 5)
00:00:00.199242 Driver <string> = "MainMouse" (cb=10)
00:00:00.199243
00:00:00.199243 [/Devices/pckbd/0/LUN#1/AttachedDriver/Config/] (level 6)
00:00:00.199245 Object <integer> = 0x00000008050873c0 (34 444 178 368)
00:00:00.199246
00:00:00.199246 [/Devices/pckbd/0/LUN#1/Config/] (level 5)
00:00:00.199248 QueueSize <integer> = 0x0000000000000080 (128)
00:00:00.199248
00:00:00.199249 [/Devices/pcnet/] (level 2)
00:00:00.199250
00:00:00.199250 [/Devices/piix3ide/] (level 2)
00:00:00.199251
00:00:00.199252 [/Devices/piix3ide/0/] (level 3)
00:00:00.199253 PCIBusNo <integer> = 0x0000000000000000 (0)
00:00:00.199254 PCIDeviceNo <integer> = 0x0000000000000001 (1)
00:00:00.199254 PCIFunctionNo <integer> = 0x0000000000000001 (1)
00:00:00.199255 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199256
00:00:00.199256 [/Devices/piix3ide/0/Config/] (level 4)
00:00:00.199258 Type <string> = "ICH6" (cb=5)
00:00:00.199258
00:00:00.199259 [/Devices/piix3ide/0/Config/PrimaryMaster/] (level 5)
00:00:00.199260 NonRotationalMedium <integer> = 0x0000000000000000 (0)
00:00:00.199261
00:00:00.199261 [/Devices/piix3ide/0/Config/PrimarySlave/] (level 5)
00:00:00.199263 NonRotationalMedium <integer> = 0x0000000000000000 (0)
00:00:00.199264
00:00:00.199264 [/Devices/piix3ide/0/LUN#0/] (level 4)
00:00:00.199265 Driver <string> = "Block" (cb=6)
00:00:00.199266
00:00:00.199266 [/Devices/piix3ide/0/LUN#0/AttachedDriver/] (level 5)
00:00:00.199268 Driver <string> = "VD" (cb=3)
00:00:00.199268
00:00:00.199269 [/Devices/piix3ide/0/LUN#0/AttachedDriver/Config/] (level 6)
00:00:00.199270 Format <string> = "VDI" (cb=4)
00:00:00.199271 Path <string> = "/Storage/VirtualBox VMs/FreeBSD 9.2 i386/FreeBSD 9.2 i386.vdi" (cb=62)
00:00:00.199272 Type <string> = "HardDisk" (cb=9)
00:00:00.199273
00:00:00.199273 [/Devices/piix3ide/0/LUN#0/Config/] (level 5)
00:00:00.199274 Mountable <integer> = 0x0000000000000000 (0)
00:00:00.199275 Type <string> = "HardDisk" (cb=9)
00:00:00.199276
00:00:00.199276 [/Devices/piix3ide/0/LUN#1/] (level 4)
00:00:00.199278 Driver <string> = "Block" (cb=6)
00:00:00.199278
00:00:00.199279 [/Devices/piix3ide/0/LUN#1/AttachedDriver/] (level 5)
00:00:00.199280 Driver <string> = "VD" (cb=3)
00:00:00.199281
00:00:00.199281 [/Devices/piix3ide/0/LUN#1/AttachedDriver/Config/] (level 6)
00:00:00.199283 Format <string> = "VDI" (cb=4)
00:00:00.199283 Path <string> = "/Storage/VirtualBox VMs/FreeBSD 9.2 i386/build 9.2 i386.vdi" (cb=60)
00:00:00.199284 Type <string> = "HardDisk" (cb=9)
00:00:00.199285
00:00:00.199285 [/Devices/piix3ide/0/LUN#1/Config/] (level 5)
00:00:00.199287 Mountable <integer> = 0x0000000000000000 (0)
00:00:00.199287 Type <string> = "HardDisk" (cb=9)
00:00:00.199288
00:00:00.199288 [/Devices/piix3ide/0/LUN#999/] (level 4)
00:00:00.199290 Driver <string> = "MainStatus" (cb=11)
00:00:00.199290
00:00:00.199291 [/Devices/piix3ide/0/LUN#999/Config/] (level 5)
00:00:00.199292 DeviceInstance <string> = "piix3ide/0" (cb=11)
00:00:00.199293 First <integer> = 0x0000000000000000 (0)
00:00:00.199294 Last <integer> = 0x0000000000000003 (3)
00:00:00.199296 pConsole <integer> = 0x000000080508f800 (34 444 212 224)
00:00:00.199297 papLeds <integer> = 0x000000080508fb00 (34 444 212 992)
00:00:00.199299 pmapMediumAttachments <integer> = 0x000000080508fe08 (34 444 213 768)
00:00:00.199300
00:00:00.199300 [/Devices/serial/] (level 2)
00:00:00.199301
00:00:00.199302 [/Devices/vga/] (level 2)
00:00:00.199303
00:00:00.199303 [/Devices/vga/0/] (level 3)
00:00:00.199304 PCIBusNo <integer> = 0x0000000000000000 (0)
00:00:00.199305 PCIDeviceNo <integer> = 0x0000000000000002 (2)
00:00:00.199306 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:00.199307 Trusted <integer> = 0x0000000000000001 (1)
00:00:00.199309
00:00:00.199309 [/Devices/vga/0/Config/] (level 4)
00:00:00.199310 CustomVideoModes <integer> = 0x0000000000000000 (0)
00:00:00.199311 FadeIn <integer> = 0x0000000000000001 (1)
00:00:00.199312 FadeOut <integer> = 0x0000000000000001 (1)
00:00:00.199313 HeightReduction <integer> = 0x0000000000000000 (0)
00:00:00.199314 LogoFile <string> = "" (cb=1)
00:00:00.199315 LogoTime <integer> = 0x0000000000000000 (0)
00:00:00.199316 MonitorCount <integer> = 0x0000000000000001 (1)
00:00:00.199317 ShowBootMenu <integer> = 0x0000000000000002 (2)
00:00:00.199318 VRamSize <integer> = 0x0000000000700000 (7 340 032, 7 MB)
00:00:00.199319
00:00:00.199320 [/Devices/vga/0/LUN#0/] (level 4)
00:00:00.199321 Driver <string> = "MainDisplay" (cb=12)
00:00:00.199322
00:00:00.199322 [/Devices/vga/0/LUN#0/Config/] (level 5)
00:00:00.199323 Object <integer> = 0x000000080520e000 (34 445 778 944)
00:00:00.199325
00:00:00.199325 [/Devices/virtio-net/] (level 2)
00:00:00.199326
00:00:00.199326 [/EM/] (level 1)
00:00:00.199327 TripleFaultReset <integer> = 0x0000000000000000 (0)
00:00:00.199328
00:00:00.199328 [/HM/] (level 1)
00:00:00.199329 64bitEnabled <integer> = 0x0000000000000001 (1)
00:00:00.199330 EnableLargePages <integer> = 0x0000000000000001 (1)
00:00:00.199331 EnableNestedPaging <integer> = 0x0000000000000000 (0)
00:00:00.199332 EnableUX <integer> = 0x0000000000000001 (1)
00:00:00.199333 EnableVPID <integer> = 0x0000000000000001 (1)
00:00:00.199334 Exclusive <integer> = 0x0000000000000001 (1)
00:00:00.199335 HMForced <integer> = 0x0000000000000001 (1)
00:00:00.199336
00:00:00.199336 [/MM/] (level 1)
00:00:00.199337 CanUseLargerHeap <integer> = 0x0000000000000000 (0)
00:00:00.199338
00:00:00.199338 [/PDM/] (level 1)
00:00:00.199339
00:00:00.199340 [/PDM/AsyncCompletion/] (level 2)
00:00:00.199341
00:00:00.199341 [/PDM/AsyncCompletion/File/] (level 3)
00:00:00.199342
00:00:00.199343 [/PDM/AsyncCompletion/File/BwGroups/] (level 4)
00:00:00.199344
00:00:00.199344 [/PDM/BlkCache/] (level 2)
00:00:00.199345 CacheSize <integer> = 0x0000000000500000 (5 242 880, 5 MB)
00:00:00.199347
00:00:00.199347 [/PDM/Devices/] (level 2)
00:00:00.199348
00:00:00.199348 [/PDM/Drivers/] (level 2)
00:00:00.199349
00:00:00.199350 [/PDM/Drivers/VBoxC/] (level 3)
00:00:00.199351 Path <string> = "/usr/local/lib/virtualbox/components/VBoxC" (cb=43)
00:00:00.199352
00:00:00.199352 [/PDM/NetworkShaper/] (level 2)
00:00:00.199353
00:00:00.199353 [/PDM/NetworkShaper/BwGroups/] (level 3)
00:00:00.199354
00:00:00.199355 [/TM/] (level 1)
00:00:00.199356 UTCOffset <integer> = 0x0000000000000000 (0)
00:00:00.199356
00:00:00.199357 ********************* End of CFGM dump **********************
00:00:00.199364 VM: fHMEnabled=true (configured) fRecompileUser=false fRecompileSupervisor=false
00:00:00.199366 VM: fRawRing1Enabled=false CSAM=true PATM=true
00:00:00.199490 HMR3Init: VT-x w/ nested paging
00:00:00.199513 MM: cbHyperHeap=0x140000 (1310720)
00:00:00.202669 CPUM: Matched host CPU INTEL 0x6/0x3c/0x3 Intel_Core7_Haswell with CPU DB entry 'Intel Core i5-3570' (INTEL 0x6/0x3a/0x9 Intel_Core7_IvyBridge).
00:00:00.203151 Debug: HCPhysInterPD=0000000017e36000 HCPhysInterPaePDPT=0000000095ca8000 HCPhysInterPaePML4=00000000b1e8b000
00:00:00.203165 Debug: apInterPTs={000000009f7ae000,0000000021e36000} apInterPaePTs={000000062efe2000,000000079d9a7000} apInterPaePDs={0000000676ec2000,00000002ee5ce000,000000018122e000,000000015d532000} pInterPaePDPT64=000000009a20d000
00:00:00.203175 Host paging mode: AMD64+PGE+NX
00:00:00.203187 PGMPool: cMaxPages=4144 (u64MaxPages=4136)
00:00:00.203195 pgmR3PoolInit: cMaxPages=0x1030 cMaxUsers=0x2060 cMaxPhysExts=0x2000 fCacheEnable=true
00:00:00.250971 TM: GIP - u32Mode=1 (SyncTSC) u32UpdateHz=99
00:00:00.284014 TM: cTSCTicksPerSecond=0xb6d078d5 (3 067 115 733) fTSCVirtualized=true fTSCUseRealTSC=false
00:00:00.284018 TM: fMaybeUseOffsettedHostTSC=false TSCTiedToExecution=false TSCNotTiedToHalt=false
00:00:00.284211 CoreCode: R3=0000000800817000 R0=ffffff826d458000 RC=a1041000 Phys=000000006e6a6000 cb=0x1000
00:00:00.284399 AIO: Async I/O manager not supported (rc=VERR_NOT_SUPPORTED). Falling back to simple manager
00:00:00.284469 BlkCache: Cache successfully initialised. Cache size is 5242880 bytes
00:00:00.284479 BlkCache: Cache commit interval is 10000 ms
00:00:00.284486 BlkCache: Cache commit threshold is 2621440 bytes
00:00:00.297315 [SMP] BIOS with 4 CPUs
00:00:00.300424 SUP: Loaded VBoxDDR0.r0 (/usr/local/lib/virtualbox/VBoxDDR0.r0) at 0xffffffff81c60020 - ModuleInit at 0000000000000000 and ModuleTerm at 0000000000000000
00:00:00.301560 SUP: Loaded VBoxDD2R0.r0 (/usr/local/lib/virtualbox/VBoxDD2R0.r0) at 0xffffffff81c7e020 - ModuleInit at 0000000000000000 and ModuleTerm at 0000000000000000
00:00:00.301587 Activating Local APIC
00:00:00.301598 CPUM: SetGuestCpuIdFeature: Enabled APIC
00:00:00.301913 PIT: mode=3 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:00.303176 Shared Folders service loaded.
00:00:00.304703 DrvBlock: Flushes will be ignored
00:00:00.304729 DrvBlock: Async flushes will be passed to the disk
00:00:00.304821 VDInit finished
00:00:00.451173 VD: Opening the disk took 146427031 ns
00:00:00.451218 PIIX3 ATA: LUN#0: disk, PCHS=16383/16/63, total number of sectors 105276992
00:00:00.451237 DrvBlock: Flushes will be ignored
00:00:00.451245 DrvBlock: Async flushes will be passed to the disk
00:00:00.451687 VD: Opening the disk took 427522 ns
00:00:00.451722 PIIX3 ATA: LUN#1: disk, PCHS=16383/16/63, total number of sectors 21474832
00:00:00.451752 PIIX3 ATA: LUN#2: no unit
00:00:00.451762 PIIX3 ATA: LUN#3: no unit
00:00:00.451777 PIIX3 ATA: Ctl#1: finished processing RESET
00:00:00.451790 PIIX3 ATA: Ctl#0: finished processing RESET
00:00:00.451889 IntNet#0: szNetwork={HostInterfaceNetworking-em0} enmTrunkType=3 szTrunk={em0} fFlags=0x8000 cbRecv=325632 cbSend=196608 fIgnoreConnectFailure=false
00:00:00.452517 DevPcBios: ATA LUN#0 LCHS=1024/255/63
00:00:00.452532 DevPcBios: ATA LUN#1 LCHS=1024/255/63
00:00:00.452800 PGM: The CPU physical address width is 39 bits
00:00:00.452812 PGMR3InitFinalize: 4 MB PSE mask 0000007fffffffff
00:00:00.456243 VMM: Thread-context hooks unavailable.
00:00:00.456274 HM: Using VT-x implementation 2.0!
00:00:00.456274 HM: Host CR4 = 0x1506f0
00:00:00.456276 HM: Host EFER = 0xd01
00:00:00.456276 HM: MSR_IA32_FEATURE_CONTROL = 0x5
00:00:00.456277 HM: MSR_IA32_VMX_BASIC_INFO = 0xda040000000012
00:00:00.456278 HM: VMCS id = 0x12
00:00:00.456279 HM: VMCS size = 1024 bytes
00:00:00.456280 HM: VMCS physical address limit = None
00:00:00.456281 HM: VMCS memory type = 0x6
00:00:00.456281 HM: Dual-monitor treatment support = true
00:00:00.456282 HM: OUTS & INS instruction-info = true
00:00:00.456283 HM: Max resume loops = 8192
00:00:00.456283 HM: MSR_IA32_VMX_PINBASED_CTLS = 0x7f00000016
00:00:00.456284 HM: VMX_VMCS_CTRL_PIN_EXEC_EXT_INT_EXIT
00:00:00.456285 HM: VMX_VMCS_CTRL_PIN_EXEC_NMI_EXIT
00:00:00.456285 HM: VMX_VMCS_CTRL_PIN_EXEC_VIRTUAL_NMI
00:00:00.456286 HM: VMX_VMCS_CTRL_PIN_EXEC_PREEMPT_TIMER
00:00:00.456286 HM: MSR_IA32_VMX_PROCBASED_CTLS = 0xfff9fffe0401e172
00:00:00.456287 HM: VMX_VMCS_CTRL_PROC_EXEC_INT_WINDOW_EXIT
00:00:00.456288 HM: VMX_VMCS_CTRL_PROC_EXEC_USE_TSC_OFFSETTING
00:00:00.456288 HM: VMX_VMCS_CTRL_PROC_EXEC_HLT_EXIT
00:00:00.456289 HM: VMX_VMCS_CTRL_PROC_EXEC_INVLPG_EXIT
00:00:00.456289 HM: VMX_VMCS_CTRL_PROC_EXEC_MWAIT_EXIT
00:00:00.456290 HM: VMX_VMCS_CTRL_PROC_EXEC_RDPMC_EXIT
00:00:00.456290 HM: VMX_VMCS_CTRL_PROC_EXEC_RDTSC_EXIT
00:00:00.456291 HM: VMX_VMCS_CTRL_PROC_EXEC_CR3_LOAD_EXIT
00:00:00.456292 HM: VMX_VMCS_CTRL_PROC_EXEC_CR3_LOAD_EXIT (must be set)
00:00:00.456292 HM: VMX_VMCS_CTRL_PROC_EXEC_CR3_STORE_EXIT
00:00:00.456293 HM: VMX_VMCS_CTRL_PROC_EXEC_CR3_STORE_EXIT (must be set)
00:00:00.456293 HM: VMX_VMCS_CTRL_PROC_EXEC_CR8_LOAD_EXIT
00:00:00.456293 HM: VMX_VMCS_CTRL_PROC_EXEC_CR8_STORE_EXIT
00:00:00.456294 HM: VMX_VMCS_CTRL_PROC_EXEC_USE_TPR_SHADOW
00:00:00.456295 HM: VMX_VMCS_CTRL_PROC_EXEC_NMI_WINDOW_EXIT
00:00:00.456295 HM: VMX_VMCS_CTRL_PROC_EXEC_MOV_DR_EXIT
00:00:00.456295 HM: VMX_VMCS_CTRL_PROC_EXEC_UNCOND_IO_EXIT
00:00:00.456296 HM: VMX_VMCS_CTRL_PROC_EXEC_USE_IO_BITMAPS
00:00:00.456296 HM: VMX_VMCS_CTRL_PROC_EXEC_MONITOR_TRAP_FLAG
00:00:00.456297 HM: VMX_VMCS_CTRL_PROC_EXEC_USE_MSR_BITMAPS
00:00:00.456297 HM: VMX_VMCS_CTRL_PROC_EXEC_MONITOR_EXIT
00:00:00.456298 HM: VMX_VMCS_CTRL_PROC_EXEC_PAUSE_EXIT
00:00:00.456298 HM: VMX_VMCS_CTRL_PROC_EXEC_USE_SECONDARY_EXEC_CTRL
00:00:00.456299 HM: MSR_IA32_VMX_PROCBASED_CTLS2 = 0x3cff00000000
00:00:00.456300 HM: VMX_VMCS_CTRL_PROC_EXEC2_VIRT_APIC
00:00:00.456300 HM: VMX_VMCS_CTRL_PROC_EXEC2_EPT
00:00:00.456301 HM: VMX_VMCS_CTRL_PROC_EXEC2_DESCRIPTOR_TABLE_EXIT
00:00:00.456301 HM: VMX_VMCS_CTRL_PROC_EXEC2_RDTSCP
00:00:00.456302 HM: VMX_VMCS_CTRL_PROC_EXEC2_VIRT_X2APIC
00:00:00.456302 HM: VMX_VMCS_CTRL_PROC_EXEC2_VPID
00:00:00.456302 HM: VMX_VMCS_CTRL_PROC_EXEC2_WBINVD_EXIT
00:00:00.456303 HM: VMX_VMCS_CTRL_PROC_EXEC2_UNRESTRICTED_GUEST
00:00:00.456303 HM: VMX_VMCS_CTRL_PROC_EXEC2_PAUSE_LOOP_EXIT
00:00:00.456304 HM: VMX_VMCS_CTRL_PROC_EXEC2_RDRAND_EXIT
00:00:00.456304 HM: VMX_VMCS_CTRL_PROC_EXEC2_INVPCID
00:00:00.456305 HM: VMX_VMCS_CTRL_PROC_EXEC2_VMFUNC
00:00:00.456305 HM: MSR_IA32_VMX_ENTRY_CTLS = 0xffff000011ff
00:00:00.456306 HM: VMX_VMCS_CTRL_ENTRY_LOAD_DEBUG
00:00:00.456307 HM: VMX_VMCS_CTRL_ENTRY_LOAD_DEBUG (must be set)
00:00:00.456307 HM: VMX_VMCS_CTRL_ENTRY_IA32E_MODE_GUEST
00:00:00.456308 HM: VMX_VMCS_CTRL_ENTRY_ENTRY_SMM
00:00:00.456308 HM: VMX_VMCS_CTRL_ENTRY_DEACTIVATE_DUALMON
00:00:00.456309 HM: VMX_VMCS_CTRL_ENTRY_LOAD_GUEST_PERF_MSR
00:00:00.456309 HM: VMX_VMCS_CTRL_ENTRY_LOAD_GUEST_PAT_MSR
00:00:00.456310 HM: VMX_VMCS_CTRL_ENTRY_LOAD_GUEST_EFER_MSR
00:00:00.456310 HM: MSR_IA32_VMX_EXIT_CTLS = 0x7fffff00036dff
00:00:00.456311 HM: VMX_VMCS_CTRL_EXIT_SAVE_DEBUG
00:00:00.456312 HM: VMX_VMCS_CTRL_EXIT_SAVE_DEBUG (must be set)
00:00:00.456312 HM: VMX_VMCS_CTRL_EXIT_HOST_ADDR_SPACE_SIZE
00:00:00.456312 HM: VMX_VMCS_CTRL_EXIT_LOAD_PERF_MSR
00:00:00.456313 HM: VMX_VMCS_CTRL_EXIT_ACK_EXT_INT
00:00:00.456313 HM: VMX_VMCS_CTRL_EXIT_SAVE_GUEST_PAT_MSR
00:00:00.456314 HM: VMX_VMCS_CTRL_EXIT_LOAD_HOST_PAT_MSR
00:00:00.456314 HM: VMX_VMCS_CTRL_EXIT_SAVE_GUEST_EFER_MSR
00:00:00.456315 HM: VMX_VMCS_CTRL_EXIT_LOAD_HOST_EFER_MSR
00:00:00.456315 HM: VMX_VMCS_CTRL_EXIT_SAVE_VMX_PREEMPT_TIMER
00:00:00.456316 HM: MSR_IA32_VMX_EPT_VPID_CAP = 0xf0106334141
00:00:00.456316 HM: MSR_IA32_VMX_EPT_VPID_CAP_RWX_X_ONLY
00:00:00.456317 HM: MSR_IA32_VMX_EPT_VPID_CAP_GAW_48_BITS
00:00:00.456317 HM: MSR_IA32_VMX_EPT_VPID_CAP_EMT_UC
00:00:00.456318 HM: MSR_IA32_VMX_EPT_VPID_CAP_EMT_WB
00:00:00.456318 HM: MSR_IA32_VMX_EPT_VPID_CAP_SP_21_BITS
00:00:00.456319 HM: MSR_IA32_VMX_EPT_VPID_CAP_SP_30_BITS
00:00:00.456319 HM: MSR_IA32_VMX_EPT_VPID_CAP_INVEPT
00:00:00.456320 HM: MSR_IA32_VMX_EPT_VPID_CAP_INVEPT_SINGLE_CONTEXT
00:00:00.456320 HM: MSR_IA32_VMX_EPT_VPID_CAP_INVEPT_ALL_CONTEXTS
00:00:00.456320 HM: MSR_IA32_VMX_EPT_VPID_CAP_INVVPID
00:00:00.456321 HM: MSR_IA32_VMX_EPT_VPID_CAP_INVVPID_INDIV_ADDR
00:00:00.456321 HM: MSR_IA32_VMX_EPT_VPID_CAP_INVVPID_SINGLE_CONTEXT
00:00:00.456322 HM: MSR_IA32_VMX_EPT_VPID_CAP_INVVPID_ALL_CONTEXTS
00:00:00.456322 HM: MSR_IA32_VMX_EPT_VPID_CAP_INVVPID_SINGLE_CONTEXT_RETAIN_GLOBALS
00:00:00.456323 HM: MSR_IA32_VMX_MISC = 0x300481e5
00:00:00.456323 HM: MSR_IA32_VMX_MISC_PREEMPT_TSC_BIT = 0x5
00:00:00.456324 HM: MSR_IA32_VMX_MISC_STORE_EFERLMA_VMEXIT = true
00:00:00.456325 HM: MSR_IA32_VMX_MISC_ACTIVITY_STATES = 0x7
00:00:00.456325 HM: MSR_IA32_VMX_MISC_CR3_TARGET = 0x4
00:00:00.456326 HM: MSR_IA32_VMX_MISC_MAX_MSR = 512
00:00:00.456326 HM: MSR_IA32_VMX_MISC_RDMSR_SMBASE_MSR_SMM = true
00:00:00.456327 HM: MSR_IA32_VMX_MISC_SMM_MONITOR_CTL_B2 = true
00:00:00.456327 HM: MSR_IA32_VMX_MISC_VMWRITE_VMEXIT_INFO = true
00:00:00.456328 HM: MSR_IA32_VMX_MISC_MSEG_ID = 0x0
00:00:00.456329 HM: MSR_IA32_VMX_CR0_FIXED0 = 0x80000021
00:00:00.456329 HM: MSR_IA32_VMX_CR0_FIXED1 = 0xffffffff
00:00:00.456330 HM: MSR_IA32_VMX_CR4_FIXED0 = 0x2000
00:00:00.456331 HM: MSR_IA32_VMX_CR4_FIXED1 = 0x1727ff
00:00:00.456331 HM: MSR_IA32_VMX_VMCS_ENUM = 0x2a
00:00:00.456332 HM: MSR_IA32_VMX_VMCS_ENUM_HIGHEST_INDEX = 0x15
00:00:00.456333 HM: MSR_A32_VMX_VMFUNC = 0x1
00:00:00.456333 HM: VMX_VMCS_CTRL_VMFUNC_EPTP_SWITCHING
00:00:00.456334 HM: APIC-access page physaddr = 0x00000000b758f000
00:00:00.456335 HM: VCPU 0: MSR bitmap physaddr = 0x000000006e13c000
00:00:00.456336 HM: VCPU 0: VMCS physaddr = 0x000000004b9d6000
00:00:00.456336 HM: VCPU 1: MSR bitmap physaddr = 0x00000000a67ca000
00:00:00.456337 HM: VCPU 1: VMCS physaddr = 0x0000000033192000
00:00:00.456338 HM: VCPU 2: MSR bitmap physaddr = 0x000000002ebe3000
00:00:00.456339 HM: VCPU 2: VMCS physaddr = 0x00000000d4494000
00:00:00.456340 HM: VCPU 3: MSR bitmap physaddr = 0x00000000b7cad000
00:00:00.456340 HM: VCPU 3: VMCS physaddr = 0x000000009b7af000
00:00:00.456343 HM: Real Mode TSS guest physaddr = 0x00000000f0400000
00:00:00.456344 HM: Non-Paging Mode EPT CR3 = 0x00000000f0403000
00:00:00.456345 HM: Guest support: 32-bit and 64-bit.
00:00:00.456349 HM: VMX enabled!
00:00:00.456354 CPUM: SetGuestCpuIdFeature: Enabled SYSENTER/EXIT
00:00:00.456355 CPUM: SetGuestCpuIdFeature: Enabled PAE
00:00:00.456356 CPUM: SetGuestCpuIdFeature: Enabled LONG MODE
00:00:00.456356 CPUM: SetGuestCpuIdFeature: Enabled SYSCALL/RET
00:00:00.456357 CPUM: SetGuestCpuIdFeature: Enabled LAHF/SAHF
00:00:00.456357 CPUM: SetGuestCpuIdFeature: Enabled NX
00:00:00.456358 HM: VPID enabled!
00:00:00.456358 HM: VPID flush type = VMX_FLUSH_VPID_SINGLE_CONTEXT
00:00:00.456359 HM: VMX-preemption timer enabled (cPreemptTimerShift=5).
00:00:00.456360 HM: VT-x/AMD-V init method: GLOBAL
00:00:00.456361 VMM: fUsePeriodicPreemptionTimers=false
00:00:00.456395 Logical host processors: 4 present, 4 max, 4 online, online mask: 000000000000000f
00:00:00.456398 Physical host cores: 4
00:00:00.456398 ************************* CPUID dump ************************
00:00:00.456404 RAW Standard CPUIDs
00:00:00.456404 Function eax ebx ecx edx
00:00:00.456405 Gst: 00000000 00000005 756e6547 6c65746e 49656e69
00:00:00.456406 Hst: 0000000d 756e6547 6c65746e 49656e69
00:00:00.456408 Gst: 00000001 000306c3 00040800 00000201 178bfbff
00:00:00.456409 Hst: 000306c3 00100800 7ffafbbf bfebfbff
00:00:00.456411 Gst: 00000002 76036301 00f0b6ff 00000000 00c10000
00:00:00.456412 Hst: 76036301 00f0b6ff 00000000 00c10000
00:00:00.456413 Gst: 00000003 00000000 00000000 00000000 00000000
00:00:00.456414 Hst: 00000000 00000000 00000000 00000000
00:00:00.456415 Gst: 00000004 0c000000 00000000 00000000 00000000
00:00:00.456416 Hst: 1c004121 01c0003f 0000003f 00000000
00:00:00.456418 Gst: 00000005 00000000 00000000 00000000 00000000
00:00:00.456418 Hst: 00000040 00000040 00000003 00042120
00:00:00.456420 Hst: 00000006 00000077 00000002 00000009 00000000
00:00:00.456421 Hst: 00000007 00000000 000027ab 00000000 00000000
00:00:00.456422 Hst: 00000008 00000000 00000000 00000000 00000000
00:00:00.456423 Hst: 00000009 00000000 00000000 00000000 00000000
00:00:00.456424 Hst: 0000000a 07300803 00000000 00000000 00000603
00:00:00.456425 Hst: 0000000b 00000001 00000001 00000100 00000000
00:00:00.456426 Hst: 0000000c 00000000 00000000 00000000 00000000
00:00:00.456427 Hst: 0000000d 00000007 00000340 00000340 00000000
00:00:00.456428 Name: GenuineIntel
00:00:00.456429 Supports: 0-5
00:00:00.456430 Family: 6 Extended: 0 Effective: 6
00:00:00.456431 Model: 12 Extended: 3 Effective: 60
00:00:00.456432 Stepping: 3
00:00:00.456432 Type: 0 (primary)
00:00:00.456433 APIC ID: 0x00
00:00:00.456434 Logical CPUs: 4
00:00:00.456434 CLFLUSH Size: 8
00:00:00.456435 Brand ID: 0x00
00:00:00.456435 Mnemonic - Description = guest (host)
00:00:00.456436 FPU - x87 FPU on Chip = 1 (1)
00:00:00.456437 VME - Virtual 8086 Mode Enhancements = 1 (1)
00:00:00.456437 DE - Debugging extensions = 1 (1)
00:00:00.456438 PSE - Page Size Extension = 1 (1)
00:00:00.456438 TSC - Time Stamp Counter = 1 (1)
00:00:00.456439 MSR - Model Specific Registers = 1 (1)
00:00:00.456440 PAE - Physical Address Extension = 1 (1)
00:00:00.456440 MCE - Machine Check Exception = 1 (1)
00:00:00.456441 CX8 - CMPXCHG8B instruction = 1 (1)
00:00:00.456441 APIC - APIC On-Chip = 1 (1)
00:00:00.456442 10 - Reserved = 0 (0)
00:00:00.456443 SEP - SYSENTER and SYSEXIT = 1 (1)
00:00:00.456443 MTRR - Memory Type Range Registers = 1 (1)
00:00:00.456444 PGE - PTE Global Bit = 1 (1)
00:00:00.456444 MCA - Machine Check Architecture = 1 (1)
00:00:00.456445 CMOV - Conditional Move Instructions = 1 (1)
00:00:00.456446 PAT - Page Attribute Table = 1 (1)
00:00:00.456446 PSE-36 - 36-bit Page Size Extention = 1 (1)
00:00:00.456447 PSN - Processor Serial Number = 0 (0)
00:00:00.456447 CLFSH - CLFLUSH Instruction. = 1 (1)
00:00:00.456448 20 - Reserved = 0 (0)
00:00:00.456448 DS - Debug Store = 0 (1)
00:00:00.456449 ACPI - Thermal Mon. & Soft. Clock Ctrl.= 0 (1)
00:00:00.456450 MMX - Intel MMX Technology = 1 (1)
00:00:00.456450 FXSR - FXSAVE and FXRSTOR Instructions = 1 (1)
00:00:00.456451 SSE - SSE Support = 1 (1)
00:00:00.456451 SSE2 - SSE2 Support = 1 (1)
00:00:00.456452 SS - Self Snoop = 0 (1)
00:00:00.456453 HTT - Hyper-Threading Technology = 1 (1)
00:00:00.456453 TM - Thermal Monitor = 0 (1)
00:00:00.456454 30 - Reserved = 0 (0)
00:00:00.456454 PBE - Pending Break Enable = 0 (1)
00:00:00.456455 Supports SSE3 = 1 (1)
00:00:00.456456 PCLMULQDQ = 0 (1)
00:00:00.456456 DS Area 64-bit layout = 0 (1)
00:00:00.456457 Supports MONITOR/MWAIT = 0 (1)
00:00:00.456457 CPL-DS - CPL Qualified Debug Store = 0 (1)
00:00:00.456458 VMX - Virtual Machine Technology = 0 (1)
00:00:00.456458 SMX - Safer Mode Extensions = 0 (0)
00:00:00.456459 Enhanced SpeedStep Technology = 0 (1)
00:00:00.456460 Terminal Monitor 2 = 0 (1)
00:00:00.456460 Supplemental SSE3 instructions = 1 (1)
00:00:00.456461 L1 Context ID = 0 (0)
00:00:00.456462 11 - Reserved = 0 (1)
00:00:00.456462 FMA extensions using YMM state = 0 (1)
00:00:00.456463 CMPXCHG16B instruction = 0 (1)
00:00:00.456463 xTPR Update Control = 0 (1)
00:00:00.456464 Perf/Debug Capability MSR = 0 (1)
00:00:00.456465 16 - Reserved = 0 (0)
00:00:00.456465 PCID - Process-context identifiers = 0 (1)
00:00:00.456466 DCA - Direct Cache Access = 0 (0)
00:00:00.456466 SSE4.1 instruction extensions = 0 (1)
00:00:00.456467 SSE4.2 instruction extensions = 0 (1)
00:00:00.456468 Supports the x2APIC extensions = 0 (1)
00:00:00.456468 MOVBE instruction = 0 (1)
00:00:00.456469 POPCNT instruction = 0 (1)
00:00:00.456470 TSC-Deadline LAPIC timer mode = 0 (1)
00:00:00.456470 AESNI instruction extensions = 0 (1)
00:00:00.456471 XSAVE/XRSTOR extended state feature = 0 (1)
00:00:00.456471 Supports OSXSAVE = 0 (1)
00:00:00.456472 AVX instruction extensions = 0 (1)
00:00:00.456472 29/30 - Reserved = 0x0 (0x3)
00:00:00.456473 Hypervisor Present (we're a guest) = 0 (0)
00:00:00.456474
00:00:00.456474 RAW Extended CPUIDs
00:00:00.456474 Function eax ebx ecx edx
00:00:00.456475 Gst: 80000000 80000008 00000000 00000000 00000000
00:00:00.456476 Hst: 80000008 00000000 00000000 00000000
00:00:00.456477 Gst: 80000001 00000000 00000000 00000001 28100800
00:00:00.456478 Hst: 00000000 00000000 00000021 2c100800
00:00:00.456480 Gst: 80000002 65746e49 2952286c 726f4320 4d542865
00:00:00.456481 Hst: 65746e49 2952286c 726f4320 4d542865
00:00:00.456483 Gst: 80000003 35692029 3434342d 50432030 20402055
00:00:00.456485 Hst: 35692029 3434342d 50432030 20402055
00:00:00.456487 Gst: 80000004 30312e33 007a4847 00000000 00000000
00:00:00.456488 Hst: 30312e33 007a4847 00000000 00000000
00:00:00.456489 Gst: 80000005 00000000 00000000 00000000 00000000
00:00:00.456490 Hst: 00000000 00000000 00000000 00000000
00:00:00.456491 Gst: 80000006 00000000 00000000 01006040 00000000
00:00:00.456492 Hst: 00000000 00000000 01006040 00000000
00:00:00.456493 Gst: 80000007 00000000 00000000 00000000 00000000
00:00:00.456494 Hst: 00000000 00000000 00000000 00000100
00:00:00.456495 Gst: 80000008 00003027 00000000 00000000 00000000
00:00:00.456496 Hst: 00003027 00000000 00000000 00000000
00:00:00.456497 Gst: 80000009 00000007 00000340 00000340 00000000*
00:00:00.456499 Hst: 00000007 00000340 00000340 00000000
00:00:00.456500 Ext Name:
00:00:00.456500 Ext Supports: 0x80000000-0x80000008
00:00:00.456501 Family: 0 Extended: 0 Effective: 0
00:00:00.456502 Model: 0 Extended: 0 Effective: 0
00:00:00.456502 Stepping: 0
00:00:00.456503 Brand ID: 0x000
00:00:00.456504 Mnemonic - Description = guest (host)
00:00:00.456504 FPU - x87 FPU on Chip = 0 (0)
00:00:00.456505 VME - Virtual 8086 Mode Enhancements = 0 (0)
00:00:00.456505 DE - Debugging extensions = 0 (0)
00:00:00.456506 PSE - Page Size Extension = 0 (0)
00:00:00.456506 TSC - Time Stamp Counter = 0 (0)
00:00:00.456507 MSR - K86 Model Specific Registers = 0 (0)
00:00:00.456508 PAE - Physical Address Extension = 0 (0)
00:00:00.456508 MCE - Machine Check Exception = 0 (0)
00:00:00.456509 CX8 - CMPXCHG8B instruction = 0 (0)
00:00:00.456509 APIC - APIC On-Chip = 0 (0)
00:00:00.456510 10 - Reserved = 0 (0)
00:00:00.456510 SEP - SYSCALL and SYSRET = 1 (1)
00:00:00.456511 MTRR - Memory Type Range Registers = 0 (0)
00:00:00.456512 PGE - PTE Global Bit = 0 (0)
00:00:00.456512 MCA - Machine Check Architecture = 0 (0)
00:00:00.456513 CMOV - Conditional Move Instructions = 0 (0)
00:00:00.456513 PAT - Page Attribute Table = 0 (0)
00:00:00.456514 PSE-36 - 36-bit Page Size Extention = 0 (0)
00:00:00.456515 18 - Reserved = 0 (0)
00:00:00.456515 19 - Reserved = 0 (0)
00:00:00.456516 NX - No-Execute Page Protection = 1 (1)
00:00:00.456516 DS - Debug Store = 0 (0)
00:00:00.456517 AXMMX - AMD Extensions to MMX Instr. = 0 (0)
00:00:00.456518 MMX - Intel MMX Technology = 0 (0)
00:00:00.456518 FXSR - FXSAVE and FXRSTOR Instructions = 0 (0)
00:00:00.456519 25 - AMD fast FXSAVE and FXRSTOR Instr.= 0 (0)
00:00:00.456519 26 - 1 GB large page support = 0 (1)
00:00:00.456520 27 - RDTSCP instruction = 1 (1)
00:00:00.456521 28 - Reserved = 0 (0)
00:00:00.456521 29 - AMD Long Mode = 1 (1)
00:00:00.456522 30 - AMD Extensions to 3DNow! = 0 (0)
00:00:00.456522 31 - AMD 3DNow! = 0 (0)
00:00:00.456523 LahfSahf - LAHF/SAHF in 64-bit mode = 1 (1)
00:00:00.456524 CmpLegacy - Core MP legacy mode (depr) = 0 (0)
00:00:00.456524 SVM - AMD VM Extensions = 0 (0)
00:00:00.456525 APIC registers starting at 0x400 = 0 (0)
00:00:00.456525 AltMovCR8 - LOCK MOV CR0 means MOV CR8 = 0 (0)
00:00:00.456526 5 - Advanced bit manipulation = 0 (1)
00:00:00.456526 6 - SSE4A instruction support = 0 (0)
00:00:00.456527 7 - Misaligned SSE mode = 0 (0)
00:00:00.456528 8 - PREFETCH and PREFETCHW instruction= 0 (0)
00:00:00.456528 9 - OS visible workaround = 0 (0)
00:00:00.456529 10 - Instruction based sampling = 0 (0)
00:00:00.456529 11 - SSE5 support = 0 (0)
00:00:00.456530 12 - SKINIT, STGI, and DEV support = 0 (0)
00:00:00.456531 13 - Watchdog timer support. = 0 (0)
00:00:00.456531 31:14 - Reserved = 0x0 (0x0)
00:00:00.456532 Full Name: Intel(R) Core(TM) i5-4440 CPU @ 3.10GHz
00:00:00.456533 TLB 2/4M Instr/Uni: res0 0 entries
00:00:00.456534 TLB 2/4M Data: res0 0 entries
00:00:00.456534 TLB 4K Instr/Uni: res0 0 entries
00:00:00.456535 TLB 4K Data: res0 0 entries
00:00:00.456535 L1 Instr Cache Line Size: 0 bytes
00:00:00.456536 L1 Instr Cache Lines Per Tag: 0
00:00:00.456536 L1 Instr Cache Associativity: res0
00:00:00.456537 L1 Instr Cache Size: 0 KB
00:00:00.456537 L1 Data Cache Line Size: 0 bytes
00:00:00.456538 L1 Data Cache Lines Per Tag: 0
00:00:00.456538 L1 Data Cache Associativity: res0
00:00:00.456539 L1 Data Cache Size: 0 KB
00:00:00.456539 L2 TLB 2/4M Instr/Uni: off 0 entries
00:00:00.456540 L2 TLB 2/4M Data: off 0 entries
00:00:00.456541 L2 TLB 4K Instr/Uni: off 0 entries
00:00:00.456541 L2 TLB 4K Data: off 0 entries
00:00:00.456542 L2 Cache Line Size: 0 bytes
00:00:00.456542 L2 Cache Lines Per Tag: 0
00:00:00.456543 L2 Cache Associativity: off
00:00:00.456543 L2 Cache Size: 0 KB
00:00:00.456544 APM Features:
00:00:00.456545 Physical Address Width: 39 bits
00:00:00.456545 Virtual Address Width: 48 bits
00:00:00.456546 Guest Physical Address Width: 0 bits
00:00:00.456546 Physical Core Count: 0
00:00:00.456547
00:00:00.456547 RAW Centaur CPUIDs
00:00:00.456547 Function eax ebx ecx edx
00:00:00.456548 Gst: c0000000 00000007 00000340 00000340 00000000
00:00:00.456549 Hst: 00000007 00000340 00000340 00000000
00:00:00.456550 Gst: c0000001 00000007 00000340 00000340 00000000
00:00:00.456551 Hst: 00000007 00000340 00000340 00000000
00:00:00.456552 Gst: c0000002 00000007 00000340 00000340 00000000
00:00:00.456553 Hst: 00000007 00000340 00000340 00000000
00:00:00.456554 Gst: c0000003 00000007 00000340 00000340 00000000
00:00:00.456555 Hst: 00000007 00000340 00000340 00000000
00:00:00.456556 Centaur Supports: 0xc0000000-0x00000007
00:00:00.456557 Mnemonic - Description = guest (host)
00:00:00.456558 AIS - Alternate Instruction Set = 0 (0)
00:00:00.456558 AIS-E - AIS enabled = 0 (0)
00:00:00.456559 RNG - Random Number Generator = 0 (0)
00:00:00.456559 RNG-E - RNG enabled = 0 (0)
00:00:00.456560 LH - LongHaul MSR 0000_110Ah = 0 (0)
00:00:00.456561 FEMMS - FEMMS = 0 (0)
00:00:00.456561 ACE - Advanced Cryptography Engine = 0 (0)
00:00:00.456562 ACE-E - ACE enabled = 0 (0)
00:00:00.456562 ACE2 - Advanced Cryptography Engine 2 = 0 (0)
00:00:00.456563 ACE2-E - ACE enabled = 0 (0)
00:00:00.456564 PHE - Padlock Hash Engine = 0 (0)
00:00:00.456564 PHE-E - PHE enabled = 0 (0)
00:00:00.456565 PMM - Montgomery Multiplier = 0 (0)
00:00:00.456565 PMM-E - PMM enabled = 0 (0)
00:00:00.456566 14 - Reserved = 0 (0)
00:00:00.456566 15 - Reserved = 0 (0)
00:00:00.456567 Parallax = 0 (0)
00:00:00.456568 Parallax enabled = 0 (0)
00:00:00.456568 Overstress = 0 (0)
00:00:00.456569 Overstress enabled = 0 (0)
00:00:00.456569 TM3 - Temperature Monitoring 3 = 0 (0)
00:00:00.456570 TM3-E - TM3 enabled = 0 (0)
00:00:00.456571 RNG2 - Random Number Generator 2 = 0 (0)
00:00:00.456571 RNG2-E - RNG2 enabled = 0 (0)
00:00:00.456572 24 - Reserved = 0 (0)
00:00:00.456572 PHE2 - Padlock Hash Engine 2 = 0 (0)
00:00:00.456573 PHE2-E - PHE2 enabled = 0 (0)
00:00:00.456574
00:00:00.456574
00:00:00.456574 ******************** End of CPUID dump **********************
00:00:00.459080 VM: Halt method global1 (5)
00:00:00.459109 HaltedGlobal1 config: cNsSpinBlockThresholdCfg=50000
00:00:00.459131 Changing the VM state from 'CREATING' to 'CREATED'.
00:00:00.459487 VNC: port = 5900
00:00:00.459568 VNC: Configuring password
00:00:00.459627 Changing the VM state from 'CREATED' to 'POWERING_ON'.
00:00:00.459705 Changing the VM state from 'POWERING_ON' to 'RUNNING'.
00:00:00.464114 Guest Log: BIOS: VirtualBox 4.3.12
00:00:00.464236 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:00.493664 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0x00 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:00:00.493695 PIIX3 ATA: Ctl#0: finished processing RESET
00:00:00.494008 Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
00:00:00.495115 PIIX3 ATA: Ctl#0: RESET, DevSel=1 AIOIf=0 CmdIf0=0xec (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:00:00.495137 PIIX3 ATA: Ctl#0: finished processing RESET
00:00:00.495430 Guest Log: BIOS: ata0-1: PCHS=16383/16/63 LCHS=1024/255/63
00:00:00.503252 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
00:00:00.507026 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=0000000811a4d000 w=640 h=480 bpp=32 cbLine=0xA00, flags=0x1
00:00:00.507086 VNCServerImpl::VRDEResize to 640x480x32bpp
00:00:01.484475 VBVA: VRDP acceleration has been requested.
00:00:02.978226 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:02.978420 Guest Log: BIOS: Boot : bseqnr=1, bootseq=0002
00:00:02.986007 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0, flags=0x1
00:00:02.986033 VNCServerImpl::VRDEResize to 720x400x32bpp
00:00:03.039900 Guest Log: BIOS: Booting from Hard Disk...
00:00:22.692360 Guest Log: int13_harddisk: function 08, unmapped device for ELDL=82
00:00:22.736525 Guest Log: int13_harddisk: function 08, unmapped device for ELDL=83
00:00:22.737091 Guest Log: int13_harddisk: function 08, unmapped device for ELDL=84
00:00:22.737651 Guest Log: int13_harddisk: function 08, unmapped device for ELDL=85
00:00:22.738211 Guest Log: int13_harddisk: function 08, unmapped device for ELDL=86
00:00:22.738768 Guest Log: int13_harddisk: function 08, unmapped device for ELDL=87
00:00:22.739817 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:28.758638 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0xc4 (-1 usec ago) CmdIf1=0xec (-1 usec ago)
00:00:28.791104 PIIX3 ATA: Ctl#0: finished processing RESET
00:44:10.900833 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:44:10.961351 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:44:11.040947 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:44:11.077073 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:44:11.119105 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:44:42.673878 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:44:42.721689 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:44:42.786881 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:44:42.948819 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:44:42.991362 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:45:13.080011 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:45:13.137603 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:45:13.167471 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:45:13.198750 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:45:13.234957 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:45:44.242813 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:45:44.288030 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:45:44.323690 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:45:44.357158 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:45:44.394911 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:46:15.025754 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:46:15.110369 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:46:15.147953 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:46:15.185513 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:46:15.234033 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:46:46.198650 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:46:46.255829 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:46:46.293497 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:46:46.334390 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:46:46.375395 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:47:17.555027 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:47:17.614930 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:47:17.647694 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:47:17.698135 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:47:17.738723 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:47:48.794582 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:47:48.872849 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:47:48.902553 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:47:48.932122 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:47:48.971759 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:48:20.009234 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:48:20.072372 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:48:20.119886 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:48:20.164234 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:48:20.202956 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:48:51.473906 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:48:51.522486 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:48:51.564597 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:48:51.603126 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:48:51.639322 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:49:21.988241 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:49:22.032101 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:49:22.073539 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:49:22.103797 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:49:22.132690 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:49:53.176592 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:49:53.206739 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:49:53.252203 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:49:53.320123 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:49:53.350366 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:50:24.409187 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:50:24.438804 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:50:24.469345 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:50:24.497185 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:50:24.526435 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:50:55.590439 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:50:55.634766 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:50:55.674909 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:50:55.704812 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:50:55.735767 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:51:26.762818 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:51:26.840996 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:51:26.875432 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:51:26.922254 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:51:26.963444 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:51:57.986269 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:51:58.033374 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:51:58.065582 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:51:58.113827 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:51:58.144634 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:52:29.059235 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:52:29.100991 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:52:29.140843 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:52:29.189191 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
00:52:29.222609 PIIX3 ATA: LUN#0: disk write error (rc=VERR_UNRESOLVED_ERROR iSector=0x5c9335a cSectors=0x8)
comment:4 by , 10 years ago
Second here's iostat 1 on the HOSTOS output whilst it happened multiple times:
root@colossus:/Storage/VirtualBox VMs/FreeBSD 9.2 i386/Logs # iostat 1
tty mfid0 ada0 ada1 cpu
tin tout KB/t tps MB/s KB/t tps MB/s KB/t tps MB/s us ni sy in id
0 56 45.28 996 44.03 19.57 2 0.04 19.52 2 0.04 2 0 26 0 72
0 234 28.04 1024 28.03 0.00 0 0.00 0.00 0 0.00 3 0 88 0 9
0 79 32.73 939 30.02 0.00 0 0.00 0.00 0 0.00 4 0 84 0 12
0 78 32.51 528 16.78 3.00 3 0.01 3.00 3 0.01 4 0 87 0 9
0 78 31.80 812 25.22 0.00 0 0.00 0.00 0 0.00 4 0 88 0 8
0 78 29.83 600 17.49 16.00 7 0.11 16.00 7 0.11 3 0 88 0 10
0 78 31.04 858 26.01 32.00 9 0.28 32.00 9 0.28 2 0 90 0 7
0 78 33.82 931 30.75 0.00 0 0.00 0.00 0 0.00 4 0 85 0 10
0 78 22.36 620 13.54 0.00 0 0.00 0.00 0 0.00 5 0 75 0 20
0 78 32.02 1009 31.55 0.00 0 0.00 0.00 0 0.00 5 0 86 0 9
0 79 28.57 822 22.94 0.00 0 0.00 0.00 0 0.00 3 0 88 0 9
0 78 24.03 585 13.74 1.00 1 0.00 1.00 1 0.00 4 0 84 0 12
0 78 15.83 525 8.12 0.00 0 0.00 0.00 0 0.00 6 0 76 0 18
0 78 24.90 618 15.04 0.00 0 0.00 0.00 0 0.00 5 0 85 0 10
0 78 27.85 960 26.11 0.00 0 0.00 0.00 0 0.00 6 0 88 0 6
0 78 16.78 526 8.63 0.00 0 0.00 0.00 0 0.00 5 0 78 0 17
0 78 10.98 605 6.49 0.00 0 0.00 0.00 0 0.00 4 0 69 0 27
0 78 8.41 775 6.37 0.00 0 0.00 0.00 0 0.00 6 0 51 0 43
0 78 15.29 575 8.59 0.00 0 0.00 0.00 0 0.00 4 0 57 0 39
0 78 13.29 610 7.92 0.00 0 0.00 0.00 0 0.00 5 0 71 0 24
0 78 12.50 727 8.88 0.00 0 0.00 0.00 0 0.00 7 0 67 0 26
0 78 10.57 935 9.66 0.00 0 0.00 0.00 0 0.00 5 0 72 0 23
0 78 13.56 547 7.25 0.00 0 0.00 0.00 0 0.00 5 0 69 0 26
0 78 12.73 589 7.33 0.00 0 0.00 0.00 0 0.00 6 0 49 0 45
0 78 9.85 754 7.25 0.00 0 0.00 0.00 0 0.00 3 0 69 0 28
0 78 16.42 506 8.12 14.12 8 0.11 14.12 8 0.11 5 0 81 0 15
0 78 11.90 747 8.68 0.00 0 0.00 0.00 0 0.00 4 0 74 0 22
0 78 9.93 614 5.96 0.00 0 0.00 0.00 0 0.00 4 0 78 0 17
0 78 11.80 634 7.31 0.00 0 0.00 0.00 0 0.00 5 0 73 0 21
0 78 9.90 489 4.72 0.00 0 0.00 0.00 0 0.00 4 0 81 0 15
0 78 16.46 404 6.49 1.00 1 0.00 1.00 1 0.00 5 0 67 0 28
0 78 24.62 649 15.61 0.00 0 0.00 0.00 0 0.00 2 0 86 0 12
0 78 21.90 666 14.25 0.00 0 0.00 0.00 0 0.00 2 0 82 0 16
0 78 21.85 712 15.20 0.00 0 0.00 0.00 0 0.00 2 0 84 0 14
0 78 28.94 665 18.80 3.00 3 0.01 3.00 3 0.01 4 0 81 0 16
0 78 20.30 356 7.05 0.00 0 0.00 0.00 0 0.00 4 0 84 0 12
0 78 16.75 360 5.88 0.00 0 0.00 0.00 0 0.00 6 0 77 0 18
0 78 14.51 291 4.12 0.00 0 0.00 0.00 0 0.00 3 0 76 0 20
0 78 17.98 567 9.96 0.00 0 0.00 0.00 0 0.00 5 0 81 0 13
0 78 27.66 675 18.24 1.00 1 0.00 1.00 1 0.00 6 0 85 0 9
0 78 28.75 603 16.94 0.00 0 0.00 0.00 0 0.00 4 0 83 0 12
0 78 29.18 609 17.37 0.00 0 0.00 0.00 0 0.00 2 0 88 0 10
0 78 27.31 1021 27.23 0.00 0 0.00 0.00 0 0.00 6 0 78 0 16
0 79 17.12 951 15.90 0.00 0 0.00 0.00 0 0.00 5 0 80 0 14
0 78 25.58 807 20.16 0.00 0 0.00 0.00 0 0.00 5 0 87 0 8
0 78 34.70 790 26.78 14.12 8 0.11 14.12 8 0.11 5 0 86 0 9
0 78 27.02 643 16.97 0.00 0 0.00 0.00 0 0.00 5 0 86 0 10
0 78 27.29 572 15.25 0.00 0 0.00 0.00 0 0.00 4 0 86 0 10
0 78 10.94 498 5.31 0.00 0 0.00 0.00 0 0.00 4 0 35 0 61
0 78 8.81 810 6.97 0.00 0 0.00 0.00 0 0.00 3 0 38 0 59
0 78 12.29 667 8.01 1.00 1 0.00 1.00 1 0.00 6 0 61 0 33
0 78 9.46 643 5.95 0.00 0 0.00 0.00 0 0.00 4 0 65 0 31
0 78 7.21 1056 7.43 0.00 0 0.00 0.00 0 0.00 4 0 57 0 38
0 79 12.31 624 7.51 28.56 9 0.25 28.56 9 0.25 6 0 54 0 40
0 78 8.87 886 7.68 0.00 0 0.00 0.00 0 0.00 5 0 66 0 29
0 78 10.13 608 6.02 0.00 0 0.00 0.00 0 0.00 6 0 72 0 21
0 78 15.60 641 9.77 0.00 0 0.00 0.00 0 0.00 4 0 75 0 21
0 78 13.96 617 8.42 16.00 7 0.11 16.00 7 0.11 4 0 71 0 24
0 78 11.11 601 6.53 0.00 0 0.00 0.00 0 0.00 5 0 69 0 26
0 78 7.84 601 4.60 0.00 0 0.00 0.00 0 0.00 6 0 74 0 19
0 78 10.34 656 6.63 0.00 0 0.00 0.00 0 0.00 5 0 46 0 50
0 78 8.92 933 8.12 0.00 0 0.00 0.00 0 0.00 4 0 73 1 22
tty mfid0 ada0 ada1 cpu
tin tout KB/t tps MB/s KB/t tps MB/s KB/t tps MB/s us ni sy in id
0 78 14.74 1150 16.55 1.00 1 0.00 1.00 1 0.00 7 0 75 0 17
0 235 9.24 1002 9.04 0.00 0 0.00 0.00 0 0.00 5 0 83 0 12
0 79 15.74 745 11.46 0.00 0 0.00 0.00 0 0.00 4 0 86 0 11
0 78 18.98 632 11.72 3.25 4 0.01 3.25 4 0.01 4 0 82 0 14
0 78 15.91 402 6.24 0.00 0 0.00 0.00 0 0.00 3 0 86 0 12
0 78 15.00 756 11.08 0.00 0 0.00 0.00 0 0.00 5 0 76 0 19
0 78 20.50 647 12.96 0.00 0 0.00 0.00 0 0.00 4 0 80 0 16
0 78 8.72 840 7.15 0.00 0 0.00 0.00 0 0.00 7 0 68 0 25
0 78 23.14 552 12.48 1.00 1 0.00 1.00 1 0.00 4 0 84 0 12
0 78 26.44 717 18.52 0.00 0 0.00 0.00 0 0.00 5 0 78 0 17
0 78 20.06 613 12.02 0.00 0 0.00 0.00 0 0.00 7 0 77 0 17
0 78 25.15 1622 39.84 0.00 0 0.00 0.00 0 0.00 6 0 67 0 27
0 79 24.37 532 12.67 0.00 0 0.00 0.00 0 0.00 6 0 72 0 22
0 78 14.30 837 11.69 0.00 0 0.00 0.00 0 0.00 5 0 77 1 18
0 78 12.13 501 5.94 0.00 0 0.00 0.00 0 0.00 3 0 81 0 15
0 78 9.90 857 8.29 0.00 0 0.00 0.00 0 0.00 4 0 76 0 19
0 78 12.48 390 4.75 0.00 0 0.00 0.00 0 0.00 6 0 62 0 32
0 78 13.69 414 5.53 0.00 0 0.00 0.00 0 0.00 1 0 10 0 89
0 78 9.78 1164 11.11 0.00 0 0.00 0.00 0 0.00 1 0 18 0 81
0 79 5.97 1364 7.95 0.00 0 0.00 0.00 0 0.00 5 0 46 0 49
0 79 10.27 644 6.46 0.00 0 0.00 0.00 0 0.00 4 0 40 0 56
0 78 9.72 678 6.44 0.00 0 0.00 0.00 0 0.00 5 0 45 0 50
0 78 12.45 640 7.79 0.00 0 0.00 0.00 0 0.00 5 0 37 0 58
0 78 9.46 682 6.31 27.57 7 0.19 27.57 7 0.19 3 0 45 0 52
0 78 16.46 524 8.43 0.00 0 0.00 0.00 0 0.00 3 0 63 0 34
0 78 13.54 481 6.35 4.00 1 0.00 4.00 1 0.00 3 0 51 0 46
0 78 12.10 573 6.77 0.00 0 0.00 0.00 0 0.00 4 0 66 0 30
0 78 8.58 1011 8.48 16.00 7 0.11 16.00 7 0.11 4 0 61 0 34
0 79 8.63 931 7.85 0.00 0 0.00 0.00 0 0.00 4 0 43 0 53
0 78 10.52 802 8.24 0.00 0 0.00 0.00 0 0.00 5 0 39 0 56
0 78 15.12 773 11.42 0.00 0 0.00 0.00 0 0.00 5 0 43 0 52
0 78 7.71 791 5.96 0.00 0 0.00 0.00 0 0.00 5 0 60 0 35
0 78 12.46 700 8.52 1.00 1 0.00 1.00 1 0.00 5 0 59 0 36
0 78 10.62 605 6.28 0.00 0 0.00 0.00 0 0.00 5 0 60 0 34
0 78 16.93 410 6.77 3.00 3 0.01 3.00 3 0.01 3 0 51 0 45
0 78 16.63 532 8.65 0.00 0 0.00 0.00 0 0.00 3 0 43 0 54
0 78 13.44 956 12.55 0.00 0 0.00 0.00 0 0.00 8 0 64 0 28
0 78 24.88 589 14.32 0.00 0 0.00 0.00 0 0.00 3 0 65 0 32
0 78 19.21 451 8.45 0.00 0 0.00 0.00 0 0.00 4 0 66 0 29
0 78 13.82 634 8.56 1.00 1 0.00 1.00 1 0.00 5 0 72 0 23
0 78 7.42 954 6.91 0.00 0 0.00 0.00 0 0.00 4 0 73 0 22
0 78 9.84 796 7.65 0.00 0 0.00 0.00 0 0.00 5 0 53 0 42
0 78 17.74 995 17.23 0.00 0 0.00 0.00 0 0.00 4 0 56 0 40
0 78 14.65 557 7.98 0.00 0 0.00 0.00 0 0.00 7 0 57 0 36
0 78 24.20 646 15.27 0.00 0 0.00 0.00 0 0.00 3 0 46 0 50
0 78 14.22 482 6.69 0.00 0 0.00 0.00 0 0.00 3 0 49 0 48
0 78 15.86 393 6.08 0.00 0 0.00 0.00 0 0.00 3 0 60 0 37
0 78 18.33 389 6.96 14.12 8 0.11 14.12 8 0.11 2 0 39 0 59
0 78 10.91 920 9.81 0.00 0 0.00 0.00 0 0.00 2 0 37 0 61
0 78 9.97 805 7.84 0.00 0 0.00 0.00 0 0.00 5 0 58 0 37
0 78 10.89 611 6.50 0.00 0 0.00 0.00 0 0.00 4 0 54 0 42
0 78 11.65 579 6.59 0.00 0 0.00 0.00 0 0.00 4 0 48 1 47
0 78 13.38 390 5.09 1.00 1 0.00 1.00 1 0.00 3 0 43 0 55
0 78 14.34 538 7.54 0.00 0 0.00 0.00 0 0.00 2 0 33 0 65
comment:5 by , 10 years ago
Last dmesg from the guest OS:
Last login: Tue Aug 5 13:27:56 2014
root@92i386:~ # dmesg
Copyright (c) 1992-2013 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
The Regents of the University of California. All rights reserved.
FreeBSD is a registered trademark of The FreeBSD Foundation.
FreeBSD 9.2-RELEASE-p10 #0: Tue Jul 8 10:17:36 UTC 2014
root@amd64-builder.daemonology.net:/usr/obj/usr/src/sys/GENERIC i386
gcc version 4.2.1 20070831 patched [FreeBSD]
CPU: Intel(R) Core(TM) i5-4440 CPU @ 3.10GHz (3067.20-MHz 686-class CPU)
Origin = "GenuineIntel" Id = 0x306c3 Family = 0x6 Model = 0x3c Stepping = 3
Features=0x1783fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE,SSE2,HTT>
Features2=0x201<SSE3,SSSE3>
AMD Features=0x28100800<SYSCALL,NX,RDTSCP,LM>
AMD Features2=0x1<LAHF>
real memory = 3758030848 (3583 MB)
avail memory = 3664863232 (3495 MB)
Event timer "LAPIC" quality 400
ACPI APIC Table: <VBOX VBOXAPIC>
FreeBSD/SMP: Multiprocessor System Detected: 4 CPUs
FreeBSD/SMP: 1 package(s) x 4 core(s)
cpu0 (BSP): APIC ID: 0
cpu1 (AP): APIC ID: 1
cpu2 (AP): APIC ID: 2
cpu3 (AP): APIC ID: 3
pnpbios: Bad PnP BIOS data checksum
ioapic0 <Version 1.1> irqs 0-23 on motherboard
kbd1 at kbdmux0
acpi0: <VBOX VBOXXSDT> on motherboard
acpi0: Power Button (fixed)
acpi0: Sleep Button (fixed)
cpu0: <ACPI CPU> on acpi0
cpu1: <ACPI CPU> on acpi0
cpu2: <ACPI CPU> on acpi0
cpu3: <ACPI CPU> on acpi0
attimer0: <AT timer> port 0x40-0x43,0x50-0x53 on acpi0
Timecounter "i8254" frequency 1193182 Hz quality 0
Event timer "i8254" frequency 1193182 Hz quality 100
Timecounter "ACPI-fast" frequency 3579545 Hz quality 900
acpi_timer0: <32-bit timer at 3.579545MHz> port 0x4008-0x400b on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
isab0: <PCI-ISA bridge> at device 1.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel 63XXESB2 UDMA100 controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xd000-0xd00f at device 1.1 on pci0
ata0: <ATA channel> at channel 0 on atapci0
vgapci0: <VGA-compatible display> mem 0xe0000000-0xe07fffff irq 18 at device 2.0 on pci0
pci0: <base peripheral> at device 4.0 (no driver attached)
pci0: <bridge> at device 7.0 (no driver attached)
em0: <Intel(R) PRO/1000 Legacy Network Connection 1.0.6> port 0xd040-0xd047 mem 0xf0420000-0xf043ffff irq 17 at device 17.0 on pci0
em0: Ethernet address: 08:00:27:30:f8:48
acpi_acad0: <AC Adapter> on acpi0
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
psm0: model IntelliMouse Explorer, device ID 4
pmtimer0 on isa0
orm0: <ISA Option ROM> at iomem 0xc0000-0xc7fff pnpid ORM0000 on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
atrtc0: <AT realtime clock> at port 0x70 irq 8 on isa0
Event timer "RTC" frequency 32768 Hz quality 0
ppc0: parallel port not found.
Timecounters tick every 10.000 msec
ada0 at ata0 bus 0 scbus0 target 0 lun 0
ada0: <VBOX HARDDISK 1.0> ATA-6 device
ada0: 100.000MB/s transfers (UDMA5, PIO 65536bytes)
ada0: 51404MB (105276992 512 byte sectors: 16H 63S/T 16383C)
ada0: Previously was known as ad0
ada1 at ata0 bus 0 scbus0 target 1 lun 0
ada1: <VBOX HARDDISK 1.0> ATA-6 device
ada1: 100.000MB/s transfers (UDMA5, PIO 65536bytes)
ada1: 10485MB (21474832 512 byte sectors: 16H 63S/T 16383C)
ada1: Previously was known as ad1
SMP: AP CPU #2 Launched!
SMP: AP CPU #1 Launched!
SMP: AP CPU #3 Launched!
Trying to mount root from ufs:/dev/ada0p2 [rw]...
ZFS NOTICE: Prefetch is disabled by default on i386 -- to enable,
add "vfs.zfs.prefetch_disable=0" to /boot/loader.conf.
ZFS WARNING: Recommended minimum kmem_size is 512MB; expect unstable behavior.
Consider tuning vm.kmem_size and vm.kmem_size_max
in /boot/loader.conf.
ZFS filesystem version: 5
ZFS storage pool version: features support (5000)
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Retrying command
(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00
(ada0:ata0:0:0:0): CAM status: ATA Status Error
(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )
(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00
(ada0:ata0:0:0:0): Error 5, Retries exhausted
g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5
comment:6 by , 10 years ago
Relevant entries (well not really relevant, but the only thing related to vbox) in dmesg on the HostOS:
MCA: Bank 0, Status 0x90000040000f0005 MCA: Global Cap 0x0000000000000c09, Status 0x0000000000000000 MCA: Vendor "GenuineIntel", ID 0x306c3, APIC ID 0 MCA: CPU 0 COR (1) internal parity error MCA: Bank 0, Status 0x90000040000f0005 MCA: Global Cap 0x0000000000000c09, Status 0x0000000000000000 MCA: Vendor "GenuineIntel", ID 0x306c3, APIC ID 4 MCA: CPU 2 COR (1) internal parity error MCA: Bank 0, Status 0xd00000c0000f0005 MCA: Global Cap 0x0000000000000c09, Status 0x0000000000000000 MCA: Vendor "GenuineIntel", ID 0x306c3, APIC ID 6 MCA: CPU 3 COR (3) OVER internal parity error
comment:7 by , 10 years ago
The VERR_UNRESOLVED_ERROR hints that this is related to the FreeBSD port being incomplete. This happens for unknown errno values, and "everyone" knows that FreeBSD has a nice selection of values which don't exist on other platforms.
Can you try to find out using strace/truss/dtrace what the write syscall returns as the errno? Successful ones are irrelevant. Your VM is nice and simple, and uses IDE, which means I'm really curious what's causing all this mess for such a super frequently used syscall.
by , 10 years ago
| Attachment: | vbox-ktrace-drdy-errors.txt.first-52400b added |
|---|
Created with ktrace on the HostOS then: kdump -f ktrace.out | grep -A20 -B20 DRDY > file.txt (so I can give more lines either side if you think you need them - the actual file is 834G so impractical to give you in full) - first 52400 bytes only - rest of the file (all 27M) here: http://flashback.sorbs.net/packages/vbox-ktrace-drdy-errors.txt
comment:8 by , 10 years ago
taking -A20000 -B20000 and filtering out the ioctls with 'grep -v ioctl' ... this is the result fro the first occurrence of the issue:
0x0f50 7309 7277 2c6e 6673 7634 6163 6c73 2009 3020 304e 4f20 6275 696c 642f 6275 696c 642f 706f 7274 732f 6465 6661 756c 7420 2f62 7569 6c64 2f70 6f75 6472 6965 7265 2f70 6f72 7473 |s.rw,nfsv4acls .0 0NO build/build/ports/default /build/poudriere/ports|
0x0f96 2f64 6566 6175 6c74 207a 6673 0972 772c 6e66 7376 3461 636c 7320 0930 2030 4e54 2d64 6576 6673 0909 092f 6275 696c 642f 706f 7564 7269 6572 655f 6461 7461 2f62 7569 6c64 2f39 |/default zfs.rw,nfsv4acls .0 0NT-devfs.../build/poudriere_data/build/9|
0x0fdc 3269 3338 362d 6465 6661 756c 742f 7265 662f 6465 7620 6465 7666 7309 7277 2c6d 756c 7469 |2i386-default/ref/dev devfs.rw,multi|
1024 VBoxHeadless RET read 65536/0x10000
1024 VBoxHeadless CALL _umtx_op(0x8006701a0,0xf,0,0,0)
1024 VBoxHeadless CALL _umtx_op(0x8074b3e58,0x15,0x1,0,0)
1024 VBoxHeadless RET _umtx_op 0
1024 VBoxHeadless RET _umtx_op 0
1024 VBoxHeadless CALL lseek(0x10,0x20bcc9400,SEEK_SET)
1024 VBoxHeadless RET lseek 8787891200/0x20bcc9400
1024 VBoxHeadless CALL read(0x10,0x8017e9000,0x3000)
1024 VBoxHeadless PSIG SIGALRM caught handler=0x80082d300 mask=0x7ffebedc code=0x10003
1024 VBoxHeadless RET sigwaitinfo 14/0xe
1024 VBoxHeadless CALL sigwaitinfo(0x7fffff41fea0,0x7fffff41fed0)
1024 VBoxHeadless RET nanosleep 0
1024 VBoxHeadless CALL nanosleep(0x7fffff39eee0,0x7fffff39eed0)
1024 VBoxHeadless PSIG SIGALRM caught handler=0x80082d300 mask=0x7ffebedc code=0x10003
1024 VBoxHeadless RET sigwaitinfo 14/0xe
1024 VBoxHeadless CALL sigwaitinfo(0x7fffff41fea0,0x7fffff41fed0)
1024 VBoxHeadless PSIG SIGALRM caught handler=0x80082d300 mask=0x7ffebedc code=0x10003
1024 VBoxHeadless RET sigwaitinfo 14/0xe
1024 VBoxHeadless CALL sigwaitinfo(0x7fffff41fea0,0x7fffff41fed0)
1024 VBoxHeadless GIO fd 16 read 4096 bytes
" 10 (IDNF )N9+(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00N$+(ada0:ata0:0:0:0): Retrying commandNG+(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Status ErrorN@+(ada0:ata0:0:0:0): A\
TA status: 41 (DRDY ERR), error: 10 (IDNF )N9+(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00N$+(ada0:ata0:0:0:0): Retrying commandNG+(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Sta\
tus ErrorN@+(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )N9+(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00N.+(ada0:ata0:0:0:0): Error 5, Retries exhaustedNE+g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5N\
G+(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Status ErrorN@+(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )N9+(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00N$+(ada\
0:ata0:0:0:0): Retrying commandNG+(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Status ErrorN@+(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )N9+(ada0:ata0:0:0:0): RES: 41 10 5\
a 33 c9 05 05 00 00 08 00N$+(ada0:ata0:0:0:0): Retrying commandNG+(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Status ErrorN@+(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )N9\
+(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00N$+(ada0:ata0:0:0:0): Retrying commandNG+(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Status ErrorN@+(ada0:ata0:0:0:0): ATA status: 41\
(DRDY ERR), error: 10 (IDNF )N9+(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00N$+(ada0:ata0:0:0:0): Retrying commandNG+(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Status ErrorN@+(\
ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )N9+(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00N.+(ada0:ata0:0:0:0): Error 5, Retries exhaustedNE+g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5NG+(ada0:ata0:\
0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Status ErrorN@+(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )N9+(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00N$+(ada0:ata0:0:0:0)\
: Retrying commandNG+(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Status ErrorN@+(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )N9+(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05\
00 00 08 00N$+(ada0:ata0:0:0:0): Retrying commandNG+(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Status ErrorN@+(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )N9+(ada0:ata0:0\
:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00N$+(ada0:ata0:0:0:0): Retrying commandNG+(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Status ErrorN@+(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), \
error: 10 (IDNF )N9+(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00N$+(ada0:ata0:0:0:0): Retrying commandNG+(ada0:ata0:0:0:0): WRITE_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Status ErrorN@+(ada0:ata0:0:0\
:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )N9+(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00N.+(ada0:ata0:0:0:0): Error 5, Retries exhaustedNE+g_vfs_done():ada0p2[WRITE(offset=49700761600, length=4096)]error = 5NG+(ada0:ata0:0:0:0): WRITE\
_DMA. ACB: ca 00 5a 33 c9 45 00 00 00 00 08 00N0+(ada0:ata0:0:0:0): CAM status: ATA Status ErrorN@+(ada0:ata0:0:0:0): ATA status: 41 (DRDY ERR), error: 10 (IDNF )N9+(ada0:ata0:0:0:0): RES: 41 10 5a 33 c9 05 05 00 00 08 00N$+(ada0:ata0:0:0:0): Retrying co\
mmandNG+(ada0:ata0:0:0:0): WRITE"
1024 VBoxHeadless RET read 12288/0x3000
1024 VBoxHeadless CALL _umtx_op(0x8006701a0,0xf,0,0,0)
1024 VBoxHeadless PSIG SIGALRM caught handler=0x80082d300 mask=0x7ffebedc code=0x10003
1024 VBoxHeadless RET sigwaitinfo 14/0xe
1024 VBoxHeadless CALL sigwaitinfo(0x7fffff41fea0,0x7fffff41fed0)
1024 VBoxHeadless PSIG SIGALRM caught handler=0x80082d300 mask=0x7ffebedc code=0x10003
1024 VBoxHeadless RET sigwaitinfo 14/0xe
1024 VBoxHeadless CALL sigwaitinfo(0x7fffff41fea0,0x7fffff41fed0)
1024 VBoxHeadless PSIG SIGALRM caught handler=0x80082d300 mask=0x7ffebedc code=0x10003
1024 VBoxHeadless RET sigwaitinfo 14/0xe
1024 VBoxHeadless CALL sigwaitinfo(0x7fffff41fea0,0x7fffff41fed0)
1024 VBoxHeadless PSIG SIGALRM caught handler=0x80082d300 mask=0x7ffebedc code=0x10003
1024 VBoxHeadless RET sigwaitinfo 14/0xe
1024 VBoxHeadless CALL sigwaitinfo(0x7fffff41fea0,0x7fffff41fed0)
1024 VBoxHeadless PSIG SIGALRM caught handler=0x80082d300 mask=0x7ffebedc code=0x10003
1024 VBoxHeadless RET sigwaitinfo 14/0xe
1024 VBoxHeadless CALL sigwaitinfo(0x7fffff41fea0,0x7fffff41fed0)
1024 VBoxHeadless CALL _umtx_op(0x8074b3658,0x15,0x1,0,0)
1024 VBoxHeadless RET _umtx_op 0
1024 VBoxHeadless RET _umtx_op 0
1024 VBoxHeadless CALL lseek(0x10,0x20aca4400,SEEK_SET)
1024 VBoxHeadless RET lseek 8770962432/0x20aca4400
1024 VBoxHeadless CALL read(0x10,0x8017e9000,0x4000)
1024 VBoxHeadless PSIG SIGALRM caught handler=0x80082d300 mask=0x7ffebedc code=0x10003
1024 VBoxHeadless RET sigwaitinfo 14/0xe
1024 VBoxHeadless CALL sigwaitinfo(0x7fffff41fea0,0x7fffff41fed0)
1024 VBoxHeadless GIO fd 16 read 4096 bytes
0x0000 434f 2020 2020 2020 2020 2020 3135 3835 3120 2020 2020 2020 2020 2020 2020 3231 3920 2020 2020 2020 2020 2020 2020 2020 3120 2020 2020 2020 2020 2020 2020 2020 3020 2020 2020 |CO 15851 219 1 0 |
by , 10 years ago
| Attachment: | vbox-ktrace-drdy-errors.txt.no_ioctl added |
|---|
First block of data only with ioctl's grep'd out... full (no grep) log here: http://flashback.sorbs.net/packages/vbox/
comment:9 by , 10 years ago
Still no hint what error is happening (ending up as VERR_UNRESOLVED_ERROR)... The working hypothesis still is that FreeBSD has some additional errno code which VBox doesn't know. There are a couple of places where VERR_UNRESOLVED_ERROR is abused in the vbox code (e.g. in the host CD/DVD/floppy support, i.e. when accessing the host's physical CD/DVD/floppy, which isn't the case here), but I can't see how these could be reached.
So it must be the general errno->error code mapping. For people who can modify and rebuild VirtualBox from sources it's quite easy to hunt things down. Search for VERR_UNRESOLVED_ERROR in src/VBox/Runtime/common/err/RTErrConvertFromErrno.cpp. In the line before the match, replace the AssertMsgFailed with AssertReleaseMsgFailed. This way the VM will immediately be killed once the fishy errno is encountered, and VBox.log will have the errno value which causes the problem. From there it's mostly finding out what Exxxx #define this value corresponds to.
comment:10 by , 10 years ago
Got one..
00:00:02.805971 VNCServerImpl::VRDEResize to 720x400x32bpp 00:00:02.822822 Guest Log: BIOS: Booting from Hard Disk... 00:00:21.502839 Guest Log: int13_harddisk: function 08, unmapped device for ELDL=82 00:00:21.503636 Guest Log: int13_harddisk: function 08, unmapped device for ELDL=83 00:00:21.504202 Guest Log: int13_harddisk: function 08, unmapped device for ELDL=84 00:00:21.504760 Guest Log: int13_harddisk: function 08, unmapped device for ELDL=85 00:00:21.505318 Guest Log: int13_harddisk: function 08, unmapped device for ELDL=86 00:00:21.505872 Guest Log: int13_harddisk: function 08, unmapped device for ELDL=87 00:00:21.511528 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0) 00:00:27.534181 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0xc4 (-1 usec ago) CmdIf1=0xec (-1 usec ago) 00:00:27.545450 PIIX3 ATA: Ctl#0: finished processing RESET 00:33:11.467890 00:33:11.467891 !!Assertion Failed!! 00:33:11.467891 Expression: <NULL> 00:33:11.467892 Location : /usr/ports/emulators/virtualbox-ose/work/VirtualBox-4.3.12/src/VBox/Runtime/common/err/RTErrConvertFromErrno.cpp(447) int RTErrConvertFromErrno(unsigned int) 00:33:11.486768 Unhandled error code 122
comment:12 by , 10 years ago
From what I can tell neither Mac OS X nor FreeBSD have such high errno values. So it is still mysterious where this superficially plausible but not clearly defined value might be coming from.
comment:15 by , 10 years ago
Actually it wasn't - it is from the guest root drive - so it's non ZFS related... so your guess is as good as mine on what error 122 is...
comment:16 by , 10 years ago
Ok - I went the wrong way on this ... the host OS for this drive is ZFS and returns 122 on ECKSUM (checksum error)
This is also:
#define ECKSUM 122 #define EFRAGS 123
And these:
E2BIG EACCES ECKSUM EEXIST EFAULT EINVAL EINVAL /* presume failure */ EIO EJUSTRETURN ENAMETOOLONG ENOATTR ENOENT ENOMEM ENOSPC ENOTSUP ENOTTY ENXIO EOPNOTSUPP EROFS ESTALE EXDEV
comment:17 by , 10 years ago
To summarize: I think the best we can do is give somewhat better diagnostics (instead of VERR_UNRESOLVED_ERROR), as the underlying problem is unlikely to go away with repeating the access. Don't feel confident about returning an uncorrectable ECC error instead of the generic "ID not found" error, because that can be misleading, too. Also I don't think delaying the error reporting brings a significant benefit, it's slowing things down unnecessarily and the end result for guests which trigger kernel panics in response to I/O trouble will be absolutely unchanged.
comment:18 by , 8 years ago
| Resolution: | → invalid |
|---|---|
| Status: | new → closed |
Closing as invalid because this is not a bug in VBox (the better diagnostics will be done eventually).
Note:
See TracTickets
for help on using tickets.
Additional kernel messages that might help: