Build failed in Jenkins: precise-alternate-i386_default #357

Sat Mar 10 08:04:33 UTC 2012

See <http://10.189.74.2:8080/job/precise-alternate-i386_default/357/>

------------------------------------------
[...truncated 5 lines...]
INFO:root:Checking/Downloading current build for variant alternate from http://cdimage.ubuntu.com/daily
INFO:root:Remote file not available. Retrying in 15 minutes
INFO:root:Downloading ISO...

-------------------- 0.0%
#------------------- 6.4% 90.1 kBps 0:14 ETA  
###########--------- 56.2% 702.5 kBps 0:00 ETA  
#################### 100.0% 317.0 kBps DONE    

reading seed file ubuntu/precise-alternate-i386.iso: *********************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************
Read ubuntu/precise-alternate-i386.iso. Target 86.1% complete.      
downloading from http://cdimage.ubuntu.com//daily/current/precise-alternate-i386.iso:

#################--- 86.1%
#################--- 86.1% 40.3 kBps 45:18 ETA  
#################--- 86.2% 418.5 kBps 3:59 ETA  
#################--- 86.4% 1677.0 kBps 0:58 ETA  
#################--- 87.1% 4753.5 kBps 0:19 ETA  
#################--- 89.2% 15822.9 kBps 0:04 ETA  
##################-- 92.1% 20483.8 kBps 0:02 ETA  
###################- 95.1% 21562.9 kBps 0:01 ETA  
###################- 97.9% 20289.8 kBps 0:00 ETA  
#################### 100.0% 10005.7 kBps DONE    

verifying download...checksum matches OK
used 621395968 local, fetched 100371274
Syncing precise ubuntu alternate i386
Verifying ubuntu/precise-alternate-i386.iso
Verification succeeded
[precise-alternate-i386_default] $ /bin/bash /tmp/hudson325815170491186809.sh
DEBUG:root:Options: {'isoroot': '/var/lib/ubuntu-iso-testing/isos', 'isoname': False, 'test_dir': None, 'variant': 'alternate', 'release': 'precise', 'debug': False, 'flavor': 'ubuntu', 'arch': 'i386'}
DEBUG:root:Arguments: ['default']
INFO:root:Preseed: default
INFO:root:Extracting kernel and initrd from /var/lib/ubuntu-iso-testing/isos/ubuntu/precise-alternate-i386.iso
DEBUG:root:Cmd: ['unbsdtar-safelink', '-xf', '/var/lib/ubuntu-iso-testing/isos/ubuntu/precise-alternate-i386.iso', '-C', '/tmp/precise-alternate-i386.iso-9Ki63S', 'install/vmlinuz', 'install/initrd.gz']
INFO:root:Generating default configuration
DEBUG:root:Loading test configuration file: '/usr/share/ubuntu-iso-testing/templates.alternate/test_cases/default/config'
DEBUG:root:Couch Document UUID: 'af11e9d0-d369-4932-9ce0-6f05f93ae57f'
DEBUG:root:Generate preseed file
DEBUG:root:will preseed /tmp/precise-alternate-i386.iso-9Ki63S/default-YDrRE3/preseed
INFO:root:Uncompressing initrd
DEBUG:root:Initrd file: /tmp/precise-alternate-i386.iso-9Ki63S/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/precise-alternate-i386.iso', '-O', '.disk/info']
DEBUG:root:Media Info: Ubuntu 12.04 LTS "Precise Pangolin" - Alpha i386 (20120310)
DEBUG:root:Copying preseed to tftp root
INFO:root:Copying /usr/share/ubuntu-iso-testing/templates.alternate/run_test -> /tmp/precise-alternate-i386.iso-9Ki63S/default-YDrRE3/initrd-2KVPPM
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:7d:76:c0', 'uuid': 'af11e9d0-d369-4932-9ce0-6f05f93ae57f', 'kernel_boot_args': ''}
DEBUG:root:Attaching pxelinux config
DEBUG:root:Adding test script to test case document
DEBUG:root:Test script: /usr/share/ubuntu-iso-testing/templates.alternate/test_cases/default/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 /usr/share/ubuntu-iso-testing/templates.alternate/test_cases/default
DEBUG:root:Generating disk information
DEBUG:root:Setting state to created
DEBUG:root:Loading test case to run
INFO:root:Configuring test case: af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Retrieving iso file
DEBUG:root:Generating disks
DEBUG:root:Cmd: ['qemu-img', 'create', '-f', 'qcow2', '/var/lib/ubuntu-iso-testing/tests/af11e9d0-d369-4932-9ce0-6f05f93ae57f/disk1.qcow2', '6G']
Formatting '/var/lib/ubuntu-iso-testing/tests/af11e9d0-d369-4932-9ce0-6f05f93ae57f/disk1.qcow2', fmt=qcow2 size=6442450944 encryption=off cluster_size=0 
DEBUG:root:Generating libvirt configuration
DEBUG:root:Context: {'iso_file': '/var/lib/ubuntu-iso-testing/isos/ubuntu/precise-alternate-i386.iso', 'mac': '52:54:00:7d:76:c0', 'disks': [{'source': '/var/lib/ubuntu-iso-testing/tests/af11e9d0-d369-4932-9ce0-6f05f93ae57f/disk1.qcow2', 'bus': 'virtio', 'size': '6G', 'target': 'vda', 'format': 'qcow2'}], 'name': 'af11e9d0-d369-4932-9ce0-6f05f93ae57f', 'uuid': 'af11e9d0-d369-4932-9ce0-6f05f93ae57f'}
DEBUG:root:Cmd: ['virsh', 'define', '/var/lib/ubuntu-iso-testing/tests/af11e9d0-d369-4932-9ce0-6f05f93ae57f/libvirt.xml']
Domain af11e9d0-d369-4932-9ce0-6f05f93ae57f defined from /var/lib/ubuntu-iso-testing/tests/af11e9d0-d369-4932-9ce0-6f05f93ae57f/libvirt.xml

