full debug log

2013-08-20 10:50:13,110 root INFO: UTAH Features:
 PROVISIONED_AUTORUN: False
2013-08-20 10:50:13,110 root INFO: UTAH version: 0.14.8ubuntu1~precise1
2013-08-20 10:50:13,110 root DEBUG: Configuration variables:
{
    "arch": "amd64", 
    "boot": null, 
    "boottimeout": 600, 
    "bridge": "eth0", 
    "checktimeout": 180, 
    "clean": true, 
    "client_install_timeout": 60, 
    "consoleloglevel": 30, 
    "debug": false, 
    "debuglog": "/var/lib/jenkins/ps-slaves/ps-radeon-hd7450-le/workspace/nexuiz-benchmark-ps-radeon-hd7450-le/gaueko-debug.log", 
    "diskbus": "virtio", 
    "disksizes": [
        8
    ], 
    "dlcommand": "dl-ubuntu-test-iso", 
    "dlpercentincrement": 10, 
    "dlretries": 10, 
    "emulator": null, 
    "fileloglevel": 20, 
    "group": "utah", 
    "hostname": "gaueko", 
    "image": null, 
    "initrd": null, 
    "installpackages": [
        "openssh-server", 
        "gdebi"
    ], 
    "installtimeout": 9000, 
    "installtype": "mini", 
    "isodir": "/var/cache/utah/iso", 
    "jobtimeout": null, 
    "kernel": null, 
    "logfile": "/var/lib/jenkins/ps-slaves/ps-radeon-hd7450-le/workspace/nexuiz-benchmark-ps-radeon-hd7450-le/gaueko.log", 
    "logpath": "/var/lib/jenkins/ps-slaves/ps-radeon-hd7450-le/workspace/nexuiz-benchmark-ps-radeon-hd7450-le", 
    "logrotation_backupCount": 10, 
    "logrotation_interval": 1, 
    "logrotation_when": "W0", 
    "machinedir": null, 
    "machineid": null, 
    "machineinfo": null, 
    "machinetype": "virtual", 
    "machineuuid": null, 
    "macs": [], 
    "name": null, 
    "new": false, 
    "nfscommand": [
        "sudo", 
        "service", 
        "nfs-kernel-server"
    ], 
    "nfsconfigfile": "/etc/exports", 
    "nfsiface": "eth0", 
    "nfsoptions": "*(ro,async,no_root_squash,no_subtree_check)", 
    "outputpreseed": false, 
    "packagedir": "/usr/share/utah", 
    "powercmd": null, 
    "powertimeout": 15, 
    "preboot": null, 
    "prefix": "utah", 
    "preseed": "/etc/utah/default-preseed.cfg", 
    "pxedir": "/var/lib/tftpboot/pxelinux.cfg", 
    "qemupath": "qemu:///system", 
    "retry_timeout": 3, 
    "rewrite": "all", 
    "runtimeout": null, 
    "series": "precise", 
    "serieschoices": [
        "lucid", 
        "precise", 
        "quantal", 
        "raring", 
        "saucy"
    ], 
    "sqlite3_db_connection_timeout": 30, 
    "ssh_logfile": "/var/lib/jenkins/ps-slaves/ps-radeon-hd7450-le/workspace/nexuiz-benchmark-ps-radeon-hd7450-le/gaueko-ssh.log", 
    "sshknownhosts": "/var/lib/jenkins/.ssh/known_hosts", 
    "sshprivatekey": "/var/lib/jenkins/.ssh/utah", 
    "sshpublickey": "/var/lib/jenkins/.ssh/utah.pub", 
    "template": null, 
    "template_dir": "/usr/share/utah/templates", 
    "user": "jenkins", 
    "variant": null, 
    "vmpath": "/var/lib/jenkins/vm", 
    "wwwdir": "/var/www/utah", 
    "wwwiface": "virbr0", 
    "xml": "/etc/utah/default-vm.xml"
}
2013-08-20 10:50:13,111 iso INFO: Preparing image: /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-20 10:50:13,115 iso INFO: /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso is locally available as /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-20 10:50:13,115 iso INFO: Getting image type of /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-20 10:50:13,116 iso DEBUG: bsdtar list command: bsdtar -t -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-20 10:50:13,120 iso INFO: Image type is: desktop
2013-08-20 10:50:13,121 iso DEBUG: Using normal image
2013-08-20 10:50:13,121 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso ./.disk/info
2013-08-20 10:50:13,124 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O .disk/info
2013-08-20 10:50:13,127 iso INFO: Arch is: amd64
2013-08-20 10:50:13,128 iso INFO: Series is saucy
2013-08-20 10:50:13,128 root DEBUG: Executing SQL statement: SELECT COUNT(*) FROM machines, []
2013-08-20 10:50:13,129 root DEBUG: Executing SQL statement: SELECT * FROM machines WHERE name=?, ['ps-radeon-hd7450-le']
2013-08-20 10:50:13,129 root DEBUG: Executing SQL statement: UPDATE machines SET pid=?, state='provisioned' WHERE machineid=? AND state=?, [93345, 7, u'available']
2013-08-20 10:50:13,268 ps-radeon-hd7450-le INFO: Preparing preseed: /data/jenkins/ps-slaves/ps-radeon-hd7450-le/workspace/nexuiz-benchmark-ps-radeon-hd7450-le/ps-qa-tools/preseeds/private-preseed.cfg
2013-08-20 10:50:13,268 ps-radeon-hd7450-le DEBUG: /data/jenkins/ps-slaves/ps-radeon-hd7450-le/workspace/nexuiz-benchmark-ps-radeon-hd7450-le/ps-qa-tools/preseeds/private-preseed.cfg is locally available as /data/jenkins/ps-slaves/ps-radeon-hd7450-le/workspace/nexuiz-benchmark-ps-radeon-hd7450-le/ps-qa-tools/preseeds/private-preseed.cfg
2013-08-20 10:50:13,268 ps-radeon-hd7450-le INFO: Preparing xml: /etc/utah/default-vm.xml
2013-08-20 10:50:13,268 ps-radeon-hd7450-le DEBUG: /etc/utah/default-vm.xml is locally available as /etc/utah/default-vm.xml
2013-08-20 10:50:13,268 ps-radeon-hd7450-le DEBUG: Machine init finished
2013-08-20 10:50:13,269 ps-radeon-hd7450-le INFO: Adding needed command line options
2013-08-20 10:50:13,270 rsyslog INFO: rsylogd port(55748) opened
2013-08-20 10:50:13,270 ps-radeon-hd7450-le INFO: Adding boot option: automatic-ubiquity
2013-08-20 10:50:13,270 ps-radeon-hd7450-le INFO: Adding boot option: noprompt
2013-08-20 10:50:13,270 ps-radeon-hd7450-le INFO: Adding boot option: netcfg/get_hostname=ps-radeon-hd7450-le
2013-08-20 10:50:13,270 ps-radeon-hd7450-le INFO: Adding boot option: log_host=10.97.2.12
2013-08-20 10:50:13,271 ps-radeon-hd7450-le INFO: Adding boot option: log_port=55748
2013-08-20 10:50:13,271 ps-radeon-hd7450-le INFO: Adding boot option: boot=casper
2013-08-20 10:50:13,271 ps-radeon-hd7450-le INFO: Adding boot option: keyboard-configuration/layoutcode=us
2013-08-20 10:50:13,271 ps-radeon-hd7450-le INFO: Boot command line is: automatic-ubiquity noprompt netcfg/get_hostname=ps-radeon-hd7450-le log_host=10.97.2.12 log_port=55748 boot=casper keyboard-configuration/layoutcode=us
2013-08-20 10:50:13,271 root DEBUG: Running command: sudo service nfs-kernel-server status
2013-08-20 10:50:13,271 root DEBUG: Output follows:
2013-08-20 10:50:13,291 root DEBUG: nfsd running
2013-08-20 10:50:13,291 root DEBUG: 
2013-08-20 10:50:13,291 root DEBUG: Return code: 0
2013-08-20 10:50:13,291 ps-radeon-hd7450-le DEBUG: Cobbler arch is x86_64
2013-08-20 10:50:13,291 ps-radeon-hd7450-le DEBUG: Cobbler machine init finished
2013-08-20 10:50:13,291 ps-radeon-hd7450-le INFO: Installing client deb on machine
2013-08-20 10:50:14,089 ps-radeon-hd7450-le DEBUG: Checking if machine is active
2013-08-20 10:50:14,090 ps-radeon-hd7450-le DEBUG: Checking if machine is provisioned
2013-08-20 10:50:14,090 ps-radeon-hd7450-le INFO: Mounting image
2013-08-20 10:50:14,090 ps-radeon-hd7450-le DEBUG: Mounting ISO
2013-08-20 10:50:14,090 root DEBUG: Running command: sudo mount -o loop /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso /tmp/ps-radeon-hd7450-le_VhVAF0/iso.d
2013-08-20 10:50:14,091 root DEBUG: Output follows:
2013-08-20 10:50:14,100 root DEBUG: mount: warning: /tmp/ps-radeon-hd7450-le_VhVAF0/iso.d seems to be mounted read-only.
2013-08-20 10:50:14,101 root DEBUG: 
2013-08-20 10:50:14,101 root DEBUG: Return code: 0
2013-08-20 10:50:14,101 ps-radeon-hd7450-le INFO: Preparing kernel
2013-08-20 10:50:14,101 ps-radeon-hd7450-le INFO: Unpacking kernel from image
2013-08-20 10:50:14,101 iso DEBUG: Getting kernel for desktop image
2013-08-20 10:50:14,101 iso DEBUG: bsdtar list command: bsdtar -t -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-20 10:50:14,107 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso isolinux/txt.cfg
2013-08-20 10:50:14,112 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O isolinux/txt.cfg
2013-08-20 10:50:14,116 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-20 10:50:14,117 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-20 10:50:14,117 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-20 10:50:14,117 iso DEBUG: Rejecting memtest kernel
2013-08-20 10:50:14,117 iso DEBUG: bsdtar list command: bsdtar -t -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-20 10:50:14,122 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso isolinux/rqtxt.cfg
2013-08-20 10:50:14,126 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O isolinux/rqtxt.cfg
2013-08-20 10:50:14,130 iso DEBUG: Found kernel: install/vmlinuz
2013-08-20 10:50:14,130 iso DEBUG: bsdtar list command: bsdtar -t -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-20 10:50:14,135 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso boot/grub/grub.cfg
2013-08-20 10:50:14,139 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O boot/grub/grub.cfg
2013-08-20 10:50:14,143 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-20 10:50:14,144 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-20 10:50:14,144 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-20 10:50:14,144 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-20 10:50:14,144 iso DEBUG: bsdtar list command: bsdtar -t -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-20 10:50:14,149 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso boot/grub/loopback.cfg
2013-08-20 10:50:14,153 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O boot/grub/loopback.cfg
2013-08-20 10:50:14,157 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-20 10:50:14,157 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-20 10:50:14,157 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-20 10:50:14,157 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso casper/vmlinuz.efi
2013-08-20 10:50:14,161 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O casper/vmlinuz.efi
2013-08-20 10:50:14,174 ps-radeon-hd7450-le INFO: Preparing initrd
2013-08-20 10:50:14,174 ps-radeon-hd7450-le INFO: Unpacking initrd from image
2013-08-20 10:50:14,174 ps-radeon-hd7450-le DEBUG: Desktop image detected
2013-08-20 10:50:14,174 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso ./casper/initrd.lz
2013-08-20 10:50:14,179 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O casper/initrd.lz
2013-08-20 10:50:14,216 ps-radeon-hd7450-le INFO: Unpacking initrd
2013-08-20 10:50:14,216 ps-radeon-hd7450-le DEBUG: Using lzma based on file extension
2013-08-20 10:50:15,842 ps-radeon-hd7450-le INFO: Copying ssh public key
2013-08-20 10:50:15,843 ps-radeon-hd7450-le INFO: Creating latecommand scripts
2013-08-20 10:50:15,854 ps-radeon-hd7450-le INFO: Configuring latecommand for desktop
2013-08-20 10:50:15,854 ps-radeon-hd7450-le INFO: Setting up preseed
2013-08-20 10:50:15,876 ps-radeon-hd7450-le INFO: Changing d-i latecommand to ubiquity success_command and prepending ubiquity lines
2013-08-20 10:50:15,878 ps-radeon-hd7450-le INFO: Rewriting username to jenkins
2013-08-20 10:50:15,879 ps-radeon-hd7450-le DEBUG: Adding ubiquity/failure_command question with log messsage
2013-08-20 10:50:15,879 ps-radeon-hd7450-le INFO: Inserting preseed into casper
2013-08-20 10:50:15,880 root DEBUG: Running command: sed -i 1i/scripts/casper-bottom/utah\n[ -e /conf/param.conf ] && . /conf/param.conf /tmp/ps-radeon-hd7450-le_VhVAF0/initrd.d/scripts/casper-bottom/ORDER
2013-08-20 10:50:15,880 root DEBUG: Output follows:
2013-08-20 10:50:15,883 root DEBUG: 
2013-08-20 10:50:15,884 root DEBUG: Return code: 0
2013-08-20 10:50:15,884 ps-radeon-hd7450-le INFO: Creating rsyslog config file
2013-08-20 10:50:15,886 ps-radeon-hd7450-le INFO: Repacking initrd
2013-08-20 10:50:15,886 ps-radeon-hd7450-le DEBUG: Using lzma because installtype is desktop
2013-08-20 10:51:01,249 ps-radeon-hd7450-le INFO: Setting up system with cobbler
2013-08-20 10:51:01,249 ps-radeon-hd7450-le DEBUG: Removing old system
2013-08-20 10:51:01,252 root DEBUG: Running command: sudo cobbler system remove --name=ps-radeon-hd7450-le
2013-08-20 10:51:01,252 root DEBUG: Output follows:
2013-08-20 10:51:01,414 root DEBUG: internal error, unknown system name ps-radeon-hd7450-le
2013-08-20 10:51:01,419 root DEBUG: 
2013-08-20 10:51:01,420 root DEBUG: Return code: 0
2013-08-20 10:51:01,420 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:51:01 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:01 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:01 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:01 2013 - INFO | Exception occured: <class 'cobbler.cexceptions.CX'>
Tue Aug 20 10:51:01 2013 - INFO | Exception value: 'internal error, unknown system name ps-radeon-hd7450-le'
Tue Aug 20 10:51:01 2013 - INFO | Exception Info:
  File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 1944, in _dispatch
    return method_handle(*params)
   File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 873, in xapi_object_edit
    handle = self.get_item_handle(object_type, object_name)
   File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 655, in get_item_handle
    raise CX("internal error, unknown %s name %s" % (what,name))


