Ubiquity killed by system: Out of memory

[Reinis Zumbergs]

Binary package hint: ubiquity

TestDrive'ing latest Xubuntu with VirtualBox
All options default, except two checkboxes - download updates while install and install codecs.
On "Where are you" screen stopped any activity and in expandable area was this text:

Traceback (most recent call last):
  File "/usr/lib/ubiquity/ubiquity/frontend/gtk_ui.py", line 1269, in on_next_clicked
    self.dbfilter.ok_handler()
  File "/usr/lib/ubiquity/plugins/ubi-timezone.py", line 594, in ok_handler
    self.preseed('time/zone', zone)
  File "/usr/lib/ubiquity/ubiquity/filteredcommand.py", line 338, in preseed
    self.db.set(name, value)
  File "/usr/lib/python2.7/dist-packages/debconf.py", line 65, in <lambda>
    lambda *args, **kw: self.command(command, *args, **kw))
  File "/usr/lib/python2.7/dist-packages/debconf.py", line 69, in command
    self.write.write("%s %s\n" % (command, ' '.join(map(str, params))))
IOError: [Errno 32] Broken pipe

Apr 26 08:50:55 ubuntu ubiquity[3009]: Traceback (most recent call last):
Apr 26 08:50:55 ubuntu ubiquity[3009]: File "/usr/lib/ubiquity/ubiquity/frontend/gtk_ui.py", line 1269, in on_next_clicked
Apr 26 08:50:55 ubuntu ubiquity[3009]: self.dbfilter.ok_handler()
Apr 26 08:50:55 ubuntu ubiquity[3009]: File "/usr/lib/ubiquity/plugins/ubi-timezone.py", line 594, in ok_handler
Apr 26 08:50:55 ubuntu ubiquity[3009]: self.preseed('time/zone', zone)
Apr 26 08:50:55 ubuntu ubiquity[3009]: File "/usr/lib/ubiquity/ubiquity/filteredcommand.py", line 338, in preseed
Apr 26 08:50:55 ubuntu ubiquity[3009]: self.db.set(name, value)
Apr 26 08:50:55 ubuntu ubiquity[3009]: File "/usr/lib/python2.7/dist-packages/debconf.py", line 65, in <lambda>
Apr 26 08:50:55 ubuntu ubiquity[3009]: lambda *args, **kw: self.command(command, *args, **kw))
Apr 26 08:50:55 ubuntu ubiquity[3009]: File "/usr/lib/python2.7/dist-packages/debconf.py", line 69, in command
Apr 26 08:50:55 ubuntu ubiquity[3009]: self.write.write("%s %s\n" % (command, ' '.join(map(str, params))))
Apr 26 08:50:55 ubuntu ubiquity[3009]: IOError: [Errno 32] Broken pipe
Apr 26 08:50:55 ubuntu ubiquity[3009]:
Traceback (most recent call last):
  File "/usr/lib/ubiquity/ubiquity/frontend/gtk_ui.py", line 1269, in on_next_clicked
    self.dbfilter.ok_handler()
  File "/usr/lib/ubiquity/plugins/ubi-timezone.py", line 594, in ok_handler
    self.preseed('time/zone', zone)
  File "/usr/lib/ubiquity/ubiquity/filteredcommand.py", line 338, in preseed
    self.db.set(name, value)
  File "/usr/lib/python2.7/dist-packages/debconf.py", line 65, in <lambda>
    lambda *args, **kw: self.command(command, *args, **kw))
  File "/usr/lib/python2.7/dist-packages/debconf.py", line 69, in command
    self.write.write("%s %s\n" % (command, ' '.join(map(str, params))))
