Build failed in Jenkins: precise-server-amd64_dns-server #741

[Jenkins Notification]

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

------------------------------------------
Started by upstream project "precise-server-amd64_default" build number 785
[EnvInject] - Loading node environment variables.
Building remotely on wazn in workspace <http://d-jenkins.ubuntu-ci:8080/job/precise-server-amd64_dns-server/ws/>
[precise-server-amd64_dns-server] $ /bin/bash /tmp/hudson7489543770033284357.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_dns-server] $ /bin/bash /tmp/hudson7404678815293521768.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_dns-server/741/artifact/tests',> 'variant': 'server', 'release': 'precise', 'debug': False, 'flavor': 'ubuntu-server', 'arch': 'amd64'}
DEBUG:root:Arguments: ['dns-server']
INFO:root:Sleeping for 23 seconds (LP: #961217)
INFO:root:Preseed: dns-server
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-2ZsQ1Q', 'install/vmlinuz', 'install/initrd.gz']
INFO:root:Generating dns-server configuration
DEBUG:root:Loading test configuration file: '<http://d-jenkins.ubuntu-ci:8080/job/precise-server-amd64_dns-server/741/artifact/tests/test_cases/dns-server/config'>
DEBUG:root:Couch Document UUID: 'caf827bb-6e7e-4158-ad21-da13a40f39ad'
DEBUG:root:Generate preseed file
DEBUG:root:will preseed /tmp/precise-server-amd64.iso-2ZsQ1Q/dns-server-5JMWNz/preseed
INFO:root:Uncompressing initrd
DEBUG:root:Initrd file: /tmp/precise-server-amd64.iso-2ZsQ1Q/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 (20140122)
DEBUG:root:Copying preseed to tftp root
INFO:root:Copying <http://d-jenkins.ubuntu-ci:8080/job/precise-server-amd64_dns-server/741/artifact/tests/run_test> -> /tmp/precise-server-amd64.iso-2ZsQ1Q/dns-server-5JMWNz/initrd-uz52fQ
INFO:root:Copying <http://d-jenkins.ubuntu-ci:8080/job/precise-server-amd64_dns-server/741/artifact/tests/boottests.py> -> /tmp/precise-server-amd64.iso-2ZsQ1Q/dns-server-5JMWNz/initrd-uz52fQ
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:6d:b8:29', 'uuid': 'caf827bb-6e7e-4158-ad21-da13a40f39ad', '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_dns-server/741/artifact/tests/test_cases/dns-server/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_dns-server/741/artifact/tests/test_cases/dns-server>
DEBUG:root:Generating disk information
DEBUG:root:Setting state to created
DEBUG:root:Loading test case to run
INFO:root:Configuring test case: caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Retrieving iso file
DEBUG:root:Generating disks
DEBUG:root:Cmd: ['qemu-img', 'create', '-f', 'qcow2', '/var/lib/ubuntu-iso-testing/tests/caf827bb-6e7e-4158-ad21-da13a40f39ad/disk1.qcow2', '2G']
Formatting '/var/lib/ubuntu-iso-testing/tests/caf827bb-6e7e-4158-ad21-da13a40f39ad/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:6d:b8:29', 'disks': [{'source': '/var/lib/ubuntu-iso-testing/tests/caf827bb-6e7e-4158-ad21-da13a40f39ad/disk1.qcow2', 'bus': 'virtio', 'size': '2G', 'target': 'vda', 'format': 'qcow2'}], 'name': 'caf827bb-6e7e-4158-ad21-da13a40f39ad', 'uuid': 'caf827bb-6e7e-4158-ad21-da13a40f39ad'}
DEBUG:root:Cmd: ['virsh', 'define', '/var/lib/ubuntu-iso-testing/tests/caf827bb-6e7e-4158-ad21-da13a40f39ad/libvirt.xml']
Domain caf827bb-6e7e-4158-ad21-da13a40f39ad defined from /var/lib/ubuntu-iso-testing/tests/caf827bb-6e7e-4158-ad21-da13a40f39ad/libvirt.xml