DEBUG:root:Starting test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'start', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
Domain af11e9d0-d369-4932-9ce0-6f05f93ae57f started

DEBUG:root:Cmd: ['sudo', 'chown', 'usit', '/tmp/af11e9d0-d369-4932-9ce0-6f05f93ae57f.syslog.log']
DEBUG:root:Checking VM state for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'domstate', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
DEBUG:root:running
DEBUG:root:test start: Mar 10 2012 07:59:26, Mar 10 07:59:44 alderamin dnsmasq-dhcp[2154]: DHCPREQUEST(virbr999) 192.168.123.254 52:54:00:7d:76:c0 
DEBUG:root:Got IP address of 192.168.123.254 for af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Capturing current d-i syslog for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Mar 10 07:59:58 debconf: <-- 0
Mar 10 07:59:58 debconf: --> PROGRESS INFO cdrom-detect/scanning_progress_step
Mar 10 07:59:58 debconf: <-- 0 OK
Mar 10 07:59:58 debconf: --> PROGRESS STEP 1
Mar 10 07:59:58 debconf: <-- 0 OK
Mar 10 07:59:58 debconf: --> SUBST cdrom-detect/scanning_progress_step DIR /cdrom/pool/main/p
Mar 10 07:59:58 debconf: Adding [DIR] -> [/cdrom/pool/main/p]
Mar 10 07:59:58 debconf: <-- 0
Mar 10 07:59:58 debconf: --> PROGRESS INFO cdrom-detect/scanning_progress_step
Mar 10 07:59:58 debconf: <-- 0 OK
DEBUG:root:Checking VM state for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'domstate', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Mar 10 08:00:28 netcfg[10726]: DEBUG: rdisc6 line: No response.
Mar 10 08:00:28 netcfg[10726]: DEBUG: rdisc6 parsing finished
Mar 10 08:00:28 debconf: --> PROGRESS STEP 1
Mar 10 08:00:28 debconf: <-- 0 OK
Mar 10 08:00:28 netcfg[10726]: DEBUG: rdisc6 line: Soliciting ff02::2 (ff02::2) on eth0...
Mar 10 08:00:28 netcfg[10726]: DEBUG: rdisc6 line: Timed out.
Mar 10 08:00:28 netcfg[10726]: DEBUG: rdisc6 line: No response.
Mar 10 08:00:28 netcfg[10726]: DEBUG: rdisc6 parsing finished
Mar 10 08:00:28 debconf: --> PROGRESS STEP 1
Mar 10 08:00:28 debconf: <-- 0 OK
DEBUG:root:Checking VM state for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'domstate', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Mar 10 08:01:00 debconf: Adding [SECTION] -> [Retrieving packages]
Mar 10 08:01:00 debconf: <-- 0
Mar 10 08:01:00 debconf: --> PROGRESS INFO base-installer/debootstrap/info/retrieving
Mar 10 08:01:00 debconf: <-- 0 OK
Mar 10 08:01:00 debconf: --> METAGET base-installer/debootstrap/info/validating description
Mar 10 08:01:00 debconf: <-- 0 Validating udev...
Mar 10 08:01:00 debconf: --> SUBST base-installer/debootstrap/info/validating SUBST0 upstart
Mar 10 08:01:00 debconf: Adding [SUBST0] -> [upstart]
Mar 10 08:01:00 debconf: <-- 0
Mar 10 08:01:00 debconf: --> SUBST base-installer/debootstrap/info/validating SECTION Retrieving packages
DEBUG:root:Checking VM state for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'domstate', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Mar 10 08:01:30 debconf: --> METAGET base-installer/debootstrap/info/configuring description
Mar 10 08:01:30 debconf: <-- 0 Configuring mount...
Mar 10 08:01:30 debconf: --> SUBST base-installer/debootstrap/info/configuring SUBST0 initscripts
Mar 10 08:01:30 debconf: Adding [SUBST0] -> [initscripts]
Mar 10 08:01:30 debconf: <-- 0
Mar 10 08:01:30 debconf: --> SUBST base-installer/debootstrap/info/configuring SECTION Configuring required packages
Mar 10 08:01:30 debconf: Adding [SECTION] -> [Configuring required packages]
Mar 10 08:01:30 debconf: <-- 0
Mar 10 08:01:30 debconf: --> PROGRESS INFO base-installer/debootstrap/info/configuring
Mar 10 08:01:30 debconf: <-- 0 OK
DEBUG:root:Checking VM state for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'domstate', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Mar 10 08:01:55 debconf: <-- 0 multiselect backup progresscancel align plugin-detect-keyboard plugin-terminal
Mar 10 08:01:55 in-target: debconf (developer): <---- 0 multiselect backup progresscancel align plugin-detect-keyboard plugin-terminal
Mar 10 08:01:55 in-target: debconf (developer): --> 0 multiselect escape multiselect backup progresscancel align plugin-detect-keyboard plugin-terminal
Mar 10 08:01:55 in-target: debconf (developer): <---- 0 multiselect escape multiselect backup progresscancel align plugin-detect-keyboard plugin-terminal
Mar 10 08:01:55 in-target: debconf (developer): ----> CAPB
Mar 10 08:01:55 in-target: debconf (developer): <-- CAPB
Mar 10 08:01:55 in-target: debconf (developer): --> 0 multiselect escape multiselect backup progresscancel align plugin-detect-keyboard plugin-terminal
Mar 10 08:01:55 in-target: debconf (developer): <---- 0 multiselect escape multiselect backup progresscancel align plugin-detect-keyboard plugin-terminal
Mar 10 08:01:55 in-target: debconf (developer): --> 0 multiselect escape multiselect escape multiselect backup progresscancel align plugin-detect-keyboard plugin-terminal
Mar 10 08:01:55 in-target: Done.
DEBUG:root:Checking VM state for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'domstate', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Mar 10 08:02:21 in-target: debconf (developer): <---- 0 OK
Mar 10 08:02:21 in-target: debconf (developer): ----> DATA debconf-apt-progress/info description Preparing linux-headers-3.2.0-18
Mar 10 08:02:21 debconf: --> DATA debconf-apt-progress/info description Preparing linux-headers-3.2.0-18
Mar 10 08:02:21 debconf: <-- 0 OK
Mar 10 08:02:21 in-target: debconf (developer): <---- 0 OK
Mar 10 08:02:21 in-target: debconf (developer): ----> PROGRESS INFO debconf-apt-progress/info
Mar 10 08:02:21 debconf: --> PROGRESS INFO debconf-apt-progress/info
Mar 10 08:02:21 debconf: <-- 0 OK
Mar 10 08:02:21 in-target: debconf (developer): <---- 0 OK
Mar 10 08:02:21 in-target: debconf (developer): --> 0 OK
DEBUG:root:Checking VM state for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'domstate', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Mar 10 08:02:58 in-target: debconf (developer): <---- 0 OK
Mar 10 08:02:58 in-target: debconf (developer): ----> DATA debconf-apt-progress/info description Retrieving file 57 of 57
Mar 10 08:02:58 debconf: --> DATA debconf-apt-progress/info description Retrieving file 57 of 57
Mar 10 08:02:58 debconf: <-- 0 OK
Mar 10 08:02:58 in-target: debconf (developer): <---- 0 OK
Mar 10 08:02:58 in-target: debconf (developer): ----> PROGRESS INFO debconf-apt-progress/info
Mar 10 08:02:58 debconf: --> PROGRESS INFO debconf-apt-progress/info
Mar 10 08:02:58 debconf: <-- 0 OK
Mar 10 08:02:58 in-target: debconf (developer): <---- 0 OK
Mar 10 08:02:58 in-target: debconf (developer): --> 0 OK
DEBUG:root:Checking VM state for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'domstate', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Mar 10 08:03:26 in-target: debconf (developer): <---- 0 OK
Mar 10 08:03:26 in-target: debconf (developer): --> 0 OK
Mar 10 08:03:26 in-target: Generating locales...
Mar 10 08:03:26 in-target:   en_AG.UTF-8... 
Mar 10 08:03:27 in-target: done
Mar 10 08:03:27 in-target:   en_AU.UTF-8... 
Mar 10 08:03:28 in-target: done
Mar 10 08:03:28 in-target:   en_BW.UTF-8... 
Mar 10 08:03:29 in-target: done
Mar 10 08:03:29 in-target:   en_CA.UTF-8...
DEBUG:root:Checking VM state for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'domstate', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Mar 10 08:03:59 in-target: debconf (developer): <-- PROGRESS SET 177
Mar 10 08:03:59 in-target: debconf (developer): ----> PROGRESS SET 177
Mar 10 08:03:59 debconf: --> PROGRESS SET 177
Mar 10 08:03:59 debconf: <-- 0 OK
Mar 10 08:03:59 in-target: debconf (developer): <---- 0 OK
Mar 10 08:03:59 in-target: debconf (developer): --> 0 OK
Mar 10 08:03:59 in-target: debconf (developer): <-- SUBST debconf-apt-progress/info DESCRIPTION Configuring xfonts-encodings
Mar 10 08:03:59 in-target: debconf (developer): --> 0
Mar 10 08:03:59 in-target: debconf (developer): <-- PROGRESS INFO debconf-apt-progress/info
Mar 10 08:03:59 in-target: debconf (developer): ----> DATA debconf-apt-progress/info type text
DEBUG:root:Checking VM state for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'domstate', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Mar 10 08:04:02 debconf: --> CAPB
Mar 10 08:04:02 debconf: <-- 0 multiselect backup progresscancel align plugin-detect-keyboard plugin-terminal
Mar 10 08:04:02 debconf: --> METAGET debian-installer/pkgsel/title Description
Mar 10 08:04:02 debconf: <-- 0 Select and install software
Mar 10 08:04:02 debconf: --> SUBST debian-installer/main-menu/item-failure ITEM Select and install software
Mar 10 08:04:02 debconf: Adding [ITEM] -> [Select and install software]
Mar 10 08:04:02 debconf: <-- 0
Mar 10 08:04:02 debconf: --> INPUT critical debian-installer/main-menu/item-failure
Mar 10 08:04:02 debconf: <-- 0 question will be asked
Mar 10 08:04:02 debconf: --> GO
INFO:root:Test af11e9d0-d369-4932-9ce0-6f05f93ae57f: debian-installer reports error
DEBUG:root:Terminating test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'destroy', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
Domain af11e9d0-d369-4932-9ce0-6f05f93ae57f destroyed

