Virtualbox - VM autosuspend (host disk full)

philamonster · 11. Februar 2014

OMV 0.4.38 - 2.6.32-5-amd64
latest VB plugin from stable (OMV-plugins)

I have a VM that I use to host ownCloud and Subsonic services. Machine virtual disk is dynamic with an original limit of 10GB (I've since raised it to 15GB), as are all my VMs. I have attached 2 vdi's; 1 for OC (100GB) and another for Subsonic (200GB). Both are nowhere near capacity according to Debian:

Code

root@owncloud:~# df -h
Filesystem                                              Size  Used Avail Use% Mounted on
rootfs                                                  9.4G  2.2G  6.8G  25% /
udev                                                     10M     0   10M   0% /dev
tmpfs                                                   199M  304K  199M   1% /run
/dev/disk/by-uuid/xxxxx  9.4G  2.2G  6.8G  25% /
tmpfs                                                   5.0M     0  5.0M   0% /run/lock
tmpfs                                                   491M     0  491M   0% /run/shm
/dev/sdb1                                                99G  4.4G   90G   5% /var/www/owncloud/data
/dev/sdc1                                               197G   62G  125G  34% /home/subsonic/media

I know it says host but under settings in phpVirtualBox, guest system vdi shows a virtual size of 10GB and an actual size of 9.43GB. I have other VMs with same vdi limitations whose actual size are in correlation with what is seen in the guest OS and at most 30% disk space used. I don't foresee this being an issue anymore as I have expanded the vdi to 15GB but am curious as to why the actual and virtual sizes were so close on this particular VM. There's nothing stored on the system drive and I never write any data to it since I've had the VM set up which is going on 9 months now.

FWIW here is vbox.log that shows the initial suspension, me trying to start vm and then eventually powering off:

Code

83:22:50.324018 AHCI: Host disk full
83:22:50.324092 VM: Raising runtime error 'DevAHCI_DISKFULL' (fFlags=0x6)
83:22:50.324134 Changing the VM state from 'RUNNING' to 'SUSPENDING'.
83:22:50.324211 PDMR3Suspend: after     0 ms, 1 loops: 1 async tasks - ahci/0
83:22:50.324859 AHCI#1: Write at offset 86297063424 (524288 bytes left) returned rc=VERR_DISK_FULL
83:22:50.325736 AHCI#1: Write at offset 86297587712 (524288 bytes left) returned rc=VERR_DISK_FULL
83:22:50.967462 PDMR3Suspend: 643 282 407 ns run time
83:22:50.967497 Changing the VM state from 'SUSPENDING' to 'SUSPENDED'.
83:22:50.967905 Console: VM runtime error: fatal=false, errorID=DevAHCI_DISKFULL message="Host system reported disk full. VM execution is suspended. You can resume after freeing some space"
83:34:48.503475 Changing the VM state from 'SUSPENDED' to 'RESUMING'.
83:34:48.504671 Changing the VM state from 'RESUMING' to 'RUNNING'.
83:34:48.506936 AHCI#1: Write at offset 86296539136 (524288 bytes left) returned rc=VERR_DISK_FULL
83:34:48.506956 AHCI: Host disk full
83:34:48.506998 VM: Raising runtime error 'DevAHCI_DISKFULL' (fFlags=0x6)
83:34:48.507027 Changing the VM state from 'RUNNING' to 'SUSPENDING'.
83:34:48.507082 PDMR3Suspend: after     0 ms, 1 loops: 1 async tasks - ahci/0
83:34:48.507787 AHCI#1: Write at offset 86297063424 (524288 bytes left) returned rc=VERR_DISK_FULL
83:34:48.508777 AHCI#1: Write at offset 86297587712 (524288 bytes left) returned rc=VERR_DISK_FULL
83:34:48.509535 AHCI#1: Write at offset 86298112000 (524288 bytes left) returned rc=VERR_DISK_FULL
83:34:48.510363 AHCI#1: Write at offset 86298636288 (524288 bytes left) returned rc=VERR_DISK_FULL
83:34:48.510988 AHCI#1: Write at offset 86299160576 (393216 bytes left) returned rc=VERR_DISK_FULL
83:34:48.704874 PDMR3Suspend: 197 821 416 ns run time
83:34:48.704914 Changing the VM state from 'SUSPENDING' to 'SUSPENDED'.
83:34:48.705292 Console: VM runtime error: fatal=false, errorID=DevAHCI_DISKFULL message="Host system reported disk full. VM execution is suspended. You can resume after freeing some space"
83:35:47.355574 Changing the VM state from 'SUSPENDED' to 'RESUMING'.
83:35:47.356675 Changing the VM state from 'RESUMING' to 'RUNNING'.
83:35:47.358265 AHCI#1: Write at offset 86296539136 (524288 bytes left) returned rc=VERR_DISK_FULL
83:35:47.358289 AHCI: Host disk full
83:35:47.358330 VM: Raising runtime error 'DevAHCI_DISKFULL' (fFlags=0x6)
83:35:47.358359 Changing the VM state from 'RUNNING' to 'SUSPENDING'.
83:35:47.358411 PDMR3Suspend: after     0 ms, 1 loops: 1 async tasks - ahci/0
83:35:47.359141 AHCI#1: Write at offset 86297063424 (524288 bytes left) returned rc=VERR_DISK_FULL
83:35:47.360236 AHCI#1: Write at offset 86297587712 (524288 bytes left) returned rc=VERR_DISK_FULL
83:35:47.361073 AHCI#1: Write at offset 86298112000 (524288 bytes left) returned rc=VERR_DISK_FULL
83:35:47.362084 AHCI#1: Write at offset 86298636288 (524288 bytes left) returned rc=VERR_DISK_FULL
83:35:47.363021 AHCI#1: Write at offset 86299160576 (393216 bytes left) returned rc=VERR_DISK_FULL
83:35:47.557019 PDMR3Suspend: 198 635 111 ns run time
83:35:47.557048 Changing the VM state from 'SUSPENDING' to 'SUSPENDED'.
83:35:47.557429 Console: VM runtime error: fatal=false, errorID=DevAHCI_DISKFULL message="Host system reported disk full. VM execution is suspended. You can resume after freeing some space"
83:36:00.862377 Console::powerDown(): A request to power off the VM has been issued (mMachineState=Stopping, InUninit=0)
83:36:00.862479 VRDP: TCP server closed.
83:36:00.863103 Changing the VM state from 'SUSPENDED' to 'POWERING_OFF'.

Alles anzeigen

From OMV host:

Code

me@omv:~$ df -h
Filesystem            Size  Used Avail Use% Mounted on
/dev/sde1              71G  1.5G   66G   3% /
tmpfs                 4.0G     0  4.0G   0% /lib/init/rw
udev                  3.9G  224K  3.9G   1% /dev
tmpfs                 4.0G     0  4.0G   0% /dev/shm
/dev/mapper/500RE4-homes     147G  9.5G  131G   7% /media/xxxx
/dev/mapper/500RE4-apps        116G  6.9G  103G   7% /media/xxxx
/dev/mapper/500BLUE-storage  2.7T  465G  2.1T  18% /media/xxxx
/dev/mapper/500RE4-vbox       197G  135G   52G  73% /media/xxxx
/dev/sdk1             1.8T  833G  909G  48% /media/xxxxx

Alles anzeigen

Also note that my storage vdi's for OC and Subsonic live on /dev/mapper/500BLUE-storage and not dev/mapper/500RE4-vbox which hosts the vm itself and system vdi.

philamonster · 13. Februar 2014

Quotas.

Disabled quotas on storage for vbox user and VM started up on it's own. No idea why as threshold was nowhere close to being reached.

philamonster · 18. März 2014

This is happening again. Same message about host disk being full. Don't have logs readily available but will try to post more info later today.

Anyone experience anything like this?

philamonster · 19. März 2014

Code

00:14:35.905032 AHCI#1: Write at offset 90229964800 (4096 bytes left) returned rc=VERR_DISK_FULL
00:14:35.905061 AHCI: Host disk full
00:14:35.905106 VM: Raising runtime error 'DevAHCI_DISKFULL' (fFlags=0x6)
00:14:35.905123 Changing the VM state from 'RUNNING' to 'SUSPENDING'.
00:14:36.124678 PDMR3Suspend: 219 532 892 ns run time
00:14:36.124710 Changing the VM state from 'SUSPENDING' to 'SUSPENDED'.
00:14:36.125053 Console: VM runtime error: fatal=false, errorID=DevAHCI_DISKFULL message="Host system reported disk full. VM execution is suspended. You can resume after freeing some space"
00:14:38.365606 VRDP: Connection closed: 
00:14:38.365650 VRDP: Logoff: localhost (127.0.0.1) build 2601. User: [] Domain: [] Reason 0xFFFFFFFF.
00:14:38.365705 VBVA: VRDP acceleration has been disabled.

Narrowed it down to the VDI I attach for ownCloud storage. If I remove both attached drives OS boots without issue minus complaints of missing disks. Once I add the 100GB VDI for owncloud the machine is suspended. I've attached it to another test VM and same thing happens when mounting manually or via fstab at boot.

Not sure how much time I want to spend on this. I do have a backup of the storage VDI I could try to restore but I get the feeling this could be a recurring issue for whatever reason. Might be time to move to OMV .5. I haven't looked into it too much but is there a VBox plugin that's closer to the current version with .5?

philamonster · 30. März 2014

Ended up happening with either subsonic or owncloud storage drive mounted. Had an idea to convert VDI's to fixed and am able to boot with both drives mounted. VM no longer pauses. See how long that lasts.

Jetzt mitmachen!