DEBUG:root:Starting test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Cmd: ['virsh', 'start', 'caf827bb-6e7e-4158-ad21-da13a40f39ad']
Domain caf827bb-6e7e-4158-ad21-da13a40f39ad started

DEBUG:root:Cmd: ['sudo', 'chown', 'usit', '/tmp/caf827bb-6e7e-4158-ad21-da13a40f39ad.syslog.log']
DEBUG:root:Checking VM state for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Cmd: ['virsh', 'domstate', 'caf827bb-6e7e-4158-ad21-da13a40f39ad']
DEBUG:root:running
DEBUG:root:test start: Jan 22 2014 17:15:54, Jan 22 17:16:03 wazn dnsmasq-dhcp[4669]: DHCPREQUEST(virbr999) 192.168.123.142 52:54:00:6d:b8:29 
DEBUG:root:Got IP address of 192.168.123.142 for caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Capturing current d-i syslog for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Jan 22 17:16:25 debconf: <-- 0 OK
Jan 22 17:16:25 anna[7378]: DEBUG: retrieving partman-ext3 67ubuntu1.1
Jan 22 17:16:25 debconf: --> SUBST anna/progress_step_retr PACKAGE partman-ext3
Jan 22 17:16:25 debconf: Adding [PACKAGE] -> [partman-ext3]
Jan 22 17:16:25 debconf: <-- 0
Jan 22 17:16:25 debconf: --> PROGRESS INFO anna/progress_step_retr
Jan 22 17:16:25 debconf: <-- 0 OK
Jan 22 17:16:25 debconf: --> X_LOADTEMPLATEFILE /var/lib/dpkg/info/partman-ext3.templates partman-ext3
Jan 22 17:16:25 debconf: <-- 0 OK
Jan 22 17:16:25 debconf: --> X_SAVE
DEBUG:root:Checking VM state for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Cmd: ['virsh', 'domstate', 'caf827bb-6e7e-4158-ad21-da13a40f39ad']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Jan 22 17:16:55 debconf: <-- 0 OK
Jan 22 17:16:55 disk-detect: No Serial ATA RAID disks detected
Jan 22 17:16:55 debconf: --> GET disk-detect/multipath/enable
Jan 22 17:16:55 debconf: <-- 0 false
Jan 22 17:16:56 check-missing-firmware: /dev/.udev/firmware-missing does not exist, skipping
Jan 22 17:16:56 check-missing-firmware: /run/udev/firmware-missing does not exist, skipping
Jan 22 17:16:56 check-missing-firmware: no missing firmware in /dev/.udev/firmware-missing /run/udev/firmware-missing
Jan 22 17:16:56 debconf: --> GET debconf/priority
Jan 22 17:16:56 debconf: <-- 0 critical
Jan 22 17:16:56 debconf: --> X_SAVE
DEBUG:root:Checking VM state for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Cmd: ['virsh', 'domstate', 'caf827bb-6e7e-4158-ad21-da13a40f39ad']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Jan 22 17:17:26 debconf: --> SUBST base-installer/debootstrap/info/configuring SECTION Configuring required packages
Jan 22 17:17:26 debconf: Adding [SECTION] -> [Configuring required packages]
Jan 22 17:17:26 debconf: <-- 0
Jan 22 17:17:26 debconf: --> PROGRESS INFO base-installer/debootstrap/info/configuring
Jan 22 17:17:26 debconf: <-- 0 OK
Jan 22 17:17:26 debootstrap: update-alternatives: 
Jan 22 17:17:26 debootstrap: using /usr/bin/w.procps to provide /usr/bin/w (w) in auto mode.
Jan 22 17:17:26 debootstrap: 
Jan 22 17:17:26 debootstrap: dpkg: warning: version '
Jan 22 17:17:26 debootstrap: initctl' has bad syntax: version number does not start with digit
DEBUG:root:Checking VM state for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Cmd: ['virsh', 'domstate', 'caf827bb-6e7e-4158-ad21-da13a40f39ad']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Jan 22 17:17:43 debconf: <-- 0 multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 22 17:17:43 in-target: debconf (developer): <---- 0 multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 22 17:17:43 in-target: debconf (developer): --> 0 multiselect escape multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 22 17:17:43 in-target: debconf (developer): <---- 0 multiselect escape multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 22 17:17:43 in-target: debconf (developer): ----> CAPB
Jan 22 17:17:43 in-target: debconf (developer): <-- CAPB
Jan 22 17:17:43 in-target: debconf (developer): --> 0 multiselect escape multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 22 17:17:43 in-target: debconf (developer): <---- 0 multiselect escape multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 22 17:17:43 in-target: debconf (developer): --> 0 multiselect escape multiselect escape multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 22 17:17:43 in-target: Done.
DEBUG:root:Checking VM state for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Cmd: ['virsh', 'domstate', 'caf827bb-6e7e-4158-ad21-da13a40f39ad']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Jan 22 17:18:26 debconf: <-- 0 OK
Jan 22 17:18:26 in-target: debconf (developer): <---- 0 OK
Jan 22 17:18:26 in-target: debconf (developer): ----> PROGRESS INFO debconf-apt-progress/info
Jan 22 17:18:26 debconf: --> PROGRESS INFO debconf-apt-progress/info
Jan 22 17:18:26 debconf: <-- 0 OK
Jan 22 17:18:26 in-target: debconf (developer): <---- 0 OK
Jan 22 17:18:26 in-target: debconf (developer): --> 0 OK
Jan 22 17:18:26 in-target: debconf (developer): <-- PROGRESS SET 800
Jan 22 17:18:26 in-target: debconf (developer): ----> PROGRESS SET 800
Jan 22 17:18:26 debconf: --> PROGRESS SET 800
DEBUG:root:Checking VM state for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Cmd: ['virsh', 'domstate', 'caf827bb-6e7e-4158-ad21-da13a40f39ad']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Jan 22 17:18:49 debconf: --> DATA debconf-apt-progress/info description Retrieving file 138 of 173
Jan 22 17:18:49 debconf: <-- 0 OK
Jan 22 17:18:49 in-target: debconf (developer): <---- 0 OK
Jan 22 17:18:49 in-target: debconf (developer): ----> PROGRESS INFO debconf-apt-progress/info
Jan 22 17:18:49 debconf: --> PROGRESS INFO debconf-apt-progress/info
Jan 22 17:18:49 debconf: <-- 0 OK
Jan 22 17:18:49 in-target: debconf (developer): <---- 0 OK
Jan 22 17:18:49 in-target: debconf (developer): --> 0 OK
Jan 22 17:18:49 in-target: debconf (developer): <-- PROGRESS SET 150
Jan 22 17:18:49 in-target: debconf (devel
DEBUG:root:Checking VM state for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Cmd: ['virsh', 'domstate', 'caf827bb-6e7e-4158-ad21-da13a40f39ad']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Jan 22 17:19:20 debconf: <-- 0 OK
Jan 22 17:19:20 in-target: debconf (developer): <---- 0 OK
Jan 22 17:19:20 in-target: debconf (developer): --> 0 OK
Jan 22 17:19:20 in-target: debconf (developer): <-- PROGRESS SET 620
Jan 22 17:19:20 in-target: debconf (developer): ----> PROGRESS SET 620
Jan 22 17:19:20 debconf: --> PROGRESS SET 620
Jan 22 17:19:20 debconf: <-- 0 OK
Jan 22 17:19:20 in-target: debconf (developer): <---- 0 OK
Jan 22 17:19:20 in-target: debconf (developer): --> 0 OK
Jan 22 17:19:20
DEBUG:root:Checking VM state for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Cmd: ['virsh', 'domstate', 'caf827bb-6e7e-4158-ad21-da13a40f39ad']
DEBUG:root:running
DEBUG:root:Capturing current d-i syslog for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Jan 22 17:19:50 debconf: --> CAPB
Jan 22 17:19:50 debconf: <-- 0 multiselect backup progresscancel align plugin-terminal plugin-detect-keyboard
Jan 22 17:19:50 debconf: --> METAGET debian-installer/pkgsel/title Description
Jan 22 17:19:50 debconf: <-- 0 Select and install software
Jan 22 17:19:50 debconf: --> SUBST debian-installer/main-menu/item-failure ITEM Select and install software
Jan 22 17:19:50 debconf: Adding [ITEM] -> [Select and install software]
Jan 22 17:19:50 debconf: <-- 0
Jan 22 17:19:50 debconf: --> INPUT critical debian-installer/main-menu/item-failure
Jan 22 17:19:50 debconf: <-- 0 question will be asked
Jan 22 17:19:50 debconf: --> GO
INFO:root:Test caf827bb-6e7e-4158-ad21-da13a40f39ad: debian-installer reports error
DEBUG:root:Terminating test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Cmd: ['virsh', 'destroy', 'caf827bb-6e7e-4158-ad21-da13a40f39ad']
Domain caf827bb-6e7e-4158-ad21-da13a40f39ad destroyed

DEBUG:root:test[iso_url]=/var/lib/ubuntu-iso-testing/isos/ubuntu-server/precise-server-amd64.iso
DEBUG:root:test[_rev]=13-cb4bfd68c43cd9307375ad5bf114edc9
DEBUG:root:test[disks]=[{'bus': 'virtio', 'size': '2G', 'target': 'vda', 'format': 'qcow2'}]
DEBUG:root:test[_attachments]={'tests/dns-server': {'stub': True, 'length': 3769, 'digest': 'md5-bkHtlH9K2B6Cv9RG9AZozg==', 'revpos': 8, 'content_type': u''}, 'libvirt.xml': {'stub': True, 'length': 2032, 'digest': 'md5-l6ddzufN0s62LchKoGoqoA==', 'revpos': 11, 'content_type': 'application/xml'}, 'preseed': {'stub': True, 'length': 13178, 'digest': 'md5-amAr2lPqJDmrInTk1oPedQ==', 'revpos': 2, 'content_type': u''}, 'pxe/pxelinux.cfg/52:54:00:6d:b8:29': {'stub': True, 'length': 1108, 'digest': 'md5-HjAjVJgLsVpRGWoA78kJ1g==', 'revpos': 7, 'content_type': u''}, 'pxe/initrd': {'stub': True, 'length': 8017528, 'digest': 'md5-kD64nTRO9zpwm2TzkENpyA==', 'revpos': 5, 'content_type': u''}, 'media_info': {'stub': True, 'length': 71, 'digest': 'md5-Gxsa/ZDmZgAGfFVKm3E4Nw==', 'revpos': 3, 'content_type': u''}, 'run_test': {'stub': True, 'length': 6943, 'digest': 'md5-ZbbgRpEcHSeMP9A+cvAv1Q==', 'revpos': 4, 'content_type': u''}, 'pxe/kernel': {'stub': True, 'length': 5908472, 'digest': 'md5-dsQygz56UZam95FeYMtUpQ==', 'revpos': 6, 'content_type': u''}}
DEBUG:root:test[states]={'started': '2014-01-22T17:15:56.905080', 'configured': '2014-01-22T17:15:55.085790', 'creating': '2014-01-22T17:15:46.044444', 'created': '2014-01-22T17:15:54.801791'}
DEBUG:root:test[mac]=52:54:00:6d:b8:29
DEBUG:root:test[_id]=caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:test[test_case]=dns-server
ERROR:root:VM for test case caf827bb-6e7e-4158-ad21-da13a40f39ad powered off but tests not completed
WARNING:root:Could not move syslog ([Errno 13] Permission denied: '/tmp/caf827bb-6e7e-4158-ad21-da13a40f39ad.syslog.log')
DEBUG:root:Undefine VM for  test case caf827bb-6e7e-4158-ad21-da13a40f39ad
DEBUG:root:Cmd: ['virsh', 'undefine', 'caf827bb-6e7e-4158-ad21-da13a40f39ad']
Domain caf827bb-6e7e-4158-ad21-da13a40f39ad has been undefined

DEBUG:root:Collating output for test case caf827bb-6e7e-4158-ad21-da13a40f39ad
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: 20140122