Build failed in Jenkins: precise-desktop-amd64_oem #341

Jenkins Notification devnull at canonical.com
Mon Sep 24 07:23:42 UTC 2012 

See <http://10.189.74.2:8080/job/precise-desktop-amd64_oem/341/>

------------------------------------------
[...truncated 136 lines...]
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Get updates()
Sep 23 09:31:14 ubuntu AptDaemon.Worker: INFO: Finished transaction /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:30:33 ubuntu install.py: keeping language packs for: en_US.UTF-8
Sep 23 09:31:12 ubuntu dbus[2472]: [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Sep 23 09:31:13 ubuntu AptDaemon: INFO: Initializing daemon
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Initializing PackageKit compat layer
Sep 23 09:31:13 ubuntu dbus[2472]: [system] Successfully activated service 'org.freedesktop.PackageKit'
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Initializing PackageKit transaction
Sep 23 09:31:13 ubuntu AptDaemon.Worker: INFO: Simulating trans: /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
Sep 23 09:31:13 ubuntu AptDaemon.Worker: INFO: Processing transaction /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Get updates()
Sep 23 09:31:14 ubuntu AptDaemon.Worker: INFO: Finished transaction /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:30:33 ubuntu install.py: keeping language packs for: en_US.UTF-8
Sep 23 09:31:12 ubuntu dbus[2472]: [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Sep 23 09:31:13 ubuntu AptDaemon: INFO: Initializing daemon
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Initializing PackageKit compat layer
Sep 23 09:31:13 ubuntu dbus[2472]: [system] Successfully activated service 'org.freedesktop.PackageKit'
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Initializing PackageKit transaction
Sep 23 09:31:13 ubuntu AptDaemon.Worker: INFO: Simulating trans: /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
Sep 23 09:31:13 ubuntu AptDaemon.Worker: INFO: Processing transaction /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Get updates()
Sep 23 09:31:14 ubuntu AptDaemon.Worker: INFO: Finished transaction /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:30:33 ubuntu install.py: keeping language packs for: en_US.UTF-8
Sep 23 09:31:12 ubuntu dbus[2472]: [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Sep 23 09:31:13 ubuntu AptDaemon: INFO: Initializing daemon
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Initializing PackageKit compat layer
Sep 23 09:31:13 ubuntu dbus[2472]: [system] Successfully activated service 'org.freedesktop.PackageKit'
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Initializing PackageKit transaction
Sep 23 09:31:13 ubuntu AptDaemon.Worker: INFO: Simulating trans: /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
Sep 23 09:31:13 ubuntu AptDaemon.Worker: INFO: Processing transaction /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Get updates()
Sep 23 09:31:14 ubuntu AptDaemon.Worker: INFO: Finished transaction /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:30:33 ubuntu install.py: keeping language packs for: en_US.UTF-8
Sep 23 09:31:12 ubuntu dbus[2472]: [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Sep 23 09:31:13 ubuntu AptDaemon: INFO: Initializing daemon
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Initializing PackageKit compat layer
Sep 23 09:31:13 ubuntu dbus[2472]: [system] Successfully activated service 'org.freedesktop.PackageKit'
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Initializing PackageKit transaction
Sep 23 09:31:13 ubuntu AptDaemon.Worker: INFO: Simulating trans: /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
Sep 23 09:31:13 ubuntu AptDaemon.Worker: INFO: Processing transaction /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Get updates()
Sep 23 09:31:14 ubuntu AptDaemon.Worker: INFO: Finished transaction /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:30:33 ubuntu install.py: keeping language packs for: en_US.UTF-8
Sep 23 09:31:12 ubuntu dbus[2472]: [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Sep 23 09:31:13 ubuntu AptDaemon: INFO: Initializing daemon
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Initializing PackageKit compat layer
Sep 23 09:31:13 ubuntu dbus[2472]: [system] Successfully activated service 'org.freedesktop.PackageKit'
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Initializing PackageKit transaction
Sep 23 09:31:13 ubuntu AptDaemon.Worker: INFO: Simulating trans: /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
Sep 23 09:31:13 ubuntu AptDaemon.Worker: INFO: Processing transaction /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
Sep 23 09:31:13 ubuntu AptDaemon.PackageKit: INFO: Get updates()
Sep 23 09:31:14 ubuntu AptDaemon.Worker: INFO: Finished transaction /org/debian/apt/transaction/8d5f8b45ee554f2481daad6e522067a1
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:34:56 ubuntu plugininstall.py: log-output -t ubiquity chroot /target /usr/share/python/runtime.d/ibus-pinyin.rtupdate rtupdate python2.7 python2.7
Sep 23 09:34:57 ubuntu plugininstall.py: log-output -t ubiquity chroot /target /usr/share/python/runtime.d/ubiquity-frontend-gtk.rtupdate rtupdate python2.7 python2.7
Sep 23 09:34:58 ubuntu plugininstall.py: log-output -t ubiquity chroot /target /usr/share/python/runtime.d/hplip-data.rtupdate rtupdate python2.7 python2.7
Sep 23 09:34:58 ubuntu plugininstall.py: log-output -t ubiquity chroot /target /usr/share/python/runtime.d/apport.rtupdate rtupdate python2.7 python2.7
Sep 23 09:34:59 ubuntu plugininstall.py: log-output -t ubiquity chroot /target /usr/share/python/runtime.d/gedit.rtupdate rtupdate python2.7 python2.7
Sep 23 09:34:59 ubuntu plugininstall.py: log-output -t ubiquity chroot /target /usr/share/python/runtime.d/rhythmbox-plugin-zeitgeist.rtupdate rtupdate python2.7 python2.7
Sep 23 09:34:59 ubuntu plugininstall.py: log-output -t ubiquity chroot /target /usr/share/python/runtime.d/software-center.rtupdate rtupdate python2.7 python2.7
Sep 23 09:35:00 ubuntu plugininstall.py: log-output -t ubiquity chroot /target /usr/share/python/runtime.d/rhythmbox-plugins.rtupdate rtupdate python2.7 python2.7
Sep 23 09:35:00 ubuntu plugininstall.py: log-output -t ubiquity chroot /target /usr/share/python/runtime.d/totem.rtupdate rtupdate python2.7 python2.7
Sep 23 09:35:00 ubuntu plugininstall.py: log-output -t ubiquity chroot /target /usr/share/python/runtime.d/rhythmbox.rtupdate rtupdate python2.7 python2.7
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:35:10 ubuntu in-target: Get:59 http://us.archive.ubuntu.com precise-updates/main Translation-en [192 kB]
Sep 23 09:35:10 ubuntu in-target: Get:60 http://us.archive.ubuntu.com precise-updates/multiverse Translation-en [5,414 B]
Sep 23 09:35:10 ubuntu in-target: Get:61 http://us.archive.ubuntu.com precise-updates/restricted Translation-en [1,484 B]
Sep 23 09:35:10 ubuntu in-target: Get:62 http://us.archive.ubuntu.com precise-updates/universe Translation-en [79.3 kB]
Sep 23 09:35:10 ubuntu in-target: Get:63 http://us.archive.ubuntu.com precise-backports/main Translation-en [1,244 B]
Sep 23 09:35:10 ubuntu in-target: Get:64 http://us.archive.ubuntu.com precise-backports/multiverse Translation-en [1,476 B]
Sep 23 09:35:10 ubuntu in-target: Get:65 http://us.archive.ubuntu.com precise-backports/restricted Translation-en [14 B]
Sep 23 09:35:10 ubuntu in-target: Get:66 http://us.archive.ubuntu.com precise-backports/universe Translation-en [9,472 B]
Sep 23 09:35:24 ubuntu in-target: Fetched 24.5 MB in 16s (1,460 kB/s)
Sep 23 09:35:24 ubuntu in-target: Reading package lists...
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:35:59 ubuntu ubiquity: #01597% [9 thunderbird-locale-en-us 0 B/15.6 kB 0%]                                 
Sep 23 09:35:59 ubuntu ubiquity: #01597% [Working]                                                                   
Sep 23 09:35:59 ubuntu ubiquity: #01597% [Waiting for headers]                                                       
Sep 23 09:35:59 ubuntu ubiquity: #015Get:10 http://us.archive.ubuntu.com/ubuntu/ precise/main wbritish all 7.1-1 [269 kB]
Sep 23 09:35:59 ubuntu ubiquity: #01598% [10 wbritish 131 kB/269 kB 48%]                                             
Sep 23 09:35:59 ubuntu ubiquity: #015100% [Working]                                                                  
Sep 23 09:35:59 ubuntu plugininstall.py: Verifying downloads ...
Sep 23 09:35:59 ubuntu plugininstall.py: Downloads verified successfully
Sep 23 09:36:00 ubuntu ubiquity: Selecting previously unselected package hyphen-en-us.
Sep 23 09:36:00 ubuntu ubiquity: (Reading database ...
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:36:25 ubuntu ubiquity: Setting up myspell-en-au (2.1-5.3ubuntu1) ...
Sep 23 09:36:25 ubuntu ubiquity: Setting up myspell-en-gb (1:3.3.0-2ubuntu3) ...
Sep 23 09:36:25 ubuntu ubiquity: Setting up myspell-en-za (1:3.3.0-2ubuntu3) ...
Sep 23 09:36:25 ubuntu ubiquity: Setting up mythes-en-us (1:3.3.0-2ubuntu3) ...
Sep 23 09:36:25 ubuntu ubiquity: Setting up openoffice.org-hyphenation (0.6) ...
Sep 23 09:36:26 ubuntu ubiquity: Setting up poppler-data (0.4.5-2) ...
Sep 23 09:36:26 ubuntu ubiquity: Setting up thunderbird-locale-en (1:15.0+build1-0ubuntu0.12.04.1) ...
Sep 23 09:36:26 ubuntu ubiquity: Setting up thunderbird-locale-en-us (1:15.0+build1-0ubuntu0.12.04.1) ...
Sep 23 09:36:26 ubuntu ubiquity: Setting up wbritish (7.1-1) ...
Sep 23 09:36:28 ubuntu ubiquity: Processing triggers for dictionaries-common ...
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:36:43 ubuntu ubiquity: and dpkg --contents (= dpkg-deb --contents) to list their contents.
Sep 23 09:36:43 ubuntu ubiquity: /usr/sbin/dpkg-reconfigure: usplash is not installed
Sep 23 09:36:44 ubuntu ubiquity: Package `splashy' is not installed and no info is available.
Sep 23 09:36:44 ubuntu ubiquity: Use dpkg --info (= dpkg-deb --info) to examine archive files,
Sep 23 09:36:44 ubuntu ubiquity: and dpkg --contents (= dpkg-deb --contents) to list their contents.
Sep 23 09:36:44 ubuntu ubiquity: /usr/sbin/dpkg-reconfigure: splashy is not installed
Sep 23 09:36:47 ubuntu ubiquity: 
Sep 23 09:36:47 ubuntu ubiquity: Creating config file /etc/papersize with new version
Sep 23 09:36:50 ubuntu plugininstall.py: log-output -t ubiquity chroot /target dpkg-divert --package ubiquity --rename --quiet --remove /usr/sbin/update-initramfs
Sep 23 09:36:50 ubuntu ubiquity: update-initramfs: Generating /boot/initrd.img-3.2.0-31-generic
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:37:21 ubuntu grub-installer: info: Installing grub on '/dev/vda'
Sep 23 09:37:21 ubuntu grub-installer: info: grub-install supports --no-floppy
Sep 23 09:37:21 ubuntu grub-installer: info: Running chroot /target grub-install  --no-floppy --force "/dev/vda"
Sep 23 09:37:25 ubuntu grub-installer: Installation finished. No error reported.
Sep 23 09:37:25 ubuntu grub-installer: info: grub-install ran successfully
Sep 23 09:37:26 ubuntu ubiquity: Setting partition 1 of /dev/vda to active... done.
Sep 23 09:37:30 ubuntu os-prober: debug: running /usr/lib/os-probes/50mounted-tests on /dev/vda2
Sep 23 09:37:30 ubuntu 50mounted-tests: debug: /dev/vda2 type not recognised; skipping
Sep 23 09:37:30 ubuntu os-prober: debug: os detected by /usr/lib/os-probes/50mounted-tests
Sep 23 09:37:30 ubuntu os-prober: debug: /dev/vda5: is active swap
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:37:53 ubuntu ubiquity: Removing libgtkmm-2.4-1c2a ...
Sep 23 09:37:53 ubuntu ubiquity: Purging configuration files for libgtkmm-2.4-1c2a ...
Sep 23 09:37:54 ubuntu ubiquity: Removing xfsprogs ...
Sep 23 09:37:54 ubuntu ubiquity: Purging configuration files for xfsprogs ...
Sep 23 09:37:55 ubuntu ubiquity: Removing libreadline5 ...
Sep 23 09:37:55 ubuntu ubiquity: Purging configuration files for libreadline5 ...
Sep 23 09:37:56 ubuntu ubiquity: Removing localechooser-data ...
Sep 23 09:37:57 ubuntu ubiquity: Removing ubiquity-slideshow-ubuntu ...
Sep 23 09:37:57 ubuntu ubiquity: Removing user-setup ...
Sep 23 09:37:57 ubuntu ubiquity: Purging configuration files for user-setup ...
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 09:38:28 ubuntu plugininstall.py: log-output -t ubiquity cp -a /var/log/partman /target/var/log/installer/partman
Sep 23 09:38:28 ubuntu plugininstall.py: log-output -t ubiquity cp -a /var/log/installer/version /target/var/log/installer/version
Sep 23 09:38:28 ubuntu plugininstall.py: log-output -t ubiquity cp -a /var/log/casper.log /target/var/log/installer/casper.log
Sep 23 09:38:28 ubuntu plugininstall.py: log-output -t ubiquity cp -a /var/log/installer/debug /target/var/log/installer/debug
Sep 23 09:38:28 ubuntu plugininstall.py: log-output -t ubiquity umount /target/cdrom
Sep 23 09:38:28 ubuntu finish-install: Disabling CD in sources.list
Sep 23 09:38:28 ubuntu ubiquity[4143]: log-output -t ubiquity sh -c cp /usr/local/bin/run_test /target/usr/local/bin/ ; cp /usr/local/bin/run_test.wrapper /target/usr/local/bin/ ; cp /etc/rsyslog.d/50-default.conf /target/etc/rsyslog.d/50-default.conf ; chroot /target sh -c "sed -i 's/^%admin.*/%admin ALL=(ALL) NOPASSWD: ALL/' /etc/sudoers; chmod 0755 /usr/local/bin/run_test; echo '/usr/local/bin/run_test.wrapper --syslog --sleep 30 http://192.168.123.1:5984/tests/7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 &' > /etc/rc.local"
Sep 23 09:38:28 ubuntu ubiquity[4143]: debconffilter_done: ubiquity.components.plugininstall (current: None)
Sep 23 09:38:28 ubuntu ubiquity[4143]: Reverting lockdown of the desktop environment.
Sep 23 09:38:30 ubuntu ubiquity[4143]: log-output -t ubiquity debconf-copydb configdb targetdb -p ^oem-config/ --config=Name:targetdb --config=Driver:File --config=Mode:0644 --config=Filename:/target/var/cache/debconf/config.dat
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 05:38:58 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 rtkit-daemon[1474]: Successfully limited resources.
Sep 23 05:38:58 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 rtkit-daemon[1474]: Running.
Sep 23 05:38:58 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 rtkit-daemon[1474]: Successfully made thread 1472 of process 1472 (n/a) owned by '1000' high priority at nice level -11.
Sep 23 05:38:58 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 rtkit-daemon[1474]: Supervising 1 threads of 1 processes of 1 users.
Sep 23 05:38:58 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 rtkit-daemon[1474]: Watchdog thread running.
Sep 23 05:38:58 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 rtkit-daemon[1474]: Canary thread running.
Sep 23 05:39:00 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 dbus[735]: [system] Activating service name='org.freedesktop.UDisks' (using servicehelper)
Sep 23 05:39:00 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 dbus[735]: [system] Successfully activated service 'org.freedesktop.UDisks'
Sep 23 05:39:00 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 kernel: [   14.797265] ISO 9660 Extensions: Microsoft Joliet Level 3
Sep 23 05:39:00 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 kernel: [   14.807321] ISO 9660 Extensions: RRIP_1991A
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 05:39:00 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 kernel: [   14.807321] ISO 9660 Extensions: RRIP_1991A
Sep 23 05:39:01 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 ntpdate[1191]: adjust time server 91.189.94.4 offset 0.173814 sec
Sep 23 05:39:02 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 kernel: [   16.496034] eth0: no IPv6 routers present
Sep 23 05:39:11 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 NetworkManager[867]: <info> (eth0): IP6 addrconf timed out or failed.
Sep 23 05:39:11 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 NetworkManager[867]: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Sep 23 05:39:11 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 NetworkManager[867]: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Sep 23 05:39:11 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 NetworkManager[867]: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Sep 23 05:39:14 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 kernel: [   28.813056] audit_printk_skb: 27 callbacks suppressed
Sep 23 05:39:14 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 kernel: [   28.813060] type=1400 audit(1348393154.369:24): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/telepathy/mission-control-5" name="/usr/share/gvfs/remote-volume-monitors/" pid=1794 comm="mission-control" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Sep 23 05:39:14 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 goa[1798]: goa-daemon version 3.4.0 starting [main.c:112, main()]
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Sep 23 05:39:00 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 kernel: [   14.807321] ISO 9660 Extensions: RRIP_1991A
Sep 23 05:39:01 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 ntpdate[1191]: adjust time server 91.189.94.4 offset 0.173814 sec
Sep 23 05:39:02 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 kernel: [   16.496034] eth0: no IPv6 routers present
Sep 23 05:39:11 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 NetworkManager[867]: <info> (eth0): IP6 addrconf timed out or failed.
Sep 23 05:39:11 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 NetworkManager[867]: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Sep 23 05:39:11 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 NetworkManager[867]: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Sep 23 05:39:11 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 NetworkManager[867]: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Sep 23 05:39:14 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 kernel: [   28.813056] audit_printk_skb: 27 callbacks suppressed
Sep 23 05:39:14 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 kernel: [   28.813060] type=1400 audit(1348393154.369:24): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/telepathy/mission-control-5" name="/usr/share/gvfs/remote-volume-monitors/" pid=1794 comm="mission-control" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Sep 23 05:39:14 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 goa[1798]: goa-daemon version 3.4.0 starting [main.c:112, main()]
DEBUG:root:Checking VM state for test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'domstate', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
error: Cannot recv data: Connection reset by peer
error: failed to connect to the hypervisor
DEBUG:root:
DEBUG:root:Test 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8 failed to execute within 40 minutes
DEBUG:root:Terminating test case 7f3151b0-d945-4dc9-b1b7-7f9e25559fa8
DEBUG:root:Cmd: ['virsh', 'destroy', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']
error: failed to get domain '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8'
error: Domain not found: no domain with matching name '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8'

Traceback (most recent call last):
  File "/usr/bin/run-test", line 689, in <module>
    end_VM(test['_id'])
  File "/usr/bin/run-test", line 638, in end_VM
    subprocess.check_call(cmd)
  File "/usr/lib/python2.7/subprocess.py", line 511, in check_call
    raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['virsh', 'destroy', '7f3151b0-d945-4dc9-b1b7-7f9e25559fa8']' returned non-zero exit status 1
Archiving artifacts
Recording test results
No test report files were found. Configuration error?
Build step 'Publish JUnit test result report' changed build result to FAILURE

More information about the ubuntu-testing-notifications mailing list