Virtualbox service fails to start due to hanging plymouth --ping response

Bug #1722745 reported by Bruce Pieterse
28
This bug affects 6 people
Affects Status Importance Assigned to Milestone
virtualbox (Ubuntu)
Fix Released
Undecided
Unassigned

Bug Description

I am unable to start any Vagrant VM's due to the virtualbox service not being able to start or restart.

$ VBoxManage --version
WARNING: The character device /dev/vboxdrv does not exist.
  Please install the virtualbox-dkms package and the appropriate
  headers, most likely linux-headers-generic.

  You will not be able to start VMs until this problem is fixed.

I tried reinstalling the virtualbox-dkms package, but the process takes a while to complete due to the virtualbox service timing out.

$ sudo apt-get install virtualbox-dkms --reinstall
Reading package lists... Done
Building dependency tree
Reading state information... Done
0 upgraded, 0 newly installed, 1 reinstalled, 0 to remove and 0 not upgraded.
Need to get 0 B/646 kB of archives.
After this operation, 0 B of additional disk space will be used.
(Reading database ... 351318 files and directories currently installed.)
Preparing to unpack .../virtualbox-dkms_5.1.28-dfsg-2_all.deb ...

-------- Uninstall Beginning --------
Module: virtualbox
Version: 5.1.28
Kernel: 4.13.0-12-generic (x86_64)
-------------------------------------

Status: Before uninstall, this module version was ACTIVE on this kernel.

vboxdrv.ko:
 - Uninstallation
   - Deleting from: /lib/modules/4.13.0-12-generic/updates/dkms/
 - Original module
   - No original module was found for this module on this kernel.
   - Use the dkms install command to reinstall any previous module version.

vboxnetadp.ko:
 - Uninstallation
   - Deleting from: /lib/modules/4.13.0-12-generic/updates/dkms/
 - Original module
   - No original module was found for this module on this kernel.
   - Use the dkms install command to reinstall any previous module version.

vboxnetflt.ko:
 - Uninstallation
   - Deleting from: /lib/modules/4.13.0-12-generic/updates/dkms/
 - Original module
   - No original module was found for this module on this kernel.
   - Use the dkms install command to reinstall any previous module version.

vboxpci.ko:
 - Uninstallation
   - Deleting from: /lib/modules/4.13.0-12-generic/updates/dkms/
 - Original module
   - No original module was found for this module on this kernel.
   - Use the dkms install command to reinstall any previous module version.

depmod...

DKMS: uninstall completed.

------------------------------
Deleting module version: 5.1.28
completely from the DKMS tree.
------------------------------
Done.
Unpacking virtualbox-dkms (5.1.28-dfsg-2) over (5.1.28-dfsg-2) ...
Setting up virtualbox-dkms (5.1.28-dfsg-2) ...
Loading new virtualbox-5.1.28 DKMS files...
Building for 4.13.0-12-generic
Building initial module for 4.13.0-12-generic
Done.

vboxdrv:
Running module version sanity check.
 - Original module
   - No original module exists within this kernel
 - Installation
   - Installing to /lib/modules/4.13.0-12-generic/updates/dkms/

vboxnetadp.ko:
Running module version sanity check.
 - Original module
   - No original module exists within this kernel
 - Installation
   - Installing to /lib/modules/4.13.0-12-generic/updates/dkms/

vboxnetflt.ko:
Running module version sanity check.
 - Original module
   - No original module exists within this kernel
 - Installation
   - Installing to /lib/modules/4.13.0-12-generic/updates/dkms/

vboxpci.ko:
Running module version sanity check.
 - Original module
   - No original module exists within this kernel
 - Installation
   - Installing to /lib/modules/4.13.0-12-generic/updates/dkms/

depmod...

DKMS: install completed.