2013-08-20 10:51:01,420 ps-radeon-hd7450-le INFO: Removing old profile
2013-08-20 10:51:01,422 root DEBUG: Running command: sudo cobbler profile remove --name=ps-radeon-hd7450-le-x86_64
2013-08-20 10:51:01,423 root DEBUG: Output follows:
2013-08-20 10:51:01,582 root DEBUG: internal error, unknown profile name ps-radeon-hd7450-le-x86_64
2013-08-20 10:51:01,586 root DEBUG: 
2013-08-20 10:51:01,587 root DEBUG: Return code: 0
2013-08-20 10:51:01,587 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:51:01 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:01 2013 - DEBUG | get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:01 2013 - DEBUG | done with get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:01 2013 - INFO | Exception occured: <class 'cobbler.cexceptions.CX'>
Tue Aug 20 10:51:01 2013 - INFO | Exception value: 'internal error, unknown profile name ps-radeon-hd7450-le-x86_64'
Tue Aug 20 10:51:01 2013 - INFO | Exception Info:
  File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 1944, in _dispatch
    return method_handle(*params)
   File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 873, in xapi_object_edit
    handle = self.get_item_handle(object_type, object_name)
   File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 655, in get_item_handle
    raise CX("internal error, unknown %s name %s" % (what,name))