DEBUG:root:test[iso_url]=/var/lib/ubuntu-iso-testing/isos/ubuntu/precise-alternate-i386.iso
DEBUG:root:test[_rev]=13-0e61184fc7e3e78211c75e7d300cce04
DEBUG:root:test[disks]=[{'bus': 'virtio', 'size': '6G', 'target': 'vda', 'format': 'qcow2'}]
DEBUG:root:test[_attachments]={'preseed.cfg': {'stub': True, 'length': 13280, 'revpos': 2, 'content_type': ''}, 'pxe/kernel': {'stub': True, 'length': 5011808, 'revpos': 6, 'content_type': ''}, 'pxe/pxelinux.cfg/52:54:00:7d:76:c0': {'stub': True, 'length': 1094, 'revpos': 7, 'content_type': ''}, 'tests/default': {'stub': True, 'length': 1216, 'revpos': 8, 'content_type': ''}, 'libvirt.xml': {'stub': True, 'length': 2184, 'revpos': 11, 'content_type': 'application/xml'}, 'pxe/initrd': {'stub': True, 'length': 6308854, 'revpos': 5, 'content_type': ''}, 'media_info': {'stub': True, 'length': 59, 'revpos': 3, 'content_type': ''}, 'run_test': {'stub': True, 'length': 5944, 'revpos': 4, 'content_type': ''}}
DEBUG:root:test[states]={'started': '2012-03-10T07:59:30.664463', 'configured': '2012-03-10T07:59:27.406495', 'creating': '2012-03-10T07:59:20.880747', 'created': '2012-03-10T07:59:26.017072'}
DEBUG:root:test[mac]=52:54:00:7d:76:c0
DEBUG:root:test[_id]=af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:test[test_case]=default
DEBUG:root:VM for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f powered off but tests not completed
DEBUG:root:Undefine VM for  test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
DEBUG:root:Cmd: ['virsh', 'undefine', 'af11e9d0-d369-4932-9ce0-6f05f93ae57f']
Domain af11e9d0-d369-4932-9ce0-6f05f93ae57f has been undefined

DEBUG:root:Collating output for test case af11e9d0-d369-4932-9ce0-6f05f93ae57f
Archiving artifacts
Recording test results
No test report files were found. Configuration error?
Description set: 20120310