Virtualbox + Windows 7 = Multiple hangs / resets

[cyberjock]

If you used the Virtualbox template or virtualbox "hack" guide I wrote, the answer to upgrading is easy...

pkg update
pkg upgrade

 

[Tsaukpaetra]

Just my two cents, but the error seems to indicate an asynchronous IO request was made with no handle or the handle was discarded before the request could complete.
Maybe, I'm no virtualbox guru.
It's weird, I slapped iTunes onto a Windows 8.1 VM no problem, but I've only tested it until it gets to the "Sign into you apple ID" screen.
I can share it since it's just a trial-licensed version set to expire on Oct 19th, if you want to try loading it up on your system to see if it still crashes then.

 

[Tsaukpaetra]

Ok, just so I have it written, I'm providing the VM as an OVA at Windows8.1-iTunes.ova. Once again, this will expire because it's a trial, so there should be no trouble using it as you only have like two weeks to use it as a test.

 

[filipvh]

When I tried to run a Windows Server 2012 R2 on VBox in a jail, it(win) was highly unstable. Converting the virtual disk to the variant 'Fixed' solved it.
Not ideal, since this takes 'a while longer' :p ....
But... it helped :p
Had not probs since

 

[1.21gigawatts]

Well, the bad news is that this isn't limited to iTunes. I have the SAME problem on both 32 and 64 bit windows 7 VMs. I get the hang pretty much randomly. Sometimes there's a log, sometimes there isn't. This time, I was installing the vBox Guest Additions and the machine just hung. The log entry is much the same as Andy's. And this is with the template included with FreeNAS 9.2.1.8.

I have tried this with and without the Guest Additions, with NAT and Bridged networking, with Dynamic and Fixed disks. I'm out of things to try.

What's next?