2013-08-20 10:51:01,587 ps-radeon-hd7450-le INFO: Removing old distro
2013-08-20 10:51:01,589 root DEBUG: Running command: sudo cobbler distro remove --name=ps-radeon-hd7450-le-x86_64
2013-08-20 10:51:01,590 root DEBUG: Output follows:
2013-08-20 10:51:01,749 root DEBUG: internal error, unknown distro name ps-radeon-hd7450-le-x86_64
2013-08-20 10:51:01,754 root DEBUG: 
2013-08-20 10:51:01,755 root DEBUG: Return code: 0
2013-08-20 10:51:01,755 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:51:01 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:01 2013 - DEBUG | get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:01 2013 - DEBUG | done with get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:01 2013 - INFO | Exception occured: <class 'cobbler.cexceptions.CX'>
Tue Aug 20 10:51:01 2013 - INFO | Exception value: 'internal error, unknown distro name ps-radeon-hd7450-le-x86_64'
Tue Aug 20 10:51:01 2013 - INFO | Exception Info:
  File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 1944, in _dispatch
    return method_handle(*params)
   File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 873, in xapi_object_edit
    handle = self.get_item_handle(object_type, object_name)
   File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 655, in get_item_handle
    raise CX("internal error, unknown %s name %s" % (what,name))


2013-08-20 10:51:01,755 ps-radeon-hd7450-le INFO: Creating distro
2013-08-20 10:51:01,757 root DEBUG: Running command: sudo cobbler distro add --name=ps-radeon-hd7450-le-x86_64 --kernel=/tmp/ps-radeon-hd7450-le_VhVAF0/kernel --initrd=/tmp/ps-radeon-hd7450-le_VhVAF0/initrd.lz
2013-08-20 10:51:01,758 root DEBUG: Output follows:
2013-08-20 10:51:01,977 root DEBUG: 
2013-08-20 10:51:01,977 root DEBUG: Return code: 0
2013-08-20 10:51:01,978 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:51:01 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:01 2013 - DEBUG | get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:01 2013 - DEBUG | done with get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:01 2013 - INFO | Exception occured: <class 'cobbler.cexceptions.CX'>
Tue Aug 20 10:51:01 2013 - INFO | Exception value: 'internal error, unknown distro name ps-radeon-hd7450-le-x86_64'
Tue Aug 20 10:51:01 2013 - INFO | Exception Info:
  File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 862, in xapi_object_edit
    handle = self.get_item_handle(object_type, object_name)
   File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 655, in get_item_handle
    raise CX("internal error, unknown %s name %s" % (what,name))

