Build failed in Jenkins: precise-server-amd64_lvm #721

Thu Jan 16 15:47:48 UTC 2014

See <http://d-jenkins.ubuntu-ci:8080/job/precise-server-amd64_lvm/721/>

------------------------------------------
Started by upstream project "precise-server-amd64_default" build number 759
[EnvInject] - Loading node environment variables.
Building remotely on wazn in workspace <http://d-jenkins.ubuntu-ci:8080/job/precise-server-amd64_lvm/ws/>
[precise-server-amd64_lvm] $ /bin/bash /tmp/hudson5944079732777103225.sh
INFO:root:Checking/Downloading current build for variant server from http://cdimage.ubuntu.com/ubuntu-server/precise/daily
INFO:root:Required ISO version 'precise-server-amd64.iso' already in cache
[precise-server-amd64_lvm] $ /bin/bash /tmp/hudson741426042976856766.sh
You have not informed bzr of your Launchpad ID, and you must do this to
write to Launchpad or access private data.  See "bzr help launchpad-login".
Branched 270 revisions.
DEBUG:root:Options: {'isoroot': '/var/lib/ubuntu-iso-testing/isos', 'isoname': False, 'test_dir': '<http://d-jenkins.ubuntu-ci:8080/job/precise-server-amd64_lvm/721/artifact/tests',> 'variant': 'server', 'release': 'precise', 'debug': False, 'flavor': 'ubuntu-server', 'arch': 'amd64'}
DEBUG:root:Arguments: ['lvm']
INFO:root:Sleeping for 19 seconds (LP: #961217)
INFO:root:Preseed: lvm
INFO:root:Extracting kernel and initrd from /var/lib/ubuntu-iso-testing/isos/ubuntu-server/precise-server-amd64.iso
DEBUG:root:Cmd: ['unbsdtar-safelink', '-xf', '/var/lib/ubuntu-iso-testing/isos/ubuntu-server/precise-server-amd64.iso', '-C', '/tmp/precise-server-amd64.iso-XEpDKU', 'install/vmlinuz', 'install/initrd.gz']
INFO:root:Generating lvm configuration
DEBUG:root:Loading test configuration file: '<http://d-jenkins.ubuntu-ci:8080/job/precise-server-amd64_lvm/721/artifact/tests/test_cases/lvm/config'>
DEBUG:root:Couch Document UUID: '00b5d283-fffd-4433-b370-09e849117705'
DEBUG:root:Generate preseed file
DEBUG:root:will preseed /tmp/precise-server-amd64.iso-XEpDKU/lvm-Pm7HKz/preseed
INFO:root:Uncompressing initrd
DEBUG:root:Initrd file: /tmp/precise-server-amd64.iso-XEpDKU/install/initrd.gz
DEBUG:root:Copying preseed to initrd and test case document
DEBUG:root:Extracting media info from ISO
DEBUG:root:Cmd: ['bsdtar', '-xf', '/var/lib/ubuntu-iso-testing/isos/ubuntu-server/precise-server-amd64.iso', '-O', '.disk/info']
DEBUG:root:Media Info: Ubuntu-Server 12.04.4 LTS "Precise Pangolin" - Release amd64 (20140116)
DEBUG:root:Copying preseed to tftp root
INFO:root:Copying <http://d-jenkins.ubuntu-ci:8080/job/precise-server-amd64_lvm/721/artifact/tests/run_test> -> /tmp/precise-server-amd64.iso-XEpDKU/lvm-Pm7HKz/initrd-9Y86Kq
INFO:root:Copying <http://d-jenkins.ubuntu-ci:8080/job/precise-server-amd64_lvm/721/artifact/tests/boottests.py> -> /tmp/precise-server-amd64.iso-XEpDKU/lvm-Pm7HKz/initrd-9Y86Kq
DEBUG:root:Updating inittab
INFO:root:Compressing initrd
DEBUG:root:Attaching initrd
DEBUG:root:Copying initrd to tftp root
DEBUG:root:Attaching kernel
DEBUG:root:Copying kernel to tftp root
DEBUG:root:Copying pxelinux config to tftp root
DEBUG:root:Loading configuration option: 'pxelinux/kernel_boot_args'
INFO:root:Section doesn't exists: 'pxelinux'
DEBUG:root:Context: {'mac': '52:54:00:e3:57:eb', 'uuid': '00b5d283-fffd-4433-b370-09e849117705', 'kernel_boot_args': ''}
DEBUG:root:Attaching pxelinux config
DEBUG:root:Adding test script to test case document
DEBUG:root:Test script: <http://d-jenkins.ubuntu-ci:8080/job/precise-server-amd64_lvm/721/artifact/tests/test_cases/lvm/test>
DEBUG:root:Loading configuration option: 'vm/disk_size'
INFO:root:Section doesn't exists: 'vm'
DEBUG:root:Loading configuration option: 'vm/disk_number'
INFO:root:Section doesn't exists: 'vm'
DEBUG:root:Generating libvirt information for <http://d-jenkins.ubuntu-ci:8080/job/precise-server-amd64_lvm/721/artifact/tests/test_cases/lvm>
DEBUG:root:Generating disk information
DEBUG:root:Setting state to created
DEBUG:root:Loading test case to run
INFO:root:Configuring test case: 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Retrieving iso file
DEBUG:root:Generating disks
DEBUG:root:Cmd: ['qemu-img', 'create', '-f', 'qcow2', '/var/lib/ubuntu-iso-testing/tests/00b5d283-fffd-4433-b370-09e849117705/disk1.qcow2', '2G']
Formatting '/var/lib/ubuntu-iso-testing/tests/00b5d283-fffd-4433-b370-09e849117705/disk1.qcow2', fmt=qcow2 size=2147483648 encryption=off cluster_size=65536 lazy_refcounts=off 
DEBUG:root:Generating libvirt configuration
DEBUG:root:Context: {'iso_file': '/var/lib/ubuntu-iso-testing/isos/ubuntu-server/precise-server-amd64.iso', 'mac': '52:54:00:e3:57:eb', 'disks': [{'source': '/var/lib/ubuntu-iso-testing/tests/00b5d283-fffd-4433-b370-09e849117705/disk1.qcow2', 'bus': 'virtio', 'size': '2G', 'target': 'vda', 'format': 'qcow2'}], 'name': '00b5d283-fffd-4433-b370-09e849117705', 'uuid': '00b5d283-fffd-4433-b370-09e849117705'}
DEBUG:root:Cmd: ['virsh', 'define', '/var/lib/ubuntu-iso-testing/tests/00b5d283-fffd-4433-b370-09e849117705/libvirt.xml']
Domain 00b5d283-fffd-4433-b370-09e849117705 defined from /var/lib/ubuntu-iso-testing/tests/00b5d283-fffd-4433-b370-09e849117705/libvirt.xml

DEBUG:root:Starting test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Cmd: ['virsh', 'start', '00b5d283-fffd-4433-b370-09e849117705']
Domain 00b5d283-fffd-4433-b370-09e849117705 started

DEBUG:root:Cmd: ['sudo', 'chown', 'usit', '/tmp/00b5d283-fffd-4433-b370-09e849117705.syslog.log']
DEBUG:root:Checking VM state for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Cmd: ['virsh', 'domstate', '00b5d283-fffd-4433-b370-09e849117705']
DEBUG:root:running
DEBUG:root:test start: Jan 16 2014 15:43:45, Jan 16 15:43:54 wazn dnsmasq-dhcp[4406]: DHCPREQUEST(virbr999) 192.168.123.6 52:54:00:e3:57:eb 
DEBUG:root:Got IP address of 192.168.123.6 for 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Capturing current d-i syslog for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Jan 16 15:44:15 debconf: --> X_LOADTEMPLATEFILE /var/lib/dpkg/info/partman-efi.templates partman-efi
Jan 16 15:44:15 debconf: <-- 0 OK
Jan 16 15:44:15 debconf: --> X_SAVE
Jan 16 15:44:16 debconf: <-- 0 OK
Jan 16 15:44:16 debconf: --> PROGRESS STEP 1
Jan 16 15:44:16 debconf: <-- 0 OK
Jan 16 15:44:16 anna[7374]: DEBUG: retrieving partman-ext3 67ubuntu1.1
Jan 16 15:44:16 debconf: --> SUBST anna/progress_step_retr PACKAGE partman-ext3
Jan 16 15:44:16 debconf: Adding [PACKAGE] -> [partman-ext3]
Jan 16 15:44:16 debconf: <-- 0
DEBUG:root:Checking VM state for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Cmd: ['virsh', 'domstate', '00b5d283-fffd-4433-b370-09e849117705']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Jan 16 15:44:45 debconf: <-- 0
Jan 16 15:44:45 debconf: --> PROGRESS INFO anna/progress_step_conf
Jan 16 15:44:45 debconf: <-- 0 OK
Jan 16 15:44:45 debconf: --> PROGRESS STEP 1
Jan 16 15:44:45 debconf: <-- 0 OK
Jan 16 15:44:45 debconf: --> PROGRESS STOP
Jan 16 15:44:45 debconf: <-- 0 OK
Jan 16 15:44:45 disk-detect: No Serial ATA RAID disks detected
Jan 16 15:44:45 debconf: --> GET disk-detect/multipath/enable
Jan 16 15:44:45 debconf: <-- 0 false
DEBUG:root:Checking VM state for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Cmd: ['virsh', 'domstate', '00b5d283-fffd-4433-b370-09e849117705']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Jan 16 15:45:15 debconf: --> METAGET base-installer/debootstrap/info/configuring description
Jan 16 15:45:15 debconf: <-- 0 Configuring libselinux1...
Jan 16 15:45:15 debconf: --> SUBST base-installer/debootstrap/info/configuring SUBST0 makedev
Jan 16 15:45:15 debconf: Adding [SUBST0] -> [makedev]
Jan 16 15:45:15 debconf: <-- 0
Jan 16 15:45:15 debconf: --> SUBST base-installer/debootstrap/info/configuring SECTION Configuring required packages
Jan 16 15:45:15 debconf: Adding [SECTION] -> [Configuring required packages]
Jan 16 15:45:15 debconf: <-- 0
Jan 16 15:45:15 debconf: --> PROGRESS INFO base-installer/debootstrap/info/configuring
Jan 16 15:45:15 debconf: <-- 0 OK
DEBUG:root:Checking VM state for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Cmd: ['virsh', 'domstate', '00b5d283-fffd-4433-b370-09e849117705']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Jan 16 15:45:38 debconf: <-- 0 multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 16 15:45:38 in-target: debconf (developer): <---- 0 multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 16 15:45:38 in-target: debconf (developer): --> 0 multiselect escape multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 16 15:45:38 in-target: debconf (developer): <---- 0 multiselect escape multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 16 15:45:38 in-target: debconf (developer): ----> CAPB
Jan 16 15:45:38 in-target: debconf (developer): <-- CAPB
Jan 16 15:45:38 in-target: debconf (developer): --> 0 multiselect escape multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 16 15:45:38 in-target: debconf (developer): <---- 0 multiselect escape multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 16 15:45:38 in-target: debconf (developer): --> 0 multiselect escape multiselect escape multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 16 15:45:38 in-target: Done.
DEBUG:root:Checking VM state for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Cmd: ['virsh', 'domstate', '00b5d283-fffd-4433-b370-09e849117705']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Jan 16 15:46:13 in-target: debconf (developer): ----> PROGRESS SET 300
Jan 16 15:46:13 debconf: --> PROGRESS SET 300
Jan 16 15:46:13 debconf: <-- 0 OK
Jan 16 15:46:13 in-target: debconf (developer): <---- 0 OK
Jan 16 15:46:13 in-target: debconf (developer): --> 0 OK
Jan 16 15:46:13 in-target: debconf (developer): <-- SUBST debconf-apt-progress/info DESCRIPTION Retrieving file 21 of 24
Jan 16 15:46:13 in-target: debconf (developer): --> 0
Jan 16 15:46:13 in-target: debconf (developer): <-- PROGRESS INFO debconf-apt-progress/info
Jan 16 15:46:13 in-target: debconf (developer): ----> DATA debconf-apt-progress/info type text
Jan 16 15:46:13 debconf: --> DATA debconf-apt-progress/info type text
DEBUG:root:Checking VM state for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Cmd: ['virsh', 'domstate', '00b5d283-fffd-4433-b370-09e849117705']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Jan 16 15:46:43 in-target: debconf (developer): <-- PROGRESS SET 150
Jan 16 15:46:43 in-target: debconf (developer): ----> PROGRESS SET 150
Jan 16 15:46:43 debconf: --> PROGRESS SET 150
Jan 16 15:46:43 debconf: <-- 0 OK
Jan 16 15:46:43 in-target: debconf (developer): <---- 0 OK
Jan 16 15:46:43 in-target: debconf (developer): --> 0 OK
Jan 16 15:46:43 in-target: debconf (developer): <-- SUBST debconf-apt-progress/info DESCRIPTION Retrieving file 75 of 169
Jan 16 15:46:43 in-target: debconf (developer): --> 0
Jan 16 15:46:43 in-target: debconf (developer): <-- PROGRESS INFO debconf-apt-progress/info
Jan 16 15:46:43 in-target: debconf (developer): ----> DATA debc
DEBUG:root:Checking VM state for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Cmd: ['virsh', 'domstate', '00b5d283-fffd-4433-b370-09e849117705']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Jan 16 15:47:04 in-target: debconf (developer): <---- 0 OK
Jan 16 15:47:04 in-target: debconf (developer): ----> DATA debconf-apt-progress/info description Preparing python-twisted-bin
Jan 16 15:47:04 debconf: --> DATA debconf-apt-progress/info description Preparing python-twisted-bin
Jan 16 15:47:04 debconf: <-- 0 OK
Jan 16 15:47:04 in-target: debconf (developer): <---- 0 OK
Jan 16 15:47:04 in-target: debconf (developer): ----> PROGRESS INFO debconf-apt-progress/info
Jan 16 15:47:04 debconf: --> PROGRESS INFO debconf-apt-progress/info
Jan 16 15:47:04 debconf: <-- 0 OK
Jan 16 15:47:04 in-target: debconf (developer): <---- 0 OK
Jan
DEBUG:root:Checking VM state for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Cmd: ['virsh', 'domstate', '00b5d283-fffd-4433-b370-09e849117705']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Jan 16 15:47:44 debconf: --> CAPB
Jan 16 15:47:44 debconf: <-- 0 multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 16 15:47:44 debconf: --> METAGET debian-installer/pkgsel/title Description
Jan 16 15:47:44 debconf: <-- 0 Select and install software
Jan 16 15:47:44 debconf: --> SUBST debian-installer/main-menu/item-failure ITEM Select and install software
Jan 16 15:47:44 debconf: Adding [ITEM] -> [Select and install software]
Jan 16 15:47:44 debconf: <-- 0
Jan 16 15:47:44 debconf: --> INPUT critical debian-installer/main-menu/item-failure
Jan 16 15:47:44 debconf: <-- 0 question will be asked
Jan 16 15:47:44 debconf: --> GO
INFO:root:Test 00b5d283-fffd-4433-b370-09e849117705: debian-installer reports error
DEBUG:root:Terminating test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Cmd: ['virsh', 'destroy', '00b5d283-fffd-4433-b370-09e849117705']
Domain 00b5d283-fffd-4433-b370-09e849117705 destroyed

DEBUG:root:test[iso_url]=/var/lib/ubuntu-iso-testing/isos/ubuntu-server/precise-server-amd64.iso
DEBUG:root:test[_rev]=13-3ad59dc688c9c6f0c998546a744af1fd
DEBUG:root:test[disks]=[{'bus': 'virtio', 'size': '2G', 'target': 'vda', 'format': 'qcow2'}]
DEBUG:root:test[_attachments]={'pxe/kernel': {'stub': True, 'length': 5908472, 'digest': 'md5-dsQygz56UZam95FeYMtUpQ==', 'revpos': 6, 'content_type': u''}, 'libvirt.xml': {'stub': True, 'length': 2032, 'digest': 'md5-5ETp7I9rMKm/C+Ho8yNJGA==', 'revpos': 11, 'content_type': 'application/xml'}, 'preseed': {'stub': True, 'length': 13162, 'digest': 'md5-nY6tl3x55TmECDtuw5AeMA==', 'revpos': 2, 'content_type': u''}, 'tests/lvm': {'stub': True, 'length': 2136, 'digest': 'md5-YQdbo+7GDBLy35OUF9DtQA==', 'revpos': 8, 'content_type': u''}, 'pxe/initrd': {'stub': True, 'length': 8017543, 'digest': 'md5-V9ow+DG2J+d/aUsowKJSlQ==', 'revpos': 5, 'content_type': u''}, 'pxe/pxelinux.cfg/52:54:00:e3:57:eb': {'stub': True, 'length': 1108, 'digest': 'md5-R4FUEi2rzlrQEEjvYmEgdQ==', 'revpos': 7, 'content_type': u''}, 'media_info': {'stub': True, 'length': 71, 'digest': 'md5-t23oYMIToe6jjXewPNRFIw==', 'revpos': 3, 'content_type': u''}, 'run_test': {'stub': True, 'length': 6943, 'digest': 'md5-ZbbgRpEcHSeMP9A+cvAv1Q==', 'revpos': 4, 'content_type': u''}}
DEBUG:root:test[states]={'started': '2014-01-16T15:43:46.717613', 'configured': '2014-01-16T15:43:45.409624', 'creating': '2014-01-16T15:43:36.022276', 'created': '2014-01-16T15:43:45.089734'}
DEBUG:root:test[mac]=52:54:00:e3:57:eb
DEBUG:root:test[_id]=00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:test[test_case]=lvm
ERROR:root:VM for test case 00b5d283-fffd-4433-b370-09e849117705 powered off but tests not completed
WARNING:root:Could not move syslog ([Errno 13] Permission denied: '/tmp/00b5d283-fffd-4433-b370-09e849117705.syslog.log')
DEBUG:root:Undefine VM for  test case 00b5d283-fffd-4433-b370-09e849117705
DEBUG:root:Cmd: ['virsh', 'undefine', '00b5d283-fffd-4433-b370-09e849117705']
Domain 00b5d283-fffd-4433-b370-09e849117705 has been undefined

DEBUG:root:Collating output for test case 00b5d283-fffd-4433-b370-09e849117705
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
Description set: 20140116