Job for virtualbox.service failed because a timeout was exceeded.
See "systemctl status virtualbox.service" and "journalctl -xe" for details.
invoke-rc.d: initscript virtualbox, action "restart" failed.
● virtualbox.service - LSB: VirtualBox Linux kernel module
   Loaded: loaded (/etc/init.d/virtualbox; generated; vendor preset: enabled)
   Active: failed (Result: timeout) since Wed 2017-10-11 10:24:15 SAST; 29ms ago
     Docs: man:systemd-sysv-generator(8)
  Process: 17897 ExecStart=/etc/init.d/virtualbox start (code=killed, signal=TERM)
    Tasks: 2 (limit: 4915)
   CGroup: /system.slice/virtualbox.service
           ├─ 1522 plymouth --ping
           └─17900 plymouth --ping

At this point, we now have two plymouth --ping processes.

After this I tried removing virtualbox and the virtualbox-dkms packages and tried to reinstall:

$sudo apt-get remove virtualbox
[sudo] password for bruce:
Reading package lists... Done
Building dependency tree
Reading state information... Done
The following packages were automatically installed and are no longer required:
  libgsoap-2.8.49 libvncserver1
Use 'sudo apt autoremove' to remove them.
The following packages will be REMOVED:
  virtualbox virtualbox-qt
0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded.
After this operation, 104 MB disk space will be freed.
Do you want to continue? [Y/n] y
(Reading database ... 351317 files and directories currently installed.)
Removing virtualbox-qt (5.1.28-dfsg-2) ...
Removing virtualbox (5.1.28-dfsg-2) ...
Processing triggers for mime-support (3.60ubuntu1) ...
Processing triggers for desktop-file-utils (0.23-1ubuntu3) ...
Processing triggers for man-db (2.7.6.1-2) ...
Processing triggers for shared-mime-info (1.8-1) ...
Processing triggers for gnome-menus (3.13.3-6ubuntu5) ...
Processing triggers for hicolor-icon-theme (0.17-1) ...
$ sudo apt-get autoremove
Reading package lists... Done
Building dependency tree
Reading state information... Done
The following packages will be REMOVED:
  libgsoap-2.8.49 libvncserver1
0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded.
After this operation, 1,838 kB disk space will be freed.
Do you want to continue? [Y/n] y
(Reading database ... 351009 files and directories currently installed.)
Removing libgsoap-2.8.49:amd64 (2.8.49-1) ...
Removing libvncserver1:amd64 (0.9.11+dfsg-1) ...
Processing triggers for libc-bin (2.26-0ubuntu1) ...
/sbin/ldconfig.real: /usr/lib/nvidia-384/libEGL.so.1 is not a symbolic link

/sbin/ldconfig.real: /usr/lib32/nvidia-384/libEGL.so.1 is not a symbolic link

$ sudo apt-get remove virtualbox-dkms
Reading package lists... Done
Building dependency tree
Reading state information... Done
The following packages will be REMOVED:
  virtualbox-dkms
0 upgraded, 0 newly installed, 1 to remove and 0 not upgraded.
After this operation, 5,284 kB disk space will be freed.
Do you want to continue? [Y/n] y
(Reading database ... 350995 files and directories currently installed.)
Removing virtualbox-dkms (5.1.28-dfsg-2) ...

-------- Uninstall Beginning --------
Module: virtualbox
Version: 5.1.28
Kernel: 4.13.0-12-generic (x86_64)
-------------------------------------

Status: Before uninstall, this module version was ACTIVE on this kernel.

vboxdrv.ko:
 - Uninstallation
   - Deleting from: /lib/modules/4.13.0-12-generic/updates/dkms/
 - Original module
   - No original module was found for this module on this kernel.
   - Use the dkms install command to reinstall any previous module version.

vboxnetadp.ko:
 - Uninstallation
   - Deleting from: /lib/modules/4.13.0-12-generic/updates/dkms/
 - Original module
   - No original module was found for this module on this kernel.
   - Use the dkms install command to reinstall any previous module version.

vboxnetflt.ko:
 - Uninstallation
   - Deleting from: /lib/modules/4.13.0-12-generic/updates/dkms/
 - Original module
   - No original module was found for this module on this kernel.
   - Use the dkms install command to reinstall any previous module version.

vboxpci.ko:
 - Uninstallation
   - Deleting from: /lib/modules/4.13.0-12-generic/updates/dkms/
 - Original module
   - No original module was found for this module on this kernel.
   - Use the dkms install command to reinstall any previous module version.

depmod...

DKMS: uninstall completed.