Tue Aug 20 10:51:01 2013 - INFO | REMOTE new_item(distro); user(<DIRECT>)
Tue Aug 20 10:51:01 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:01 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(___NEW___distro::VOnZsqVMaS0v4x3bFNvEegcoGOYhTOXC/g==); attribute(kernel)
Tue Aug 20 10:51:01 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:01 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(___NEW___distro::VOnZsqVMaS0v4x3bFNvEegcoGOYhTOXC/g==); attribute(initrd)
Tue Aug 20 10:51:01 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:01 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(___NEW___distro::VOnZsqVMaS0v4x3bFNvEegcoGOYhTOXC/g==); attribute(name)
Tue Aug 20 10:51:01 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:01 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(___NEW___distro::VOnZsqVMaS0v4x3bFNvEegcoGOYhTOXC/g==); attribute(in_place)
Tue Aug 20 10:51:01 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:01 2013 - INFO | REMOTE save_item(distro); user(<DIRECT>); object_id(___NEW___distro::VOnZsqVMaS0v4x3bFNvEegcoGOYhTOXC/g==)
Tue Aug 20 10:51:01 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:01 2013 - INFO | add_item(distro); ['ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:01 2013 - DEBUG | get_items; ['distro']
Tue Aug 20 10:51:01 2013 - DEBUG | done with get_items; ['distro']
Tue Aug 20 10:51:01 2013 - INFO | trying hardlink /tmp/ps-radeon-hd7450-le_VhVAF0/kernel -> /var/www/cobbler/images/ps-radeon-hd7450-le-x86_64/kernel
Tue Aug 20 10:51:01 2013 - INFO | trying hardlink /tmp/ps-radeon-hd7450-le_VhVAF0/initrd.lz -> /var/www/cobbler/images/ps-radeon-hd7450-le-x86_64/initrd.lz
Tue Aug 20 10:51:01 2013 - INFO | trying hardlink /tmp/ps-radeon-hd7450-le_VhVAF0/kernel -> /var/lib/tftpboot/images/ps-radeon-hd7450-le-x86_64/kernel
Tue Aug 20 10:51:01 2013 - INFO | trying hardlink /tmp/ps-radeon-hd7450-le_VhVAF0/initrd.lz -> /var/lib/tftpboot/images/ps-radeon-hd7450-le-x86_64/initrd.lz
Tue Aug 20 10:51:01 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:01 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:01 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:01 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:01 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:01 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:01 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:01 2013 - WARNING | warning: kernel option length exceeds 255

2013-08-20 10:51:01,978 ps-radeon-hd7450-le INFO: Creating profile
2013-08-20 10:51:01,981 root DEBUG: Running command: sudo cobbler profile add --name=ps-radeon-hd7450-le-x86_64 --distro=ps-radeon-hd7450-le-x86_64
2013-08-20 10:51:01,981 root DEBUG: Output follows:
2013-08-20 10:51:02,171 root DEBUG: 
2013-08-20 10:51:02,172 root DEBUG: Return code: 0
2013-08-20 10:51:02,172 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - DEBUG | get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - INFO | Exception occured: <class 'cobbler.cexceptions.CX'>
Tue Aug 20 10:51:02 2013 - INFO | Exception value: 'internal error, unknown profile name ps-radeon-hd7450-le-x86_64'
Tue Aug 20 10:51:02 2013 - INFO | Exception Info:
  File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 862, in xapi_object_edit
    handle = self.get_item_handle(object_type, object_name)
   File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 655, in get_item_handle
    raise CX("internal error, unknown %s name %s" % (what,name))

Tue Aug 20 10:51:02 2013 - INFO | REMOTE new_item(profile); user(<DIRECT>)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(profile); user(<DIRECT>); object_id(___NEW___profile::ak1qum6SHfSAp6ahpb99cq2pkgljJRF9mQ==); attribute(in_place)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(profile); user(<DIRECT>); object_id(___NEW___profile::ak1qum6SHfSAp6ahpb99cq2pkgljJRF9mQ==); attribute(name)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(profile); user(<DIRECT>); object_id(___NEW___profile::ak1qum6SHfSAp6ahpb99cq2pkgljJRF9mQ==); attribute(distro)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE save_item(profile); user(<DIRECT>); object_id(___NEW___profile::ak1qum6SHfSAp6ahpb99cq2pkgljJRF9mQ==)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | add_item(profile); ['ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | get_items; ['profile']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_items; ['profile']
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255

2013-08-20 10:51:02,172 ps-radeon-hd7450-le INFO: Setting up NFS for desktop install
2013-08-20 10:51:02,172 ps-radeon-hd7450-le DEBUG: Adding export to NFS config file
2013-08-20 10:51:02,179 ps-radeon-hd7450-le DEBUG: Reloading NFS config
2013-08-20 10:51:02,179 root DEBUG: Running command: sudo service nfs-kernel-server reload
2013-08-20 10:51:02,179 root DEBUG: Output follows:
2013-08-20 10:51:02,191 root DEBUG: * Re-exporting directories for NFS kernel daemon...
2013-08-20 10:51:02,192 root DEBUG: ...done.
2013-08-20 10:51:02,192 root DEBUG: 
2013-08-20 10:51:02,192 root DEBUG: Return code: 0
2013-08-20 10:51:02,192 ps-radeon-hd7450-le DEBUG: Adding NFS boot options
2013-08-20 10:51:02,193 ps-radeon-hd7450-le INFO: Adding kernel boot options to distro
2013-08-20 10:51:02,196 root DEBUG: Running command: sudo cobbler distro edit --name=ps-radeon-hd7450-le-x86_64 --kopts=automatic-ubiquity noprompt netcfg/get_hostname=ps-radeon-hd7450-le log_host=10.97.2.12 log_port=55748 boot=casper keyboard-configuration/layoutcode=us root=/dev/nfs netboot=nfs nfsroot=10.97.2.12:/tmp/ps-radeon-hd7450-le_VhVAF0/iso.d
2013-08-20 10:51:02,196 root DEBUG: Output follows:
2013-08-20 10:51:02,418 root DEBUG: 
2013-08-20 10:51:02,419 root DEBUG: Return code: 0
2013-08-20 10:51:02,419 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - DEBUG | get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(distro::ps-radeon-hd7450-le-x86_64); attribute(in_place)
Tue Aug 20 10:51:02 2013 - DEBUG | get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(distro::ps-radeon-hd7450-le-x86_64); attribute(name)
Tue Aug 20 10:51:02 2013 - DEBUG | get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(distro::ps-radeon-hd7450-le-x86_64); attribute(kernel_options)
Tue Aug 20 10:51:02 2013 - DEBUG | get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE save_item(distro); user(<DIRECT>); object_id(distro::ps-radeon-hd7450-le-x86_64)
Tue Aug 20 10:51:02 2013 - DEBUG | get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | add_item(distro); ['ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | get_items; ['distro']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_items; ['distro']
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255

2013-08-20 10:51:02,419 ps-radeon-hd7450-le INFO: Adding kickstart to profile
2013-08-20 10:51:02,422 root DEBUG: Running command: sudo cobbler profile edit --name=ps-radeon-hd7450-le-x86_64 --kickstart=/tmp/ps-radeon-hd7450-le_VhVAF0/initrd.d/preseed.cfg
2013-08-20 10:51:02,422 root DEBUG: Output follows:
2013-08-20 10:51:02,609 root DEBUG: 
2013-08-20 10:51:02,609 root DEBUG: Return code: 0
2013-08-20 10:51:02,609 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - DEBUG | get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(profile); user(<DIRECT>); object_id(profile::ps-radeon-hd7450-le-x86_64); attribute(in_place)
Tue Aug 20 10:51:02 2013 - DEBUG | get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(profile); user(<DIRECT>); object_id(profile::ps-radeon-hd7450-le-x86_64); attribute(kickstart)
Tue Aug 20 10:51:02 2013 - DEBUG | get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(profile); user(<DIRECT>); object_id(profile::ps-radeon-hd7450-le-x86_64); attribute(name)
Tue Aug 20 10:51:02 2013 - DEBUG | get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE save_item(profile); user(<DIRECT>); object_id(profile::ps-radeon-hd7450-le-x86_64)
Tue Aug 20 10:51:02 2013 - DEBUG | get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | add_item(profile); ['ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:51:02 2013 - DEBUG | get_items; ['profile']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_items; ['profile']
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255

2013-08-20 10:51:02,609 ps-radeon-hd7450-le INFO: Adding system
2013-08-20 10:51:02,612 root DEBUG: Running command: sudo cobbler system add --name=ps-radeon-hd7450-le --profile=ps-radeon-hd7450-le-x86_64 --netboot-enabled=Y --power-id=PS_Radeon_HD7450_LE --power-type=sentryswitch_cdu --power-pass=ubuntu --power-address=10.97.0.15 --mac-address=00:22:4d:88:a6:96 --power-user=ubuntu
2013-08-20 10:51:02,612 root DEBUG: Output follows:
2013-08-20 10:51:02,794 root DEBUG: 
2013-08-20 10:51:02,794 root DEBUG: Return code: 0
2013-08-20 10:51:02,794 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:02 2013 - INFO | Exception occured: <class 'cobbler.cexceptions.CX'>
Tue Aug 20 10:51:02 2013 - INFO | Exception value: 'internal error, unknown system name ps-radeon-hd7450-le'
Tue Aug 20 10:51:02 2013 - INFO | Exception Info:
  File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 862, in xapi_object_edit
    handle = self.get_item_handle(object_type, object_name)
   File "/usr/lib/python2.7/dist-packages/cobbler/remote.py", line 655, in get_item_handle
    raise CX("internal error, unknown %s name %s" % (what,name))

Tue Aug 20 10:51:02 2013 - INFO | REMOTE new_item(system); user(<DIRECT>)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::JKHxLkFxR/IHI7MxH0MfzhDpV2fkKaFyIQ==); attribute(profile)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::JKHxLkFxR/IHI7MxH0MfzhDpV2fkKaFyIQ==); attribute(name)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::JKHxLkFxR/IHI7MxH0MfzhDpV2fkKaFyIQ==); attribute(power_type)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::JKHxLkFxR/IHI7MxH0MfzhDpV2fkKaFyIQ==); attribute(power_id)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::JKHxLkFxR/IHI7MxH0MfzhDpV2fkKaFyIQ==); attribute(power_user)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::JKHxLkFxR/IHI7MxH0MfzhDpV2fkKaFyIQ==); attribute(power_address)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::JKHxLkFxR/IHI7MxH0MfzhDpV2fkKaFyIQ==); attribute(in_place)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::JKHxLkFxR/IHI7MxH0MfzhDpV2fkKaFyIQ==); attribute(power_pass)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::JKHxLkFxR/IHI7MxH0MfzhDpV2fkKaFyIQ==); attribute(netboot_enabled)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::JKHxLkFxR/IHI7MxH0MfzhDpV2fkKaFyIQ==); attribute(modify_interface)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | find_items; ['system']
Tue Aug 20 10:51:02 2013 - INFO | REMOTE save_item(system); user(<DIRECT>); object_id(___NEW___system::JKHxLkFxR/IHI7MxH0MfzhDpV2fkKaFyIQ==)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | add_item(system); ['ps-radeon-hd7450-le']
Tue Aug 20 10:51:02 2013 - DEBUG | get_items; ['system']
Tue Aug 20 10:51:02 2013 - DEBUG | done with get_items; ['system']
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - INFO | generating: /var/lib/tftpboot/pxelinux.cfg/01-00-22-4d-88-a6-96
Tue Aug 20 10:51:02 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:02 2013 - INFO | generating: /var/lib/tftpboot/grub/01-00-22-4D-88-A6-96