IOError: [Errno 32] Broken pipe
Apr 26 08:50:59 ubuntu ubiquity[3009]: Exception in GTK frontend (invoking crash handler):
Apr 26 08:50:59 ubuntu ubiquity[3009]: Traceback (most recent call last):
Apr 26 08:50:59 ubuntu ubiquity[3009]: File "/usr/lib/ubiquity/ubiquity/frontend/gtk_ui.py", line 1269, in on_next_clicked
Apr 26 08:50:59 ubuntu ubiquity[3009]: self.dbfilter.ok_handler()
Apr 26 08:50:59 ubuntu ubiquity[3009]: File "/usr/lib/ubiquity/plugins/ubi-timezone.py", line 594, in ok_handler
Apr 26 08:50:59 ubuntu ubiquity[3009]: self.preseed('time/zone', zone)
Apr 26 08:50:59 ubuntu ubiquity[3009]: File "/usr/lib/ubiquity/ubiquity/filteredcommand.py", line 338, in preseed
Apr 26 08:50:59 ubuntu ubiquity[3009]: self.db.set(name, value)
Apr 26 08:50:59 ubuntu ubiquity[3009]: File "/usr/lib/python2.7/dist-packages/debconf.py", line 65, in <lambda>
Apr 26 08:50:59 ubuntu ubiquity[3009]: lambda *args, **kw: self.command(command, *args, **kw))
Apr 26 08:50:59 ubuntu ubiquity[3009]: File "/usr/lib/python2.7/dist-packages/debconf.py", line 69, in command
Apr 26 08:50:59 ubuntu ubiquity[3009]: self.write.write("%s %s\n" % (command, ' '.join(map(str, params))))
Apr 26 08:50:59 ubuntu ubiquity[3009]: IOError: [Errno 32] Broken pipe
Apr 26 08:50:59 ubuntu ubiquity[3009]:
Exception in GTK frontend (invoking crash handler):
Traceback (most recent call last):
  File "/usr/lib/ubiquity/ubiquity/frontend/gtk_ui.py", line 1269, in on_next_clicked
    self.dbfilter.ok_handler()
  File "/usr/lib/ubiquity/plugins/ubi-timezone.py", line 594, in ok_handler
    self.preseed('time/zone', zone)
  File "/usr/lib/ubiquity/ubiquity/filteredcommand.py", line 338, in preseed
    self.db.set(name, value)
  File "/usr/lib/python2.7/dist-packages/debconf.py", line 65, in <lambda>
    lambda *args, **kw: self.command(command, *args, **kw))
  File "/usr/lib/python2.7/dist-packages/debconf.py", line 69, in command
    self.write.write("%s %s\n" % (command, ' '.join(map(str, params))))
IOError: [Errno 32] Broken pipe

Traceback (most recent call last):
  File "/usr/lib/ubiquity/ubiquity/frontend/gtk_ui.py", line 1269, in on_next_clicked
    self.dbfilter.ok_handler()
  File "/usr/lib/ubiquity/plugins/ubi-timezone.py", line 594, in ok_handler
    self.preseed('time/zone', zone)
  File "/usr/lib/ubiquity/ubiquity/filteredcommand.py", line 338, in preseed
    self.db.set(name, value)
  File "/usr/lib/python2.7/dist-packages/debconf.py", line 65, in <lambda>
    lambda *args, **kw: self.command(command, *args, **kw))
  File "/usr/lib/python2.7/dist-packages/debconf.py", line 69, in command
    self.write.write("%s %s\n" % (command, ' '.join(map(str, params))))
IOError: [Errno 32] Broken pipe
Apr 26 09:00:18 ubuntu ubiquity[3009]: debconffilter_done: ubiquity.components.install (current: ubi-timezone)

ProblemType: Crash
DistroRelease: Ubuntu 11.04
Package: ubiquity 2.6.10
ProcVersionSignature: Ubuntu 2.6.38-8.42-generic 2.6.38.2
Uname: Linux 2.6.38-8-generic x86_64
Architecture: amd64
Date: Tue Apr 26 08:50:59 2011
ExecutablePath: /usr/lib/ubiquity/bin/ubiquity
InterpreterPath: /usr/bin/python2.7
LiveMediaBuild: Xubuntu 11.04 "Natty Narwhal" - Release amd64 (20110426)
ProcCmdline: /usr/bin/python /usr/lib/ubiquity/bin/ubiquity gtk_ui
ProcEnviron: Error: [Errno 13] Permission denied: '/proc/3009/environ'
PythonArgs: ['/usr/lib/ubiquity/bin/ubiquity', 'gtk_ui']
SourcePackage: ubiquity
Title: ubiquity crashed with IOError in command(): [Errno 32] Broken pipe
UpgradeStatus: No upgrade log present (probably fresh install)
UserGroups:

[Jean-Baptiste Lallement (jibel)]

Thanks for your report.