------------------------------
Deleting module version: 5.1.28
completely from the DKMS tree.
------------------------------
Done.
$ sudo apt-get install virtualbox
Reading package lists... Done
Building dependency tree
Reading state information... Done
The following additional packages will be installed:
  libgsoap-2.8.49 libvncserver1 virtualbox-dkms virtualbox-qt
Suggested packages:
  vde2 virtualbox-guest-additions-iso
The following NEW packages will be installed:
  libgsoap-2.8.49 libvncserver1 virtualbox virtualbox-dkms virtualbox-qt
0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded.
Need to get 354 kB/25.1 MB of archives.
After this operation, 111 MB of additional disk space will be used.
Do you want to continue? [Y/n] y
Get:1 http://za.archive.ubuntu.com/ubuntu artful/universe amd64 libgsoap-2.8.49 amd64 2.8.49-1 [232 kB]
Get:2 http://za.archive.ubuntu.com/ubuntu artful/main amd64 libvncserver1 amd64 0.9.11+dfsg-1 [122 kB]
Fetched 354 kB in 2s (167 kB/s)
Selecting previously unselected package libgsoap-2.8.49:amd64.
(Reading database ... 350712 files and directories currently installed.)
Preparing to unpack .../libgsoap-2.8.49_2.8.49-1_amd64.deb ...
Unpacking libgsoap-2.8.49:amd64 (2.8.49-1) ...
Selecting previously unselected package libvncserver1:amd64.
Preparing to unpack .../libvncserver1_0.9.11+dfsg-1_amd64.deb ...
Unpacking libvncserver1:amd64 (0.9.11+dfsg-1) ...
Selecting previously unselected package virtualbox-dkms.
Preparing to unpack .../virtualbox-dkms_5.1.28-dfsg-2_all.deb ...
Unpacking virtualbox-dkms (5.1.28-dfsg-2) ...
Selecting previously unselected package virtualbox.
Preparing to unpack .../virtualbox_5.1.28-dfsg-2_amd64.deb ...
Unpacking virtualbox (5.1.28-dfsg-2) ...
Selecting previously unselected package virtualbox-qt.
Preparing to unpack .../virtualbox-qt_5.1.28-dfsg-2_amd64.deb ...
Unpacking virtualbox-qt (5.1.28-dfsg-2) ...
Setting up libvncserver1:amd64 (0.9.11+dfsg-1) ...
Processing triggers for mime-support (3.60ubuntu1) ...
Processing triggers for ureadahead (0.100.0-20) ...
Processing triggers for desktop-file-utils (0.23-1ubuntu3) ...
Setting up virtualbox-dkms (5.1.28-dfsg-2) ...
Loading new virtualbox-5.1.28 DKMS files...
Building for 4.13.0-12-generic
Building initial module for 4.13.0-12-generic
Done.

vboxdrv:
Running module version sanity check.
 - Original module
   - No original module exists within this kernel
 - Installation
   - Installing to /lib/modules/4.13.0-12-generic/updates/dkms/

vboxnetadp.ko:
Running module version sanity check.
 - Original module
   - No original module exists within this kernel
 - Installation
   - Installing to /lib/modules/4.13.0-12-generic/updates/dkms/

vboxnetflt.ko:
Running module version sanity check.
 - Original module
   - No original module exists within this kernel
 - Installation
   - Installing to /lib/modules/4.13.0-12-generic/updates/dkms/

vboxpci.ko:
Running module version sanity check.
 - Original module
   - No original module exists within this kernel
 - Installation
   - Installing to /lib/modules/4.13.0-12-generic/updates/dkms/

depmod...

DKMS: install completed.
Job for virtualbox.service failed because a timeout was exceeded.
See "systemctl status virtualbox.service" and "journalctl -xe" for details.
invoke-rc.d: initscript virtualbox, action "restart" failed.
● virtualbox.service - LSB: VirtualBox Linux kernel module
   Loaded: loaded (/etc/init.d/virtualbox; generated; vendor preset: enabled)
   Active: failed (Result: timeout) since Wed 2017-10-11 10:31:33 SAST; 9ms ago
     Docs: man:systemd-sysv-generator(8)
  Process: 21397 ExecStart=/etc/init.d/virtualbox start (code=killed, signal=TERM)
    Tasks: 4 (limit: 4915)
   CGroup: /system.slice/virtualbox.service
           ├─ 1522 plymouth --ping
           ├─17900 plymouth --ping
           └─21400 plymouth --ping