here's the log:
<CODE>
00:02:06.978216 Changing the VM state from 'SUSPENDED' to 'RESUMING'.
00:02:06.978537 AIOMgr: Endpoint for file '/usr/home/vbox/VirtualBox VMs/Win7/Win7_Fixed.vdi' (flags 000c0723) created successfully
00:02:06.979306 Changing the VM state from 'RESUMING' to 'RUNNING'.
00:02:48.669716 AIOMgr: I/O manager 0x0000080dbf8920 encountered a critical error (rc=VERR_FILE_AIO_NO_REQUEST) during operation. Falling back to failsafe mode. Expect reduced performance
00:02:48.669820 AIOMgr: Error happened in /wrkdirs/usr/ports/emulators/virtualbox-ose/work/VirtualBox-4.3.12/src/VBox/VMM/VMMR3/PDMAsyncCompletionFileNormal.cpp:(1664){int pdmacFileAioMgrNormal(RTTHREADINT*, void*)}
00:02:48.669890 AIOMgr: Please contact the product vendor
00:03:48.766409 AHCI#0: Port 0 reset
00:03:48.766530 AHCI#0P0: Cancelled task 1
00:03:48.766572 AHCI#0P0: Cancelled task 2
00:03:48.766611 AHCI#0P0: Cancelled task 3
00:03:48.766647 AHCI#0P0: Cancelled task 4
00:03:48.766683 AHCI#0P0: Cancelled task 5
00:03:48.766719 AHCI#0P0: Cancelled task 6
00:03:48.766755 AHCI#0P0: Cancelled task 7
00:03:48.766792 AHCI#0P0: Cancelled task 8
00:03:48.766829 AHCI#0P0: Cancelled task 9
00:03:48.766887 AHCI#0P0: Cancelled task 10
00:03:48.766945 AHCI#0P0: Cancelled task 11
00:03:48.766972 AHCI#0P0: Cancelled task 12
00:03:48.767001 AHCI#0P0: Cancelled task 13
00:03:48.767026 AHCI#0P0: Cancelled task 14
00:03:48.767052 AHCI#0P0: Cancelled task 15
00:03:48.767078 AHCI#0P0: Cancelled task 16
00:03:48.767104 AHCI#0P0: Cancelled task 17
00:03:48.767131 AHCI#0P0: Cancelled task 18
00:03:48.767157 AHCI#0P0: Cancelled task 19
00:03:48.767182 AHCI#0P0: Cancelled task 20
00:03:48.767208 AHCI#0P0: Cancelled task 21
00:03:48.767245 AHCI#0P0: Cancelled task 22
00:03:48.767271 AHCI#0P0: Cancelled task 23
00:03:48.767297 AHCI#0P0: Cancelled task 24
00:03:48.767323 AHCI#0P0: Cancelled task 25
00:03:48.767348 AHCI#0P0: Cancelled task 26
00:03:48.767373 AHCI#0P0: Cancelled task 27
00:03:48.767398 AHCI#0P0: Cancelled task 28
00:03:48.767424 AHCI#0P0: Cancelled task 29
00:03:48.767449 AHCI#0P0: Cancelled task 30
00:03:48.767474 AHCI#0P0: Cancelled task 31
00:04:49.263402 AHCI#0: Port 0 reset
00:04:49.263503 AHCI#0P0: Cancelled task 1
00:04:49.263567 AHCI#0P0: Cancelled task 2
00:04:49.263596 AHCI#0P0: Cancelled task 3
00:04:49.263623 AHCI#0P0: Cancelled task 4
00:04:49.263650 AHCI#0P0: Cancelled task 5
00:04:49.263676 AHCI#0P0: Cancelled task 6
00:04:49.263703 AHCI#0P0: Cancelled task 7
00:04:49.263730 AHCI#0P0: Cancelled task 8
00:04:49.263757 AHCI#0P0: Cancelled task 9
00:04:49.263783 AHCI#0P0: Cancelled task 10
00:04:49.263809 AHCI#0P0: Cancelled task 11
00:04:49.263835 AHCI#0P0: Cancelled task 12
00:04:49.263861 AHCI#0P0: Cancelled task 13
00:04:49.263906 AHCI#0P0: Cancelled task 14
00:04:49.263932 AHCI#0P0: Cancelled task 15
00:04:49.263959 AHCI#0P0: Cancelled task 16
00:04:49.263984 AHCI#0P0: Cancelled task 17
00:04:49.264014 AHCI#0P0: Cancelled task 18
00:04:49.264041 AHCI#0P0: Cancelled task 19
00:04:49.264066 AHCI#0P0: Cancelled task 20
00:04:49.264091 AHCI#0P0: Cancelled task 21
00:04:49.264117 AHCI#0P0: Cancelled task 22
00:04:49.264142 AHCI#0P0: Cancelled task 23
00:04:49.264167 AHCI#0P0: Cancelled task 24
00:04:49.264193 AHCI#0P0: Cancelled task 25
00:04:49.264230 AHCI#0P0: Cancelled task 26
00:04:49.264256 AHCI#0P0: Cancelled task 27
00:04:49.264281 AHCI#0P0: Cancelled task 28
00:04:49.264306 AHCI#0P0: Cancelled task 29
00:04:49.264331 AHCI#0P0: Cancelled task 30
00:04:49.264356 AHCI#0P0: Cancelled task 31
00:05:49.770409 AHCI#0: Port 0 reset
00:05:49.770527 AHCI#0P0: Cancelled task 1
00:05:49.770573 AHCI#0P0: Cancelled task 2
00:05:49.770613 AHCI#0P0: Cancelled task 3
00:05:49.770652 AHCI#0P0: Cancelled task 4
00:05:49.770733 AHCI#0P0: Cancelled task 5
00:05:49.770787 AHCI#0P0: Cancelled task 6
00:05:49.770814 AHCI#0P0: Cancelled task 7
00:05:49.770841 AHCI#0P0: Cancelled task 8
00:05:49.770867 AHCI#0P0: Cancelled task 9
00:05:49.770895 AHCI#0P0: Cancelled task 10
00:05:49.770927 AHCI#0P0: Cancelled task 11
00:05:49.770954 AHCI#0P0: Cancelled task 12
00:05:49.770981 AHCI#0P0: Cancelled task 13
00:05:49.771016 AHCI#0P0: Cancelled task 14
00:05:49.771044 AHCI#0P0: Cancelled task 15
00:05:49.771071 AHCI#0P0: Cancelled task 16
00:05:49.771112 AHCI#0P0: Cancelled task 17
00:05:49.771140 AHCI#0P0: Cancelled task 18
00:05:49.771167 AHCI#0P0: Cancelled task 19
00:05:49.771194 AHCI#0P0: Cancelled task 20
00:05:49.771221 AHCI#0P0: Cancelled task 21
00:05:49.771248 AHCI#0P0: Cancelled task 22
00:05:49.771275 AHCI#0P0: Cancelled task 23
00:05:49.771302 AHCI#0P0: Cancelled task 24
00:05:49.771329 AHCI#0P0: Cancelled task 25
00:05:49.771356 AHCI#0P0: Cancelled task 26
00:05:49.771383 AHCI#0P0: Cancelled task 27
00:05:49.771410 AHCI#0P0: Cancelled task 28
00:05:49.771500 AHCI#0P0: Cancelled task 29
00:05:49.771528 AHCI#0P0: Cancelled task 30
00:05:49.771555 AHCI#0P0: Cancelled task 31
00:06:50.267396 AHCI#0: Port 0 reset
00:06:50.267495 AHCI#0P0: Cancelled task 1
00:06:50.267526 AHCI#0P0: Cancelled task 2
00:06:50.267553 AHCI#0P0: Cancelled task 3
00:06:50.267580 AHCI#0P0: Cancelled task 4
00:06:50.267607 AHCI#0P0: Cancelled task 5
00:06:50.267634 AHCI#0P0: Cancelled task 6
00:06:50.267660 AHCI#0P0: Cancelled task 7
00:06:50.267685 AHCI#0P0: Cancelled task 8
00:06:50.267745 AHCI#0P0: Cancelled task 9
00:06:50.267771 AHCI#0P0: Cancelled task 10
00:06:50.267798 AHCI#0P0: Cancelled task 11
00:06:50.267824 AHCI#0P0: Cancelled task 12
00:06:50.267849 AHCI#0P0: Cancelled task 13
00:06:50.267875 AHCI#0P0: Cancelled task 14
00:06:50.267901 AHCI#0P0: Cancelled task 15
00:06:50.267926 AHCI#0P0: Cancelled task 16
00:06:50.267951 AHCI#0P0: Cancelled task 17
00:06:50.267976 AHCI#0P0: Cancelled task 18
00:06:50.268005 AHCI#0P0: Cancelled task 19
00:06:50.268043 AHCI#0P0: Cancelled task 20
00:06:50.268068 AHCI#0P0: Cancelled task 21
00:06:50.268093 AHCI#0P0: Cancelled task 22
00:06:50.268119 AHCI#0P0: Cancelled task 23
00:06:50.268144 AHCI#0P0: Cancelled task 24
00:06:50.268170 AHCI#0P0: Cancelled task 25
00:06:50.268195 AHCI#0P0: Cancelled task 26
00:06:50.268221 AHCI#0P0: Cancelled task 27
00:06:50.268246 AHCI#0P0: Cancelled task 28
00:06:50.268272 AHCI#0P0: Cancelled task 29
00:06:50.268297 AHCI#0P0: Cancelled task 30
00:06:50.268322 AHCI#0P0: Cancelled task 31
00:07:14.644810 Console::powerDown(): A request to power off the VM has been issued (mMachineState=Stopping, InUninit=0)
00:07:14.645600 VBVA: VRDP acceleration has been disabled.
00:07:14.647138 Changing the VM state from 'RUNNING' to 'POWERING_OFF'.
00:07:14.647244 ****************** Guest state at power off ******************
00:07:14.647273 Guest CPUM (VCPU 0) state:
00:07:14.647290 eax=00000000 ebx=00000000 ecx=00000000 edx=8001003b esi=8295c340 edi=82952d20
00:07:14.647295 eip=82819106 esp=8294fc9c ebp=8294fd20 iopl=0 rf nv up ei nt zr ac po nc
00:07:14.647305 cs={0008 base=0000000000000000 limit=ffffffff flags=0000d09b} dr0=00000000 dr1=00000000
00:07:14.647311 ds={0023 base=0000000000000000 limit=ffffffff flags=0000c0f3} dr2=00000000 dr3=00000000
00:07:14.647315 es={0023 base=0000000000000000 limit=ffffffff flags=0000c0f3} dr4=00000000 dr5=00000000
00:07:14.647318 fs={0030 base=0000000082952c00 limit=00003748 flags=00004093} dr6=ffff4ff0 dr7=00000400
00:07:14.647323 gs={0000 base=0000000000000000 limit=00000000 flags=00010000} cr0=8001003b cr2=fe3a4000
00:07:14.647327 ss={0010 base=0000000000000000 limit=ffffffff flags=0000c093} cr3=00185000 cr4=00000699
00:07:14.647331 gdtr=0000000080b95000:03ff idtr=0000000080b95400:07ff eflags=00010296
00:07:14.647335 ldtr={0000 base=00000000 limit=00000000 flags=00010000}
00:07:14.647337 tr ={0028 base=801c6000 limit=000020ab flags=0000008b}
00:07:14.647340 SysEnter={cs=0000 eip=00000000 esp=00000000}
00:07:14.647342 FCW=027f FSW=0000 FTW=0000 FOP=0000 MXCSR=00001f80 MXCSR_MASK=0000ffff
00:07:14.647346 FPUIP=00000000 CS=0000 Rsrvd1=0000 FPUDP=00000000 DS=0000 Rsvrd2=0000
00:07:14.647349 ST(0)=FPR0={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
00:07:14.647354 ST(1)=FPR1={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
00:07:14.647359 ST(2)=FPR2={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
00:07:14.647363 ST(3)=FPR3={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
00:07:14.647367 ST(4)=FPR4={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
00:07:14.647371 ST(5)=FPR5={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
00:07:14.647375 ST(6)=FPR6={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
00:07:14.647380 ST(7)=FPR7={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
00:07:14.647384 XMM0 =00000000'00000000'00000000'00000000 XMM1 =00000000'00000000'00000000'00000000
00:07:14.647389 XMM2 =00000000'00000000'00000000'00000000 XMM3 =00000000'00000000'00000000'00000000
00:07:14.647395 XMM4 =00000000'00000000'00000000'00000000 XMM5 =00000000'00000000'00000000'00000000
00:07:14.647400 XMM6 =00000000'00000000'00000000'00000000 XMM7 =00000000'00000000'00000000'00000000
00:07:14.647405 XMM8 =00000000'00000000'00000000'00000000 XMM9 =00000000'00000000'00000000'00000000
00:07:14.647410 XMM10=00000000'00000000'00000000'00000000 XMM11=00000000'00000000'00000000'00000000
00:07:14.647415 XMM12=00000000'00000000'00000000'00000000 XMM13=00000000'00000000'00000000'00000000
00:07:14.647420 XMM14=00000000'00000000'00000000'00000000 XMM15=00000000'00000000'00000000'00000000
00:07:14.647426 EFER =0000000000000000
00:07:14.647427 PAT =0007010600070106
00:07:14.647429 STAR =0000000000000000
00:07:14.647430 CSTAR =0000000000000000
00:07:14.647431 LSTAR =0000000000000000
00:07:14.647433 SFMASK =0000000000000000
00:07:14.647434 KERNELGSBASE =0000000000000000
00:07:14.647435 ***
00:07:14.647458 Guest paging mode: 32-bit (changed 25784 times), A20 enabled (changed 2 times)
00:07:14.647461 Shadow paging mode: PAE
00:07:14.647463 Host paging mode: AMD64+G+NX
00:07:14.647464 ***
00:07:14.647499 Active Timers (pVM=000000080067a000)
00:07:14.647501 pTimerR3 offNext offPrev offSched Clock Time Expire HzHint State Description
00:07:14.647522 00000008006bdc90 00011340 00000000 00000000 Real 201754507 201754526 0 2-ACTIVE VGA Refresh Timer
00:07:14.647530 00000008006cefd0 ffffff80 fffeecc0 00000000 Real 201754507 201754529 0 2-ACTIVE EMT Yielder
00:07:14.647538 00000008006cef50 00000000 00000080 00000000 Real 201754507 201755206 0 2-ACTIVE CPU Load Timer
00:07:14.647555 00000008006b5300 00000670 00000000 00000000 VrSy 434321265049 434325745939 99 2-ACTIVE i8254 Programmable Interval Timer
00:07:14.647564 00000008006b5970 000185d0 fffff990 00000000 VrSy 434321274157 434990000000 0 2-ACTIVE MC146818 RTC/CMOS - Second
00:07:14.647571 00000008006cdf40 00000000 fffe7a30 00000000 VrSy 434321282092 1199864031601 0 2-ACTIVE ACPI PM Timer
00:07:14.647579 ***
00:07:14.647584 Shadow GDT (GCAddr=fcdbb000):
00:07:14.647591 0008 - 0000ffff 00dfbb00 - base=00000000 limit=ffffffff dpl=1 CodeER Accessed Present Page 32-bit
00:07:14.647595 0010 - 0000ffff 00dfb300 - base=00000000 limit=ffffffff dpl=1 DataRW Accessed Present Page 32-bit
00:07:14.647599 0018 - 0000ffff 00cffb00 - base=00000000 limit=ffffffff dpl=3 CodeER Accessed Present Page 32-bit
00:07:14.647603 0020 - 0000ffff 00cff300 - base=00000000 limit=ffffffff dpl=3 DataRW Accessed Present Page 32-bit
00:07:14.647607 0028 - 81500087 fc008b00 - base=fc008150 limit=00000087 dpl=0 TSS32Busy Present 16-bit HyperTSS
00:07:14.647611 0030 - 2c003748 8250b395 - base=82952c00 limit=00003748 dpl=1 DataRW Accessed Present 32-bit
00:07:14.647614 0038 - 00000fff 0040f300 - base=00000000 limit=00000fff dpl=3 DataRW Accessed Present 32-bit
00:07:14.647618 0040 - 0400ffff 0000f300 - base=00000400 limit=0000ffff dpl=3 DataRW Accessed Present 16-bit
00:07:14.647622 0070 - 500003ff 8010b3b9 - base=80b95000 limit=000003ff dpl=1 DataRW Accessed Present 16-bit
00:07:14.647626 00e8 - 0000ffff 0010b300 - base=00000000 limit=0000ffff dpl=1 DataRW Accessed Present 16-bit
00:07:14.647629 00f0 - fe3003b2 8210b985 - base=8285fe30 limit=000003b2 dpl=1 CodeEO Accessed Present 16-bit
00:07:14.647633 00f8 - 0000ffff 0010b300 - base=00000000 limit=0000ffff dpl=1 DataRW Accessed Present 16-bit
00:07:14.647659 ffd8 - 81d80087 fc008900 - base=fc0081d8 limit=00000087 dpl=0 TSS32Avail Present 16-bit HyperTSSTrap08
00:07:14.647663 ffe0 - 81500087 fe008b80 - base=fe808150 limit=00000087 dpl=0 TSS32Busy Present 16-bit
00:07:14.647667 ffe8 - 0000ffff 00af9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 16-bit HyperCS64
00:07:14.647671 fff0 - 0000ffff 00cf9300 - base=00000000 limit=ffffffff dpl=0 DataRW Accessed Present Page 32-bit HyperDS
00:07:14.647675 fff8 - 0000ffff 00cf9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 32-bit HyperCS
00:07:14.647676 ***
00:07:14.647698 ************** End of Guest state at power off ***************
00:07:14.648050 PDMR3PowerOff: after 0 ms, 1 loops: 1 async tasks - ahci/0
</CODE>