2013-08-20 10:51:02,794 ps-radeon-hd7450-le INFO: Restarting system
2013-08-20 10:51:02,794 ps-radeon-hd7450-le DEBUG: Stopping system
2013-08-20 10:51:02,797 root DEBUG: Running command: sudo cobbler system poweroff --name=ps-radeon-hd7450-le
2013-08-20 10:51:02,797 root DEBUG: Output follows:
2013-08-20 10:51:06,017 root DEBUG: task started: 2013-08-20_105102_power
2013-08-20 10:51:06,017 root DEBUG: task started (id=Power management (off), time=Tue Aug 20 10:51:02 2013)
2013-08-20 10:51:06,017 root DEBUG: cobbler power configuration is:
2013-08-20 10:51:06,017 root DEBUG: type   : sentryswitch_cdu
2013-08-20 10:51:06,017 root DEBUG: address: 10.97.0.15
2013-08-20 10:51:06,017 root DEBUG: user   : ubuntu
2013-08-20 10:51:06,018 root DEBUG: id     : PS_Radeon_HD7450_LE
2013-08-20 10:51:06,018 root DEBUG: - fence_cdu -a "10.97.0.15" -n "PS_Radeon_HD7450_LE" -l "ubuntu" -p "ubuntu" -o "off"
2013-08-20 10:51:06,018 root DEBUG: running: ['/bin/sh', '-c', u'fence_cdu -a "10.97.0.15" -n "PS_Radeon_HD7450_LE" -l "ubuntu" -p "ubuntu" -o "off"']
2013-08-20 10:51:06,018 root DEBUG: received on stdout: Success: Powered OFF
2013-08-20 10:51:06,018 root DEBUG: 
2013-08-20 10:51:06,018 root DEBUG: received on stderr:
2013-08-20 10:51:06,018 root DEBUG: *** TASK COMPLETE ***
2013-08-20 10:51:06,023 root DEBUG: 
2013-08-20 10:51:06,023 root DEBUG: Return code: 0
2013-08-20 10:51:06,023 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:02 2013 - INFO | REMOTE start_task(Power management (off)); event_id(2013-08-20_105102_power); user(?)
Tue Aug 20 10:51:03 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:03 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:03 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:03 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:03 2013 - DEBUG | REMOTE CLI Authorized; user(?)

2013-08-20 10:51:06,023 ps-radeon-hd7450-le DEBUG: Waiting 15 seconds
2013-08-20 10:51:21,038 ps-radeon-hd7450-le DEBUG: Starting system
2013-08-20 10:51:21,041 root DEBUG: Running command: sudo cobbler system poweron --name=ps-radeon-hd7450-le
2013-08-20 10:51:21,042 root DEBUG: Output follows:
2013-08-20 10:51:24,272 root DEBUG: task started: 2013-08-20_105121_power
2013-08-20 10:51:24,273 root DEBUG: task started (id=Power management (on), time=Tue Aug 20 10:51:21 2013)
2013-08-20 10:51:24,273 root DEBUG: cobbler power configuration is:
2013-08-20 10:51:24,273 root DEBUG: type   : sentryswitch_cdu
2013-08-20 10:51:24,273 root DEBUG: address: 10.97.0.15
2013-08-20 10:51:24,273 root DEBUG: user   : ubuntu
2013-08-20 10:51:24,273 root DEBUG: id     : PS_Radeon_HD7450_LE
2013-08-20 10:51:24,273 root DEBUG: - fence_cdu -a "10.97.0.15" -n "PS_Radeon_HD7450_LE" -l "ubuntu" -p "ubuntu" -o "on"
2013-08-20 10:51:24,273 root DEBUG: running: ['/bin/sh', '-c', u'fence_cdu -a "10.97.0.15" -n "PS_Radeon_HD7450_LE" -l "ubuntu" -p "ubuntu" -o "on"']
2013-08-20 10:51:24,273 root DEBUG: received on stdout: Success: Powered ON
2013-08-20 10:51:24,274 root DEBUG: 
2013-08-20 10:51:24,274 root DEBUG: received on stderr:
2013-08-20 10:51:24,274 root DEBUG: *** TASK COMPLETE ***
2013-08-20 10:51:24,280 root DEBUG: 
2013-08-20 10:51:24,280 root DEBUG: Return code: 0
2013-08-20 10:51:24,280 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:51:21 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:21 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:21 2013 - INFO | REMOTE start_task(Power management (on)); event_id(2013-08-20_105121_power); user(?)
Tue Aug 20 10:51:22 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:22 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:22 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:22 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:22 2013 - DEBUG | REMOTE CLI Authorized; user(?)