Oct 11 10:26:33 chakra systemd[1]: Starting LSB: VirtualBox Linux kernel module...
Oct 11 10:31:33 chakra systemd[1]: virtualbox.service: Start operation timed out. Terminating.
Oct 11 10:31:33 chakra systemd[1]: Failed to start LSB: VirtualBox Linux kernel module.
Oct 11 10:31:33 chakra systemd[1]: virtualbox.service: Unit entered failed state.
Oct 11 10:31:33 chakra systemd[1]: virtualbox.service: Failed with result 'timeout'.
Processing triggers for libc-bin (2.26-0ubuntu1) ...
/sbin/ldconfig.real: /usr/lib/nvidia-384/libEGL.so.1 is not a symbolic link

/sbin/ldconfig.real: /usr/lib32/nvidia-384/libEGL.so.1 is not a symbolic link

Processing triggers for systemd (234-2ubuntu12) ...
Processing triggers for man-db (2.7.6.1-2) ...
Processing triggers for shared-mime-info (1.8-1) ...
Processing triggers for gnome-menus (3.13.3-6ubuntu5) ...
Processing triggers for hicolor-icon-theme (0.17-1) ...
Setting up libgsoap-2.8.49:amd64 (2.8.49-1) ...
Setting up virtualbox (5.1.28-dfsg-2) ...

Job for virtualbox.service failed because a timeout was exceeded.
See "systemctl status virtualbox.service" and "journalctl -xe" for details.
invoke-rc.d: initscript virtualbox, action "restart" failed.
● virtualbox.service - LSB: VirtualBox Linux kernel module
   Loaded: loaded (/etc/init.d/virtualbox; generated; vendor preset: enabled)
   Active: failed (Result: timeout) since Wed 2017-10-11 10:36:39 SAST; 27ms ago
     Docs: man:systemd-sysv-generator(8)
  Process: 22224 ExecStart=/etc/init.d/virtualbox start (code=killed, signal=TERM)
    Tasks: 5 (limit: 4915)
   CGroup: /system.slice/virtualbox.service
           ├─ 1522 plymouth --ping
           ├─17900 plymouth --ping
           ├─21400 plymouth --ping
           └─22227 plymouth --ping

Oct 11 10:31:39 chakra systemd[1]: Starting LSB: VirtualBox Linux kernel module...
Oct 11 10:36:39 chakra systemd[1]: virtualbox.service: Start operation timed out. Terminating.
Oct 11 10:36:39 chakra systemd[1]: Failed to start LSB: VirtualBox Linux kernel module.
Oct 11 10:36:39 chakra systemd[1]: virtualbox.service: Unit entered failed state.
Oct 11 10:36:39 chakra systemd[1]: virtualbox.service: Failed with result 'timeout'.
Setting up virtualbox-qt (5.1.28-dfsg-2) ...
Processing triggers for shim-signed (1.32+0.9+1474479173.6c180c6-1ubuntu1) ...
Secure Boot not enabled on this system.
Processing triggers for libc-bin (2.26-0ubuntu1) ...
/sbin/ldconfig.real: /usr/lib/nvidia-384/libEGL.so.1 is not a symbolic link

/sbin/ldconfig.real: /usr/lib32/nvidia-384/libEGL.so.1 is not a symbolic link

At this point we now have four plymouth --ping commands running and the problem still exists:

$ VBoxManage --version
WARNING: The character device /dev/vboxdrv does not exist.
  Please install the virtualbox-dkms package and the appropriate
  headers, most likely linux-headers-generic.

  You will not be able to start VMs until this problem is fixed.

Running the plymouth --ping command just keeps running. I'm sure the plymouth --ping command is suppose to run and exit immediately with a simple 0 or 1, but I'm not familiar with the internals of plymouth.

