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>