2013-08-20 10:51:24,281 rsyslog INFO: Waiting 8999s for: system started
2013-08-20 10:51:57,710 rsyslog INFO: Matched pattern '.*' for 'system started' message
2013-08-20 10:51:57,711 ps-radeon-hd7450-le INFO: install seems to have booted, disabling netboot
2013-08-20 10:51:57,714 root DEBUG: Running command: sudo cobbler system edit --name=ps-radeon-hd7450-le --netboot-enabled=N
2013-08-20 10:51:57,714 root DEBUG: Output follows:
2013-08-20 10:51:57,897 root DEBUG: 
2013-08-20 10:51:57,898 root DEBUG: Return code: 0
2013-08-20 10:51:57,898 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:51:57 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:57 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(system::ps-radeon-hd7450-le); attribute(in_place)
Tue Aug 20 10:51:57 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:57 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(system::ps-radeon-hd7450-le); attribute(netboot_enabled)
Tue Aug 20 10:51:57 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:57 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(system::ps-radeon-hd7450-le); attribute(name)
Tue Aug 20 10:51:57 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:57 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(system::ps-radeon-hd7450-le); attribute(modify_interface)
Tue Aug 20 10:51:57 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:57 2013 - INFO | REMOTE save_item(system); user(<DIRECT>); object_id(system::ps-radeon-hd7450-le)
Tue Aug 20 10:51:57 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:51:57 2013 - INFO | add_item(system); ['ps-radeon-hd7450-le']
Tue Aug 20 10:51:57 2013 - DEBUG | get_items; ['system']
Tue Aug 20 10:51:57 2013 - DEBUG | done with get_items; ['system']
Tue Aug 20 10:51:57 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:57 2013 - INFO | generating: /var/lib/tftpboot/pxelinux.cfg/01-00-22-4d-88-a6-96
Tue Aug 20 10:51:57 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:51:57 2013 - INFO | generating: /var/lib/tftpboot/grub/01-00-22-4D-88-A6-96

2013-08-20 10:51:57,898 rsyslog INFO: Waiting 8966s for: install started
2013-08-20 10:52:00,790 rsyslog INFO: Matched pattern '.*ubiquity\\[\\d+\\]: Ubiquity \\d' for 'install started' message
2013-08-20 10:52:00,790 rsyslog INFO: Waiting 8963s for: install complete
2013-08-20 10:55:27,071 rsyslog INFO: Matched pattern '.*log-output -t ubiquity umount /target$' for 'install complete' message
2013-08-20 10:55:27,071 rsyslog INFO: Waiting 599s for: system booted
2013-08-20 10:56:05,842 rsyslog WARNING: Expected pattern missed, matched future pattern: .*Machine UUID:\s+(.*)
2013-08-20 10:56:05,842 ssh INFO: Checking for ssh availability
2013-08-20 10:56:11,086 ps-radeon-hd7450-le INFO: Removing NFS share
2013-08-20 10:56:11,086 root DEBUG: Running command: sudo sed /\/tmp\/ps-radeon-hd7450-le_VhVAF0\/iso.d/d -i /etc/exports
2013-08-20 10:56:11,086 root DEBUG: Output follows:
2013-08-20 10:56:11,095 root DEBUG: 
2013-08-20 10:56:11,095 root DEBUG: Return code: 0
2013-08-20 10:56:11,095 ps-radeon-hd7450-le DEBUG: Reloading NFS config
2013-08-20 10:56:11,095 root DEBUG: Running command: sudo service nfs-kernel-server reload
2013-08-20 10:56:11,095 root DEBUG: Output follows:
2013-08-20 10:56:11,107 root DEBUG: * Re-exporting directories for NFS kernel daemon...
2013-08-20 10:56:11,108 root DEBUG: ...done.
2013-08-20 10:56:11,108 root DEBUG: 
2013-08-20 10:56:11,108 root DEBUG: Return code: 0
2013-08-20 10:56:11,108 ps-radeon-hd7450-le INFO: Unmounting ISO
2013-08-20 10:56:11,108 root DEBUG: Running command: sudo umount /tmp/ps-radeon-hd7450-le_VhVAF0/iso.d
2013-08-20 10:56:11,108 root DEBUG: Output follows:
2013-08-20 10:56:11,271 root DEBUG: 
2013-08-20 10:56:11,271 root DEBUG: Return code: 0
2013-08-20 10:56:11,271 ps-radeon-hd7450-le DEBUG: Removing directory
2013-08-20 10:56:11,271 ps-radeon-hd7450-le INFO: System installed
2013-08-20 10:56:16,438 ssh INFO: Uploading /usr/share/utah/python-jsonschema_1.3.0~ppa1_all.deb from the host to /tmp on the machine
2013-08-20 10:56:16,544 ps-radeon-hd7450-le DEBUG: Checking if machine is active
2013-08-20 10:56:16,544 ps-radeon-hd7450-le DEBUG: Checking if machine is provisioned
2013-08-20 10:56:16,545 ssh DEBUG: Connecting SSH
2013-08-20 10:56:21,682 ssh DEBUG: Opening SSH session
2013-08-20 10:56:21,690 ssh INFO: Running command through SSH: while fuser /var/lib/dpkg/lock \
            /var/cache/apt/archives/lock \
            /var/lib/apt/lists/lock \
      >/dev/null 2>&1
do
    logger -t utah "Waiting for packaging locks to become available..."
    sleep 10
done
DEBIAN_FRONTEND=noninteractive gdebi -n -q /tmp/python-jsonschema_1.3.0~ppa1_all.deb
2013-08-20 10:56:24,601 ssh DEBUG: Closing SSH connection
2013-08-20 10:56:24,602 ssh DEBUG: Return code: 0
2013-08-20 10:56:24,602 ssh DEBUG: Standard output follows:
2013-08-20 10:56:24,602 ssh DEBUG: Selecting previously unselected package python-jsonschema.
2013-08-20 10:56:24,602 ssh DEBUG: (Reading database ... 165903 files and directories currently installed.)
2013-08-20 10:56:24,602 ssh DEBUG: Unpacking python-jsonschema (from .../python-jsonschema_1.3.0~ppa1_all.deb) ...
2013-08-20 10:56:24,602 ssh DEBUG: Setting up python-jsonschema (1.3.0~ppa1) ...
2013-08-20 10:56:24,602 ssh DEBUG: Standard error follows:
2013-08-20 10:56:24,603 ps-radeon-hd7450-le DEBUG: Checking if machine is active
2013-08-20 10:56:24,603 ps-radeon-hd7450-le DEBUG: Checking if machine is provisioned
2013-08-20 10:56:24,745 ssh INFO: Uploading /usr/share/utah/utah-common_0.14.8ubuntu1~precise1_all.deb from the host to /tmp on the machine
2013-08-20 10:56:24,850 ps-radeon-hd7450-le DEBUG: Checking if machine is active
2013-08-20 10:56:24,850 ps-radeon-hd7450-le DEBUG: Checking if machine is provisioned
2013-08-20 10:56:24,850 ssh DEBUG: Connecting SSH
2013-08-20 10:56:24,985 ssh DEBUG: Opening SSH session
2013-08-20 10:56:24,987 ssh INFO: Running command through SSH: while fuser /var/lib/dpkg/lock \
            /var/cache/apt/archives/lock \
            /var/lib/apt/lists/lock \
      >/dev/null 2>&1
do
    logger -t utah "Waiting for packaging locks to become available..."
    sleep 10