$ ps aux | grep plymouth
root 703 0.0 0.3 138908 28336 ? S 08:24 0:00 @sbin/plymouthd --mode=boot --pid-file=/run/plymouth/pid --attach-to-session
root 1057 0.0 0.0 12936 1020 ? Ss 08:24 0:00 /bin/plymouth --wait
root 1366 0.0 0.0 12936 1124 ? S 08:24 0:00 /bin/plymouth quit
root 1516 0.0 0.0 12936 1044 ? S 08:24 0:00 plymouth --ping
root 1522 0.0 0.0 12936 1016 ? S 08:24 0:00 plymouth --ping
root 1523 0.0 0.0 12936 1024 ? S 08:24 0:00 plymouth --ping
root 2597 0.0 0.0 12936 1016 ? S 08:29 0:00 plymouth --ping
root 17900 0.0 0.0 12936 1004 ? S 10:19 0:00 plymouth --ping
root 21400 0.0 0.0 12936 1124 ? S 10:26 0:00 plymouth --ping
root 22227 0.0 0.0 12936 904 ? S 10:31 0:00 plymouth --ping

ProblemType: Bug
DistroRelease: Ubuntu 17.10
Package: virtualbox 5.1.28-dfsg-2
ProcVersionSignature: Ubuntu 4.13.0-12.13-generic 4.13.3
Uname: Linux 4.13.0-12-generic x86_64
NonfreeKernelModules: nvidia_uvm nvidia_drm nvidia_modeset nvidia
ApportVersion: 2.20.7-0ubuntu2
Architecture: amd64
CurrentDesktop: GNOME
Date: Wed Oct 11 10:36:58 2017
InstallationDate: Installed on 2017-04-02 (191 days ago)
InstallationMedia: Ubuntu-GNOME 16.04.2 LTS "Xenial Xerus" - Release amd64 (20170215)
SourcePackage: virtualbox
UpgradeStatus: Upgraded to artful on 2017-08-10 (61 days ago)

[Workaround]

Kill the plymouth --ping processes while sudo systemctl start virtualbox is running.

Revision history for this message
Bruce Pieterse (octoquad) wrote :
description: updated
description: updated
Revision history for this message
Gianfranco Costamagna (costamagnagianfranco) wrote :

did you try a systemctl virtuabox restart? seems that the dkms went fine, so probably just
a modbprobe vboxdrv is sufficient

Changed in virtualbox (Ubuntu):
status: New → Incomplete
Revision history for this message
Bruce Pieterse (octoquad) wrote :

I didn't manually do it, as the above commands would automatically restart it. modprobe vboxdrv loaded but I wasn't able to fire up the VM's. I just did a soft reboot due to a large chunk of updates landing and everything is working fine now.

I'll continue to monitor for the next week.

Changed in virtualbox (Ubuntu):
status: Incomplete → Invalid
Revision history for this message
Bruce Pieterse (octoquad) wrote :

I have this problem again, however this time I updated a bunch of packages and rebooted straight away and now have the problem after boot.

$ sudo systemctl virtualbox restart

Unknown operation virtualbox.

$ ps aux | grep plymouth
root 735 0.0 0.3 139000 28576 ? S 08:37 0:00 @sbin/plymouthd --mode=boot --pid-file=/run/plymouth/pid --attach-to-session
root 1029 0.0 0.0 12936 1124 ? Ss 08:37 0:00 /bin/plymouth --wait
root 1290 0.0 0.0 12936 1076 ? S 08:37 0:00 /bin/plymouth quit
root 1376 0.0 0.0 12936 1128 ? S 08:37 0:00 plymouth --ping
root 1377 0.0 0.0 12936 1016 ? S 08:37 0:00 plymouth --ping
root 1381 0.0 0.0 12936 1040 ? S 08:37 0:00 plymouth --ping

Plymouth is waiting, quiting, pinging.

$ sudo service virtualbox restart is the only thing that works, but you have to kill the latest plymouth --ping it spawns in order for the service to start.

Starting the virtualbox service on boot can sometimes delay the boot up process due to this bug.

Changed in virtualbox (Ubuntu):
status: Invalid → Incomplete
Revision history for this message
Gianfranco Costamagna (costamagnagianfranco) wrote :