The error is caused by the system running out of memory
Apr 26 08:48:25 ubuntu kernel: [ 882.641885] Out of memory: Kill process 3009 (ubiquity) score 148 or sacrifice child
Apr 26 08:48:25 ubuntu kernel: [ 882.641895] Killed process 5614 (debconf-communi) total-vm:74456kB, anon-rss:23704kB, file-rss:2348kB
Apr 26 08:48:35 ubuntu kernel: [ 892.610144] Adding 522236k swap on /dev/sda5. Priority:-1 extents:1 across:522236k

Similar to bug 769359 and not the same place.

[Evan (ev)]

I've done a few tests with 384 MB of RAM in KVM, but have been unable to reproduce an OOM condition. I've also committed to ubiquity trunk a patch that tells the OOM killer to not consider the main ubiquity process (not the ubiquity display manager, so the panel, metacity, ... can still be killed).

[Reinis Zumbergs (reinis-zumbergs)]

Ok, this time i tried with KVM - still crashes. And RAM, both with VirtualBox and KVM, was set to 512 MB. This time it didn't get to "Where are you" screen and the following text was in expandable area:

rocess 2830 (n/a).
Apr 26 16:15:26 ubuntu rtkit-daemon[2833]: Demoted 3 threads.
Apr 26 16:15:51 ubuntu rtkit-daemon[2833]: The canary thread is apparently starving. Taking action.
Apr 26 16:15:51 ubuntu rtkit-daemon[2833]: Demoting known real-time threads.
Apr 26 16:15:51 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2921 of process 2830 (n/a).
Apr 26 16:15:51 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2919 of process 2830 (n/a).
Apr 26 16:15:51 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2830 of process 2830 (n/a).
Apr 26 16:15:51 ubuntu rtkit-daemon[2833]: Demoted 3 threads.
Apr 26 16:16:02 ubuntu rtkit-daemon[2833]: The canary thread is apparently starving. Taking action.
Apr 26 16:16:02 ubuntu rtkit-daemon[2833]: Demoting known real-time threads.
Apr 26 16:16:02 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2921 of process 2830 (n/a).
Apr 26 16:16:02 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2919 of process 2830 (n/a).
Apr 26 16:16:02 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2830 of process 2830 (n/a).
Apr 26 16:16:02 ubuntu rtkit-daemon[2833]: Demoted 3 threads.
Apr 26 16:16:13 ubuntu rtkit-daemon[2833]: The canary thread is apparently starving. Taking action.
Apr 26 16:16:13 ubuntu rtkit-daemon[2833]: Demoting known real-time threads.
Apr 26 16:16:13 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2921 of process 2830 (n/a).
Apr 26 16:16:13 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2919 of process 2830 (n/a).
Apr 26 16:16:13 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2830 of process 2830 (n/a).
Apr 26 16:16:13 ubuntu rtkit-daemon[2833]: Demoted 3 threads.
Apr 26 16:17:09 ubuntu CRON[6726]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Apr 26 16:17:56 ubuntu rtkit-daemon[2833]: The canary thread is apparently starving. Taking action.
Apr 26 16:17:56 ubuntu rtkit-daemon[2833]: Demoting known real-time threads.
Apr 26 16:17:56 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2921 of process 2830 (n/a).
Apr 26 16:17:56 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2919 of process 2830 (n/a).
Apr 26 16:17:56 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2830 of process 2830 (n/a).
Apr 26 16:17:56 ubuntu rtkit-daemon[2833]: Demoted 3 threads.
Apr 26 16:18:36 ubuntu rtkit-daemon[2833]: The canary thread is apparently starving. Taking action.
Apr 26 16:18:36 ubuntu rtkit-daemon[2833]: Demoting known real-time threads.
Apr 26 16:18:36 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2921 of process 2830 (n/a).
Apr 26 16:18:36 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2919 of process 2830 (n/a).
Apr 26 16:18:36 ubuntu rtkit-daemon[2833]: Successfully demoted thread 2830 of process 2830 (n/a).
Apr 26 16:18:36 ubuntu rtkit-daemon[2833]: Demoted 3 threads.
Apr 26 16:18:55 ubuntu rtkit-daemon[2833]: The canary thread is apparently starving. Taking action.
Apr 26 16:18:55 ubuntu rtkit-daemon[2833...