done
DEBIAN_FRONTEND=noninteractive gdebi -n -q /tmp/utah-common_0.14.8ubuntu1~precise1_all.deb
2013-08-20 10:56:28,359 ssh DEBUG: Closing SSH connection
2013-08-20 10:56:28,360 ssh DEBUG: Return code: 0
2013-08-20 10:56:28,360 ssh DEBUG: Standard output follows:
2013-08-20 10:56:28,360 ssh DEBUG: Selecting previously unselected package utah-common.
2013-08-20 10:56:28,360 ssh DEBUG: (Reading database ... 165910 files and directories currently installed.)
2013-08-20 10:56:28,361 ssh DEBUG: Unpacking utah-common (from .../utah-common_0.14.8ubuntu1~precise1_all.deb) ...
2013-08-20 10:56:28,361 ssh DEBUG: Setting up utah-common (0.14.8ubuntu1~precise1) ...
2013-08-20 10:56:28,361 ssh DEBUG: Standard error follows:
2013-08-20 10:56:28,361 ps-radeon-hd7450-le DEBUG: Checking if machine is active
2013-08-20 10:56:28,361 ps-radeon-hd7450-le DEBUG: Checking if machine is provisioned
2013-08-20 10:56:28,531 ssh INFO: Uploading /usr/share/utah/utah-client_0.14.8ubuntu1~precise1_all.deb from the host to /tmp on the machine
2013-08-20 10:56:28,637 ps-radeon-hd7450-le DEBUG: Checking if machine is active
2013-08-20 10:56:28,637 ps-radeon-hd7450-le DEBUG: Checking if machine is provisioned
2013-08-20 10:56:28,637 ssh DEBUG: Connecting SSH
2013-08-20 10:56:28,740 ssh DEBUG: Opening SSH session
2013-08-20 10:56:28,772 ssh INFO: Running command through SSH: while fuser /var/lib/dpkg/lock \
            /var/cache/apt/archives/lock \
            /var/lib/apt/lists/lock \
      >/dev/null 2>&1
do
    logger -t utah "Waiting for packaging locks to become available..."
    sleep 10
done
DEBIAN_FRONTEND=noninteractive gdebi -n -q /tmp/utah-client_0.14.8ubuntu1~precise1_all.deb
2013-08-20 10:56:40,613 ssh DEBUG: Closing SSH connection
2013-08-20 10:56:40,614 ssh DEBUG: Return code: 0
2013-08-20 10:56:40,614 ssh DEBUG: Standard output follows:
2013-08-20 10:56:40,614 ssh DEBUG: 0% [Working]                                                                    
0% [Connecting to archive.ubuntu.com]                                           
0% [Connecting to archive.ubuntu.com (91.189.92.200)]                           
0% [Waiting for headers]                                                        
Get:1 http://archive.ubuntu.com/ubuntu/ saucy/main libyaml-0-2 amd64 0.1.4-2build1 [57.3 kB]
2013-08-20 10:56:40,614 ssh DEBUG: 4% [1 libyaml-0-2 9829 B/57.3 kB 17%]                                           
25% [Waiting for headers]                                                       
Get:2 http://archive.ubuntu.com/ubuntu/ saucy/universe python-psutil amd64 0.6.1-2ubuntu1 [51.4 kB]
2013-08-20 10:56:40,614 ssh DEBUG: 29% [2 python-psutil 7577 B/51.4 kB 14%]                                        
49% [Waiting for headers]                                                       
Get:3 http://archive.ubuntu.com/ubuntu/ saucy/main python-yaml amd64 3.10-4build2 [113 kB]
2013-08-20 10:56:40,614 ssh DEBUG: 58% [3 python-yaml 21.4 kB/113 kB 18%]                                          
100% [Working]                                                                  
Fetched 222 kB in 0s (0 B/s)
2013-08-20 10:56:40,615 ssh DEBUG: Selecting previously unselected package libyaml-0-2:amd64.
2013-08-20 10:56:40,615 ssh DEBUG: (Reading database ... 165919 files and directories currently installed.)
2013-08-20 10:56:40,615 ssh DEBUG: Unpacking libyaml-0-2:amd64 (from .../libyaml-0-2_0.1.4-2build1_amd64.deb) ...
2013-08-20 10:56:40,615 ssh DEBUG: Selecting previously unselected package python-psutil.
2013-08-20 10:56:40,615 ssh DEBUG: Unpacking python-psutil (from .../python-psutil_0.6.1-2ubuntu1_amd64.deb) ...
2013-08-20 10:56:40,615 ssh DEBUG: Selecting previously unselected package python-yaml.
2013-08-20 10:56:40,615 ssh DEBUG: Unpacking python-yaml (from .../python-yaml_3.10-4build2_amd64.deb) ...
2013-08-20 10:56:40,615 ssh DEBUG: Setting up libyaml-0-2:amd64 (0.1.4-2build1) ...
2013-08-20 10:56:40,615 ssh DEBUG: Setting up python-psutil (0.6.1-2ubuntu1) ...
2013-08-20 10:56:40,615 ssh DEBUG: Setting up python-yaml (3.10-4build2) ...
2013-08-20 10:56:40,615 ssh DEBUG: Processing triggers for libc-bin ...
2013-08-20 10:56:40,616 ssh DEBUG: Selecting previously unselected package utah-client.
2013-08-20 10:56:40,616 ssh DEBUG: (Reading database ... 166017 files and directories currently installed.)
2013-08-20 10:56:40,616 ssh DEBUG: Unpacking utah-client (from .../utah-client_0.14.8ubuntu1~precise1_all.deb) ...
2013-08-20 10:56:40,616 ssh DEBUG: Setting up utah-client (0.14.8ubuntu1~precise1) ...
2013-08-20 10:56:40,616 ssh DEBUG: Processing triggers for man-db ...
2013-08-20 10:56:40,616 ssh DEBUG: Fetched 222 kB in 0s (0 B/s)
2013-08-20 10:56:40,616 ssh DEBUG: Standard error follows:
2013-08-20 10:56:40,616 ps-radeon-hd7450-le DEBUG: Checking if machine is active
2013-08-20 10:56:40,617 ps-radeon-hd7450-le DEBUG: Checking if machine is provisioned
2013-08-20 10:56:40,759 ssh INFO: Uploading /data/jenkins/ps-slaves/ps-radeon-hd7450-le/workspace/nexuiz-benchmark-ps-radeon-hd7450-le/master.run from the host to /tmp on the machine
2013-08-20 10:56:40,863 ps-radeon-hd7450-le DEBUG: Checking if machine is active
2013-08-20 10:56:40,863 ps-radeon-hd7450-le DEBUG: Checking if machine is provisioned
2013-08-20 10:56:40,863 ssh DEBUG: Connecting SSH
2013-08-20 10:56:40,997 ssh DEBUG: Opening SSH session
2013-08-20 10:56:40,998 ssh INFO: Running command through SSH: utah -f yaml -r /tmp/master.run -o /var/lib/utah/utah.out
2013-08-20 10:58:55,308 ssh DEBUG: Closing SSH connection
2013-08-20 10:58:55,309 ssh WARNING: SSH command (utah -f yaml -r /tmp/master.run -o /var/lib/utah/utah.out) failed with return code: 3
2013-08-20 10:58:55,309 ssh DEBUG: Standard output follows:
2013-08-20 10:58:55,309 ssh DEBUG: Standard error follows:
2013-08-20 10:58:55,309 root INFO: System under test is rebooting
2013-08-20 10:58:55,309 root INFO: Checking if UTAH client is finished
2013-08-20 10:58:55,309 ps-radeon-hd7450-le DEBUG: Checking if machine is active
2013-08-20 10:58:55,309 ps-radeon-hd7450-le DEBUG: Checking if machine is provisioned
2013-08-20 10:58:55,309 ps-radeon-hd7450-le DEBUG: Checking if machine is active
2013-08-20 10:58:55,309 ps-radeon-hd7450-le DEBUG: Checking if machine is provisioned
2013-08-20 10:58:55,310 ssh DEBUG: Connecting SSH
2013-08-20 10:58:55,879 ssh DEBUG: Opening SSH session
2013-08-20 10:58:55,880 ssh INFO: Running command through SSH: /usr/share/utah/client/utah-done.py
2013-08-20 10:58:55,918 ssh DEBUG: Closing SSH connection
2013-08-20 10:58:55,918 ssh DEBUG: SSH command (/usr/share/utah/client/utah-done.py) failed with return code: -1
2013-08-20 10:58:55,919 ssh DEBUG: Standard output follows:
2013-08-20 10:58:55,919 ssh DEBUG: Standard error follows:
2013-08-20 10:58:55,919 root INFO: UTAH client is finished
2013-08-20 10:58:55,919 ps-radeon-hd7450-le DEBUG: Checking if machine is active
2013-08-20 10:58:55,919 ps-radeon-hd7450-le DEBUG: Checking if machine is provisioned
2013-08-20 10:58:55,921 ssh DEBUG: Traceback (most recent call last):
  File "/usr/lib/python2.7/dist-packages/utah/provisioning/ssh.py", line 250, in downloadfiles
    key_filename=config.sshprivatekey)
  File "/usr/lib/python2.7/dist-packages/paramiko/client.py", line 296, in connect
    sock.connect(addr)
  File "/usr/lib/python2.7/socket.py", line 224, in meth
    return getattr(self._sock,name)(*args)