sudo systemctl virtualbox restart
you mean:
sudo systemctl restart virtualbox

I'm reassigning to plymouth, not sure why vbox should be the culprit here

affects: virtualbox (Ubuntu) → plymouth (Ubuntu)
Revision history for this message
Steve Langasek (vorlon) wrote :

So what is the state of the plymouth service when this happens?

What does 'strace plymouth --ping' show about why this process is hanging?

tags: added: plymouth-ping
Revision history for this message
Bruce Pieterse (octoquad) wrote :

Seeing this with other services now:

...
Setting up apport (2.20.7-0ubuntu3.1) ...
Installing new version of config file /etc/apport/crashdb.conf ...
Job for apport.service failed because a timeout was exceeded.
See "systemctl status apport.service" and "journalctl -xe" for details.
invoke-rc.d: initscript apport, action "start" failed.
● apport.service - LSB: automatic crash report generation
   Loaded: loaded (/etc/init.d/apport; generated; vendor preset: enabled)
   Active: failed (Result: timeout) since Fri 2017-10-27 08:32:10 SAST; 10ms ago
     Docs: man:systemd-sysv-generator(8)
  Process: 28855 ExecStart=/etc/init.d/apport start (code=killed, signal=TERM)
    Tasks: 2 (limit: 4915)
   CGroup: /system.slice/apport.service
           └─28858 plymouth --ping

Oct 27 08:27:10 hostname systemd[1]: Starting LSB: automatic crash report generation...
Oct 27 08:32:10 hostname systemd[1]: apport.service: Start operation timed out. Terminating.
Oct 27 08:32:10 hostname systemd[1]: Failed to start LSB: automatic crash report generation.
Oct 27 08:32:10 hostname systemd[1]: apport.service: Unit entered failed state.
Oct 27 08:32:10 hostname systemd[1]: apport.service: Failed with result 'timeout'.
Setting up gir1.2-mutter-1:amd64 (3.26.1-2ubuntu2) ...
Setting up mutter (3.26.1-2ubuntu2) ...
...

strace as requested.

Revision history for this message
Launchpad Janitor (janitor) wrote :

Status changed to 'Confirmed' because the bug affects multiple users.

Changed in virtualbox (Ubuntu):
status: New → Confirmed
Revision history for this message
Marc MAURICE (mmaurice) wrote :

To my understanding plymouth should to stay started once the system is booted no ?

I killed all plymouth processes, then I was able to start the virtualbox service via systemd properly.

Revision history for this message
Bruce Pieterse (octoquad) wrote :

On my home PC, there are no plymouth processes after the boot up process is complete and I have logged in. On my work machine however, where I am having this problem, I have plymouth processes as described in comment #4 after logging in and for the duration of the session (a few days).

The plymouth process as far as I know, is suppose to terminate before or once the login screen is displayed.

Revision history for this message
Matthijs Lambooy (lambooy) wrote :

Have the exact same issue on 4.13.0-25-generic since today

Revision history for this message
Grzegorz Rajda (mellonedain) wrote :

As wrote previous this problem still exists. I use kernel 4.13.0-32. Only kill plymouth processes allow to start VirtualBox.

Revision history for this message
Gianfranco Costamagna (costamagnagianfranco) wrote :

Hello, let me know if the ongoing upload will fix (it will go in bionic)

Revision history for this message
Launchpad Janitor (janitor) wrote :

This bug was fixed in the package virtualbox - 5.2.8-dfsg-3

---------------
virtualbox (5.2.8-dfsg-3) unstable; urgency=medium

  * Do not delay startup because of notify-send not being ready
    (Closes: #880594, LP: #1722745)

 -- Gianfranco Costamagna <email address hidden> Fri, 16 Mar 2018 13:56:41 +0100

Changed in virtualbox (Ubuntu):
status: Confirmed → Fix Released
Revision history for this message
Bruce Pieterse (octoquad) wrote :

Gianfranco,

Sorry for the late response. This seems to be fixed now. I haven't had this problem for a good couple of months :).

Changed in plymouth (Ubuntu):
status: Incomplete → Fix Released
no longer affects: plymouth (Ubuntu)
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.