error: [Errno 111] Connection refused

2013-08-20 10:58:55,921 root ERROR: Test log not retrieved
2013-08-20 10:58:55,921 cleanup DEBUG: Running cleanup
2013-08-20 10:58:55,922 cleanup DEBUG: Running: release(machine=<utah.provisioning.baremetal.cobbler.CobblerMachine object at 0x20c85d0>)
2013-08-20 10:58:55,922 root DEBUG: Executing SQL statement: UPDATE machines SET state='available' WHERE name=?, [u'ps-radeon-hd7450-le']
2013-08-20 10:58:56,073 cleanup DEBUG: Running: _removenfs()
2013-08-20 10:58:56,073 cleanup DEBUG: Running: _cleanupcobbler()
2013-08-20 10:58:56,073 ps-radeon-hd7450-le DEBUG: Removing old system
2013-08-20 10:58:56,077 root DEBUG: Running command: sudo cobbler system remove --name=ps-radeon-hd7450-le
2013-08-20 10:58:56,077 root DEBUG: Output follows:
2013-08-20 10:58:56,419 root DEBUG: 
2013-08-20 10:58:56,420 root DEBUG: Return code: 0
2013-08-20 10:58:56,420 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:58:56 2013 - DEBUG | REMOTE expiring token; user(<DIRECT>)
Tue Aug 20 10:58:56 2013 - DEBUG | REMOTE expiring token; user(<DIRECT>)
Tue Aug 20 10:58:56 2013 - DEBUG | REMOTE expiring token; user(<DIRECT>)
Tue Aug 20 10:58:56 2013 - DEBUG | REMOTE expiring token; user(<DIRECT>)
Tue Aug 20 10:58:56 2013 - DEBUG | REMOTE expiring token; user(<DIRECT>)
Tue Aug 20 10:58:56 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:58:56 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:58:56 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:58:56 2013 - INFO | REMOTE remove_item (system, recursive=True); user(<DIRECT>); name(ps-radeon-hd7450-le)
Tue Aug 20 10:58:56 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:58:56 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:58:56 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:58:56 2013 - INFO | remove_item(system); ['ps-radeon-hd7450-le']
Tue Aug 20 10:58:56 2013 - DEBUG | get_items; ['system']
Tue Aug 20 10:58:56 2013 - DEBUG | done with get_items; ['system']

2013-08-20 10:58:56,420 ps-radeon-hd7450-le INFO: Removing old profile
2013-08-20 10:58:56,423 root DEBUG: Running command: sudo cobbler profile remove --name=ps-radeon-hd7450-le-x86_64
2013-08-20 10:58:56,423 root DEBUG: Output follows:
2013-08-20 10:58:56,755 root DEBUG: 
2013-08-20 10:58:56,756 root DEBUG: Return code: 0
2013-08-20 10:58:56,756 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:58:56 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:58:56 2013 - DEBUG | get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:58:56 2013 - DEBUG | done with get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:58:56 2013 - INFO | REMOTE remove_item (profile, recursive=True); user(<DIRECT>); name(ps-radeon-hd7450-le-x86_64)
Tue Aug 20 10:58:56 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:58:56 2013 - DEBUG | get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:58:56 2013 - DEBUG | done with get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:58:56 2013 - INFO | remove_item(profile); ['ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:58:56 2013 - DEBUG | get_items; ['profile']
Tue Aug 20 10:58:56 2013 - DEBUG | done with get_items; ['profile']
Tue Aug 20 10:58:56 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:58:56 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:58:56 2013 - DEBUG | get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:58:56 2013 - DEBUG | done with get_item; ['system', 'ps-radeon-hd7450-le']
Tue Aug 20 10:58:56 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:58:56 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:58:56 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:58:56 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:58:56 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:58:56 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:58:56 2013 - WARNING | warning: kernel option length exceeds 255
Tue Aug 20 10:58:56 2013 - WARNING | warning: kernel option length exceeds 255

2013-08-20 10:58:56,756 ps-radeon-hd7450-le INFO: Removing old distro
2013-08-20 10:58:56,760 root DEBUG: Running command: sudo cobbler distro remove --name=ps-radeon-hd7450-le-x86_64
2013-08-20 10:58:56,760 root DEBUG: Output follows:
2013-08-20 10:58:57,102 root DEBUG: 
2013-08-20 10:58:57,102 root DEBUG: Return code: 0
2013-08-20 10:58:57,103 ps-radeon-hd7450-le DEBUG: Cobbler log follows:
Tue Aug 20 10:58:56 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:58:56 2013 - DEBUG | get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:58:56 2013 - DEBUG | done with get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:58:56 2013 - INFO | REMOTE remove_item (distro, recursive=True); user(<DIRECT>); name(ps-radeon-hd7450-le-x86_64)
Tue Aug 20 10:58:56 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Tue Aug 20 10:58:56 2013 - DEBUG | get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:58:56 2013 - DEBUG | done with get_item; ['distro', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:58:56 2013 - INFO | remove_item(distro); ['ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:58:56 2013 - DEBUG | get_items; ['distro']
Tue Aug 20 10:58:56 2013 - DEBUG | done with get_items; ['distro']
Tue Aug 20 10:58:56 2013 - DEBUG | get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']
Tue Aug 20 10:58:56 2013 - DEBUG | done with get_item; ['profile', 'ps-radeon-hd7450-le-x86_64']

2013-08-20 10:58:57,126 cleanup DEBUG: Recursively Removing directory /tmp/ps-radeon-hd7450-le_VhVAF0
2013-08-20 10:58:57,174 cleanup DEBUG: Running cleanup