full debug log

2013-08-14 17:56:40,770 root INFO: UTAH Features:
 PROVISIONED_AUTORUN: False
2013-08-14 17:56:40,770 root INFO: UTAH version: 0.14.8ubuntu1~precise1
2013-08-14 17:56:40,770 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-intel-3000/workspace/openarena-benchmark-ps-intel-3000/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-intel-3000/workspace/openarena-benchmark-ps-intel-3000/gaueko.log", 
    "logpath": "/var/lib/jenkins/ps-slaves/ps-intel-3000/workspace/openarena-benchmark-ps-intel-3000", 
    "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-intel-3000/workspace/openarena-benchmark-ps-intel-3000/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-14 17:56:40,770 iso INFO: Preparing image: /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-14 17:56:40,775 iso INFO: /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso is locally available as /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-14 17:56:40,775 iso INFO: Getting image type of /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-14 17:56:40,775 iso DEBUG: bsdtar list command: bsdtar -t -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-14 17:56:40,780 iso INFO: Image type is: desktop
2013-08-14 17:56:40,780 iso DEBUG: Using normal image
2013-08-14 17:56:40,781 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso ./.disk/info
2013-08-14 17:56:40,784 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O .disk/info
2013-08-14 17:56:40,788 iso INFO: Arch is: amd64
2013-08-14 17:56:40,788 iso INFO: Series is saucy
2013-08-14 17:56:40,788 root DEBUG: Executing SQL statement: SELECT COUNT(*) FROM machines, []
2013-08-14 17:56:40,789 root DEBUG: Executing SQL statement: SELECT * FROM machines WHERE name=?, ['ps-intel-3000']
2013-08-14 17:56:40,789 root DEBUG: Executing SQL statement: UPDATE machines SET pid=?, state='provisioned' WHERE machineid=? AND state=?, [16291, 9, u'available']
2013-08-14 17:56:40,983 ps-intel-3000 INFO: Preparing preseed: /data/jenkins/ps-slaves/ps-intel-3000/workspace/openarena-benchmark-ps-intel-3000/ps-qa-tools/preseeds/private-preseed.cfg
2013-08-14 17:56:40,984 ps-intel-3000 DEBUG: /data/jenkins/ps-slaves/ps-intel-3000/workspace/openarena-benchmark-ps-intel-3000/ps-qa-tools/preseeds/private-preseed.cfg is locally available as /data/jenkins/ps-slaves/ps-intel-3000/workspace/openarena-benchmark-ps-intel-3000/ps-qa-tools/preseeds/private-preseed.cfg
2013-08-14 17:56:40,984 ps-intel-3000 INFO: Preparing xml: /etc/utah/default-vm.xml
2013-08-14 17:56:40,984 ps-intel-3000 DEBUG: /etc/utah/default-vm.xml is locally available as /etc/utah/default-vm.xml
2013-08-14 17:56:40,984 ps-intel-3000 DEBUG: Machine init finished
2013-08-14 17:56:40,984 ps-intel-3000 INFO: Adding needed command line options
2013-08-14 17:56:40,986 rsyslog INFO: rsylogd port(60534) opened
2013-08-14 17:56:40,986 ps-intel-3000 INFO: Adding boot option: automatic-ubiquity
2013-08-14 17:56:40,986 ps-intel-3000 INFO: Adding boot option: noprompt
2013-08-14 17:56:40,986 ps-intel-3000 INFO: Adding boot option: netcfg/get_hostname=ps-intel-3000
2013-08-14 17:56:40,986 ps-intel-3000 INFO: Adding boot option: log_host=10.97.2.12
2013-08-14 17:56:40,987 ps-intel-3000 INFO: Adding boot option: log_port=60534
2013-08-14 17:56:40,987 ps-intel-3000 INFO: Adding boot option: boot=casper
2013-08-14 17:56:40,987 ps-intel-3000 INFO: Adding boot option: keyboard-configuration/layoutcode=us
2013-08-14 17:56:40,987 ps-intel-3000 INFO: Boot command line is: automatic-ubiquity noprompt netcfg/get_hostname=ps-intel-3000 log_host=10.97.2.12 log_port=60534 boot=casper keyboard-configuration/layoutcode=us
2013-08-14 17:56:40,987 root DEBUG: Running command: sudo service nfs-kernel-server status
2013-08-14 17:56:40,987 root DEBUG: Output follows:
2013-08-14 17:56:41,008 root DEBUG: nfsd running
2013-08-14 17:56:41,009 root DEBUG: 
2013-08-14 17:56:41,009 root DEBUG: Return code: 0
2013-08-14 17:56:41,009 ps-intel-3000 DEBUG: Cobbler arch is x86_64
2013-08-14 17:56:41,009 ps-intel-3000 DEBUG: Cobbler machine init finished
2013-08-14 17:56:41,009 ps-intel-3000 INFO: Installing client deb on machine
2013-08-14 17:56:41,791 ps-intel-3000 DEBUG: Checking if machine is active
2013-08-14 17:56:41,792 ps-intel-3000 DEBUG: Checking if machine is provisioned
2013-08-14 17:56:41,792 ps-intel-3000 INFO: Mounting image
2013-08-14 17:56:41,792 ps-intel-3000 DEBUG: Mounting ISO
2013-08-14 17:56:41,792 root DEBUG: Running command: sudo mount -o loop /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso /tmp/ps-intel-3000_JlyzCC/iso.d
2013-08-14 17:56:41,792 root DEBUG: Output follows:
2013-08-14 17:56:41,802 root DEBUG: mount: warning: /tmp/ps-intel-3000_JlyzCC/iso.d seems to be mounted read-only.
2013-08-14 17:56:41,802 root DEBUG: 
2013-08-14 17:56:41,802 root DEBUG: Return code: 0
2013-08-14 17:56:41,802 ps-intel-3000 INFO: Preparing kernel
2013-08-14 17:56:41,803 ps-intel-3000 INFO: Unpacking kernel from image
2013-08-14 17:56:41,803 iso DEBUG: Getting kernel for desktop image
2013-08-14 17:56:41,803 iso DEBUG: bsdtar list command: bsdtar -t -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-14 17:56:41,808 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso isolinux/txt.cfg
2013-08-14 17:56:41,813 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O isolinux/txt.cfg
2013-08-14 17:56:41,817 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-14 17:56:41,817 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-14 17:56:41,817 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-14 17:56:41,817 iso DEBUG: Rejecting memtest kernel
2013-08-14 17:56:41,817 iso DEBUG: bsdtar list command: bsdtar -t -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-14 17:56:41,822 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso isolinux/rqtxt.cfg
2013-08-14 17:56:41,826 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O isolinux/rqtxt.cfg
2013-08-14 17:56:41,830 iso DEBUG: Found kernel: install/vmlinuz
2013-08-14 17:56:41,830 iso DEBUG: bsdtar list command: bsdtar -t -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-14 17:56:41,836 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso boot/grub/grub.cfg
2013-08-14 17:56:41,840 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O boot/grub/grub.cfg
2013-08-14 17:56:41,844 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-14 17:56:41,844 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-14 17:56:41,844 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-14 17:56:41,844 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-14 17:56:41,844 iso DEBUG: bsdtar list command: bsdtar -t -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso
2013-08-14 17:56:41,849 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso boot/grub/loopback.cfg
2013-08-14 17:56:41,854 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O boot/grub/loopback.cfg
2013-08-14 17:56:41,858 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-14 17:56:41,858 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-14 17:56:41,858 iso DEBUG: Found kernel: casper/vmlinuz.efi
2013-08-14 17:56:41,858 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso casper/vmlinuz.efi
2013-08-14 17:56:41,862 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O casper/vmlinuz.efi
2013-08-14 17:56:41,874 ps-intel-3000 INFO: Preparing initrd
2013-08-14 17:56:41,874 ps-intel-3000 INFO: Unpacking initrd from image
2013-08-14 17:56:41,874 ps-intel-3000 DEBUG: Desktop image detected
2013-08-14 17:56:41,875 iso DEBUG: bsdtar list command: bsdtar -t -v -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso ./casper/initrd.lz
2013-08-14 17:56:41,879 iso DEBUG: bsdtar extract command: bsdtar -x -f /data/jenkins/Isos/saucy/saucy-desktop-amd64.iso -O casper/initrd.lz
2013-08-14 17:56:41,916 ps-intel-3000 INFO: Unpacking initrd
2013-08-14 17:56:41,916 ps-intel-3000 DEBUG: Using lzma based on file extension
2013-08-14 17:56:43,547 ps-intel-3000 INFO: Copying ssh public key
2013-08-14 17:56:43,548 ps-intel-3000 INFO: Creating latecommand scripts
2013-08-14 17:56:43,559 ps-intel-3000 INFO: Configuring latecommand for desktop
2013-08-14 17:56:43,559 ps-intel-3000 INFO: Setting up preseed
2013-08-14 17:56:43,581 ps-intel-3000 INFO: Changing d-i latecommand to ubiquity success_command and prepending ubiquity lines
2013-08-14 17:56:43,583 ps-intel-3000 INFO: Rewriting username to jenkins
2013-08-14 17:56:43,583 ps-intel-3000 DEBUG: Adding ubiquity/failure_command question with log messsage
2013-08-14 17:56:43,584 ps-intel-3000 INFO: Inserting preseed into casper
2013-08-14 17:56:43,584 root DEBUG: Running command: sed -i 1i/scripts/casper-bottom/utah\n[ -e /conf/param.conf ] && . /conf/param.conf /tmp/ps-intel-3000_JlyzCC/initrd.d/scripts/casper-bottom/ORDER
2013-08-14 17:56:43,584 root DEBUG: Output follows:
2013-08-14 17:56:43,588 root DEBUG: 
2013-08-14 17:56:43,588 root DEBUG: Return code: 0
2013-08-14 17:56:43,589 ps-intel-3000 INFO: Creating rsyslog config file
2013-08-14 17:56:43,591 ps-intel-3000 INFO: Repacking initrd
2013-08-14 17:56:43,591 ps-intel-3000 DEBUG: Using lzma because installtype is desktop
2013-08-14 17:57:29,861 ps-intel-3000 INFO: Setting up system with cobbler
2013-08-14 17:57:29,862 ps-intel-3000 DEBUG: Removing old system
2013-08-14 17:57:29,865 root DEBUG: Running command: sudo cobbler system remove --name=ps-intel-3000
2013-08-14 17:57:29,865 root DEBUG: Output follows:
2013-08-14 17:57:30,026 root DEBUG: internal error, unknown system name ps-intel-3000
2013-08-14 17:57:30,031 root DEBUG: 
2013-08-14 17:57:30,031 root DEBUG: Return code: 0
2013-08-14 17:57:30,032 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE expiring token; user(<DIRECT>)
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:57:30 2013 - INFO | Exception occured: <class 'cobbler.cexceptions.CX'>
Wed Aug 14 17:57:30 2013 - INFO | Exception value: 'internal error, unknown system name ps-intel-3000'
Wed Aug 14 17:57:30 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-14 17:57:30,032 ps-intel-3000 INFO: Removing old profile
2013-08-14 17:57:30,034 root DEBUG: Running command: sudo cobbler profile remove --name=ps-intel-3000-x86_64
2013-08-14 17:57:30,034 root DEBUG: Output follows:
2013-08-14 17:57:30,193 root DEBUG: internal error, unknown profile name ps-intel-3000-x86_64
2013-08-14 17:57:30,198 root DEBUG: 
2013-08-14 17:57:30,198 root DEBUG: Return code: 0
2013-08-14 17:57:30,199 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - DEBUG | get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - INFO | Exception occured: <class 'cobbler.cexceptions.CX'>
Wed Aug 14 17:57:30 2013 - INFO | Exception value: 'internal error, unknown profile name ps-intel-3000-x86_64'
Wed Aug 14 17:57:30 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-14 17:57:30,199 ps-intel-3000 INFO: Removing old distro
2013-08-14 17:57:30,201 root DEBUG: Running command: sudo cobbler distro remove --name=ps-intel-3000-x86_64
2013-08-14 17:57:30,201 root DEBUG: Output follows:
2013-08-14 17:57:30,361 root DEBUG: internal error, unknown distro name ps-intel-3000-x86_64
2013-08-14 17:57:30,366 root DEBUG: 
2013-08-14 17:57:30,366 root DEBUG: Return code: 0
2013-08-14 17:57:30,366 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - DEBUG | get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - INFO | Exception occured: <class 'cobbler.cexceptions.CX'>
Wed Aug 14 17:57:30 2013 - INFO | Exception value: 'internal error, unknown distro name ps-intel-3000-x86_64'
Wed Aug 14 17:57:30 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-14 17:57:30,366 ps-intel-3000 INFO: Creating distro
2013-08-14 17:57:30,368 root DEBUG: Running command: sudo cobbler distro add --name=ps-intel-3000-x86_64 --kernel=/tmp/ps-intel-3000_JlyzCC/kernel --initrd=/tmp/ps-intel-3000_JlyzCC/initrd.lz
2013-08-14 17:57:30,369 root DEBUG: Output follows:
2013-08-14 17:57:30,600 root DEBUG: 
2013-08-14 17:57:30,600 root DEBUG: Return code: 0
2013-08-14 17:57:30,601 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - DEBUG | get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - INFO | Exception occured: <class 'cobbler.cexceptions.CX'>
Wed Aug 14 17:57:30 2013 - INFO | Exception value: 'internal error, unknown distro name ps-intel-3000-x86_64'
Wed Aug 14 17:57:30 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))

Wed Aug 14 17:57:30 2013 - INFO | REMOTE new_item(distro); user(<DIRECT>)
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(___NEW___distro::sDpPl/AysFbd2URTcXNsKTrgStPbBO/tZA==); attribute(kernel)
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(___NEW___distro::sDpPl/AysFbd2URTcXNsKTrgStPbBO/tZA==); attribute(initrd)
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(___NEW___distro::sDpPl/AysFbd2URTcXNsKTrgStPbBO/tZA==); attribute(name)
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(___NEW___distro::sDpPl/AysFbd2URTcXNsKTrgStPbBO/tZA==); attribute(in_place)
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | REMOTE save_item(distro); user(<DIRECT>); object_id(___NEW___distro::sDpPl/AysFbd2URTcXNsKTrgStPbBO/tZA==)
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | add_item(distro); ['ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | get_items; ['distro']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_items; ['distro']
Wed Aug 14 17:57:30 2013 - INFO | trying hardlink /tmp/ps-intel-3000_JlyzCC/kernel -> /var/www/cobbler/images/ps-intel-3000-x86_64/kernel
Wed Aug 14 17:57:30 2013 - INFO | trying hardlink /tmp/ps-intel-3000_JlyzCC/initrd.lz -> /var/www/cobbler/images/ps-intel-3000-x86_64/initrd.lz
Wed Aug 14 17:57:30 2013 - INFO | trying hardlink /tmp/ps-intel-3000_JlyzCC/kernel -> /var/lib/tftpboot/images/ps-intel-3000-x86_64/kernel
Wed Aug 14 17:57:30 2013 - INFO | trying hardlink /tmp/ps-intel-3000_JlyzCC/initrd.lz -> /var/lib/tftpboot/images/ps-intel-3000-x86_64/initrd.lz
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255

2013-08-14 17:57:30,601 ps-intel-3000 INFO: Creating profile
2013-08-14 17:57:30,603 root DEBUG: Running command: sudo cobbler profile add --name=ps-intel-3000-x86_64 --distro=ps-intel-3000-x86_64
2013-08-14 17:57:30,604 root DEBUG: Output follows:
2013-08-14 17:57:30,807 root DEBUG: 
2013-08-14 17:57:30,808 root DEBUG: Return code: 0
2013-08-14 17:57:30,808 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - DEBUG | get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - INFO | Exception occured: <class 'cobbler.cexceptions.CX'>
Wed Aug 14 17:57:30 2013 - INFO | Exception value: 'internal error, unknown profile name ps-intel-3000-x86_64'
Wed Aug 14 17:57:30 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))

Wed Aug 14 17:57:30 2013 - INFO | REMOTE new_item(profile); user(<DIRECT>)
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | REMOTE modify_item(profile); user(<DIRECT>); object_id(___NEW___profile::mLMp4jX5cN54D8A8B2qDUkl8o71tABsG/Q==); attribute(in_place)
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | REMOTE modify_item(profile); user(<DIRECT>); object_id(___NEW___profile::mLMp4jX5cN54D8A8B2qDUkl8o71tABsG/Q==); attribute(name)
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | REMOTE modify_item(profile); user(<DIRECT>); object_id(___NEW___profile::mLMp4jX5cN54D8A8B2qDUkl8o71tABsG/Q==); attribute(distro)
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | REMOTE save_item(profile); user(<DIRECT>); object_id(___NEW___profile::mLMp4jX5cN54D8A8B2qDUkl8o71tABsG/Q==)
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | add_item(profile); ['ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | get_items; ['profile']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_items; ['profile']
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:30 2013 - WARNING | warning: kernel option length exceeds 255

2013-08-14 17:57:30,808 ps-intel-3000 INFO: Setting up NFS for desktop install
2013-08-14 17:57:30,808 ps-intel-3000 DEBUG: Adding export to NFS config file
2013-08-14 17:57:30,816 ps-intel-3000 DEBUG: Reloading NFS config
2013-08-14 17:57:30,816 root DEBUG: Running command: sudo service nfs-kernel-server reload
2013-08-14 17:57:30,816 root DEBUG: Output follows:
2013-08-14 17:57:30,828 root DEBUG: * Re-exporting directories for NFS kernel daemon...
2013-08-14 17:57:30,829 root DEBUG: ...done.
2013-08-14 17:57:30,830 root DEBUG: 
2013-08-14 17:57:30,830 root DEBUG: Return code: 0
2013-08-14 17:57:30,830 ps-intel-3000 DEBUG: Adding NFS boot options
2013-08-14 17:57:30,831 ps-intel-3000 INFO: Adding kernel boot options to distro
2013-08-14 17:57:30,835 root DEBUG: Running command: sudo cobbler distro edit --name=ps-intel-3000-x86_64 --kopts=automatic-ubiquity noprompt netcfg/get_hostname=ps-intel-3000 log_host=10.97.2.12 log_port=60534 boot=casper keyboard-configuration/layoutcode=us root=/dev/nfs netboot=nfs nfsroot=10.97.2.12:/tmp/ps-intel-3000_JlyzCC/iso.d
2013-08-14 17:57:30,835 root DEBUG: Output follows:
2013-08-14 17:57:31,069 root DEBUG: 
2013-08-14 17:57:31,069 root DEBUG: Return code: 0
2013-08-14 17:57:31,069 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - DEBUG | get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(distro::ps-intel-3000-x86_64); attribute(in_place)
Wed Aug 14 17:57:30 2013 - DEBUG | get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(distro::ps-intel-3000-x86_64); attribute(name)
Wed Aug 14 17:57:30 2013 - DEBUG | get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | REMOTE modify_item(distro); user(<DIRECT>); object_id(distro::ps-intel-3000-x86_64); attribute(kernel_options)
Wed Aug 14 17:57:30 2013 - DEBUG | get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | REMOTE save_item(distro); user(<DIRECT>); object_id(distro::ps-intel-3000-x86_64)
Wed Aug 14 17:57:30 2013 - DEBUG | get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:30 2013 - INFO | add_item(distro); ['ps-intel-3000-x86_64']
Wed Aug 14 17:57:30 2013 - DEBUG | get_items; ['distro']
Wed Aug 14 17:57:30 2013 - DEBUG | done with get_items; ['distro']
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255

2013-08-14 17:57:31,070 ps-intel-3000 INFO: Adding kickstart to profile
2013-08-14 17:57:31,073 root DEBUG: Running command: sudo cobbler profile edit --name=ps-intel-3000-x86_64 --kickstart=/tmp/ps-intel-3000_JlyzCC/initrd.d/preseed.cfg
2013-08-14 17:57:31,073 root DEBUG: Output follows:
2013-08-14 17:57:31,275 root DEBUG: 
2013-08-14 17:57:31,275 root DEBUG: Return code: 0
2013-08-14 17:57:31,275 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - DEBUG | get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:31 2013 - DEBUG | done with get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(profile); user(<DIRECT>); object_id(profile::ps-intel-3000-x86_64); attribute(in_place)
Wed Aug 14 17:57:31 2013 - DEBUG | get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:31 2013 - DEBUG | done with get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(profile); user(<DIRECT>); object_id(profile::ps-intel-3000-x86_64); attribute(kickstart)
Wed Aug 14 17:57:31 2013 - DEBUG | get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:31 2013 - DEBUG | done with get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(profile); user(<DIRECT>); object_id(profile::ps-intel-3000-x86_64); attribute(name)
Wed Aug 14 17:57:31 2013 - DEBUG | get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:31 2013 - DEBUG | done with get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE save_item(profile); user(<DIRECT>); object_id(profile::ps-intel-3000-x86_64)
Wed Aug 14 17:57:31 2013 - DEBUG | get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:31 2013 - DEBUG | done with get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | add_item(profile); ['ps-intel-3000-x86_64']
Wed Aug 14 17:57:31 2013 - DEBUG | get_items; ['profile']
Wed Aug 14 17:57:31 2013 - DEBUG | done with get_items; ['profile']
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255

2013-08-14 17:57:31,275 ps-intel-3000 INFO: Adding system
2013-08-14 17:57:31,279 root DEBUG: Running command: sudo cobbler system add --name=ps-intel-3000 --profile=ps-intel-3000-x86_64 --netboot-enabled=Y --power-id=PS_Intel_3000 --power-type=sentryswitch_cdu --power-pass=ubuntu --power-address=10.97.0.15 --mac-address=44:37:e6:c0:8c:96 --power-user=ubuntu
2013-08-14 17:57:31,279 root DEBUG: Output follows:
2013-08-14 17:57:31,464 root DEBUG: 
2013-08-14 17:57:31,464 root DEBUG: Return code: 0
2013-08-14 17:57:31,464 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:57:31 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:57:31 2013 - INFO | Exception occured: <class 'cobbler.cexceptions.CX'>
Wed Aug 14 17:57:31 2013 - INFO | Exception value: 'internal error, unknown system name ps-intel-3000'
Wed Aug 14 17:57:31 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))

Wed Aug 14 17:57:31 2013 - INFO | REMOTE new_item(system); user(<DIRECT>)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::fs8z+pYax9BhEczdOhRwNF6ZkFI0hpwNzA==); attribute(profile)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::fs8z+pYax9BhEczdOhRwNF6ZkFI0hpwNzA==); attribute(name)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::fs8z+pYax9BhEczdOhRwNF6ZkFI0hpwNzA==); attribute(power_type)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::fs8z+pYax9BhEczdOhRwNF6ZkFI0hpwNzA==); attribute(power_id)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::fs8z+pYax9BhEczdOhRwNF6ZkFI0hpwNzA==); attribute(power_user)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::fs8z+pYax9BhEczdOhRwNF6ZkFI0hpwNzA==); attribute(power_address)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::fs8z+pYax9BhEczdOhRwNF6ZkFI0hpwNzA==); attribute(in_place)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::fs8z+pYax9BhEczdOhRwNF6ZkFI0hpwNzA==); attribute(power_pass)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::fs8z+pYax9BhEczdOhRwNF6ZkFI0hpwNzA==); attribute(netboot_enabled)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(___NEW___system::fs8z+pYax9BhEczdOhRwNF6ZkFI0hpwNzA==); attribute(modify_interface)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | find_items; ['system']
Wed Aug 14 17:57:31 2013 - INFO | REMOTE save_item(system); user(<DIRECT>); object_id(___NEW___system::fs8z+pYax9BhEczdOhRwNF6ZkFI0hpwNzA==)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | add_item(system); ['ps-intel-3000']
Wed Aug 14 17:57:31 2013 - DEBUG | get_items; ['system']
Wed Aug 14 17:57:31 2013 - DEBUG | done with get_items; ['system']
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - INFO | generating: /var/lib/tftpboot/pxelinux.cfg/01-44-37-e6-c0-8c-96
Wed Aug 14 17:57:31 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:57:31 2013 - INFO | generating: /var/lib/tftpboot/grub/01-44-37-E6-C0-8C-96

2013-08-14 17:57:31,464 ps-intel-3000 INFO: Restarting system
2013-08-14 17:57:31,464 ps-intel-3000 DEBUG: Stopping system
2013-08-14 17:57:31,468 root DEBUG: Running command: sudo cobbler system poweroff --name=ps-intel-3000
2013-08-14 17:57:31,468 root DEBUG: Output follows:
2013-08-14 17:57:34,666 root DEBUG: task started: 2013-08-14_175731_power
2013-08-14 17:57:34,666 root DEBUG: task started (id=Power management (off), time=Wed Aug 14 17:57:31 2013)
2013-08-14 17:57:34,666 root DEBUG: cobbler power configuration is:
2013-08-14 17:57:34,667 root DEBUG: type   : sentryswitch_cdu
2013-08-14 17:57:34,667 root DEBUG: address: 10.97.0.15
2013-08-14 17:57:34,667 root DEBUG: user   : ubuntu
2013-08-14 17:57:34,667 root DEBUG: id     : PS_Intel_3000
2013-08-14 17:57:34,667 root DEBUG: - fence_cdu -a "10.97.0.15" -n "PS_Intel_3000" -l "ubuntu" -p "ubuntu" -o "off"
2013-08-14 17:57:34,667 root DEBUG: running: ['/bin/sh', '-c', u'fence_cdu -a "10.97.0.15" -n "PS_Intel_3000" -l "ubuntu" -p "ubuntu" -o "off"']
2013-08-14 17:57:34,667 root DEBUG: received on stdout: Success: Powered OFF
2013-08-14 17:57:34,667 root DEBUG: 
2013-08-14 17:57:34,667 root DEBUG: received on stderr:
2013-08-14 17:57:34,667 root DEBUG: *** TASK COMPLETE ***
2013-08-14 17:57:34,673 root DEBUG: 
2013-08-14 17:57:34,673 root DEBUG: Return code: 0
2013-08-14 17:57:34,673 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:31 2013 - INFO | REMOTE start_task(Power management (off)); event_id(2013-08-14_175731_power); user(?)
Wed Aug 14 17:57:32 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:57:32 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:57:32 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:57:32 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:57:32 2013 - DEBUG | REMOTE CLI Authorized; user(?)

2013-08-14 17:57:34,673 ps-intel-3000 DEBUG: Waiting 15 seconds
2013-08-14 17:57:49,689 ps-intel-3000 DEBUG: Starting system
2013-08-14 17:57:49,692 root DEBUG: Running command: sudo cobbler system poweron --name=ps-intel-3000
2013-08-14 17:57:49,692 root DEBUG: Output follows:
2013-08-14 17:57:52,894 root DEBUG: task started: 2013-08-14_175749_power
2013-08-14 17:57:52,894 root DEBUG: task started (id=Power management (on), time=Wed Aug 14 17:57:49 2013)
2013-08-14 17:57:52,894 root DEBUG: cobbler power configuration is:
2013-08-14 17:57:52,894 root DEBUG: type   : sentryswitch_cdu
2013-08-14 17:57:52,894 root DEBUG: address: 10.97.0.15
2013-08-14 17:57:52,895 root DEBUG: user   : ubuntu
2013-08-14 17:57:52,895 root DEBUG: id     : PS_Intel_3000
2013-08-14 17:57:52,895 root DEBUG: - fence_cdu -a "10.97.0.15" -n "PS_Intel_3000" -l "ubuntu" -p "ubuntu" -o "on"
2013-08-14 17:57:52,895 root DEBUG: running: ['/bin/sh', '-c', u'fence_cdu -a "10.97.0.15" -n "PS_Intel_3000" -l "ubuntu" -p "ubuntu" -o "on"']
2013-08-14 17:57:52,895 root DEBUG: received on stdout: Success: Powered ON
2013-08-14 17:57:52,895 root DEBUG: 
2013-08-14 17:57:52,895 root DEBUG: received on stderr:
2013-08-14 17:57:52,895 root DEBUG: *** TASK COMPLETE ***
2013-08-14 17:57:52,900 root DEBUG: 
2013-08-14 17:57:52,900 root DEBUG: Return code: 0
2013-08-14 17:57:52,901 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 17:57:49 2013 - DEBUG | REMOTE expiring token; user(<DIRECT>)
Wed Aug 14 17:57:49 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:49 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:57:49 2013 - INFO | REMOTE start_task(Power management (on)); event_id(2013-08-14_175749_power); user(?)
Wed Aug 14 17:57:50 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:57:50 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:57:50 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:57:50 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:57:50 2013 - DEBUG | REMOTE CLI Authorized; user(?)

2013-08-14 17:57:52,901 rsyslog INFO: Waiting 8999s for: system started
2013-08-14 17:58:26,707 rsyslog INFO: Matched pattern '.*' for 'system started' message
2013-08-14 17:58:26,708 ps-intel-3000 INFO: install seems to have booted, disabling netboot
2013-08-14 17:58:26,711 root DEBUG: Running command: sudo cobbler system edit --name=ps-intel-3000 --netboot-enabled=N
2013-08-14 17:58:26,711 root DEBUG: Output follows:
2013-08-14 17:58:26,895 root DEBUG: 
2013-08-14 17:58:26,895 root DEBUG: Return code: 0
2013-08-14 17:58:26,895 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 17:58:26 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:58:26 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:58:26 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:58:26 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(system::ps-intel-3000); attribute(in_place)
Wed Aug 14 17:58:26 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:58:26 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:58:26 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:58:26 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(system::ps-intel-3000); attribute(netboot_enabled)
Wed Aug 14 17:58:26 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:58:26 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:58:26 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:58:26 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(system::ps-intel-3000); attribute(name)
Wed Aug 14 17:58:26 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:58:26 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:58:26 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:58:26 2013 - INFO | REMOTE modify_item(system); user(<DIRECT>); object_id(system::ps-intel-3000); attribute(modify_interface)
Wed Aug 14 17:58:26 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:58:26 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:58:26 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:58:26 2013 - INFO | REMOTE save_item(system); user(<DIRECT>); object_id(system::ps-intel-3000)
Wed Aug 14 17:58:26 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:58:26 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 17:58:26 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 17:58:26 2013 - INFO | add_item(system); ['ps-intel-3000']
Wed Aug 14 17:58:26 2013 - DEBUG | get_items; ['system']
Wed Aug 14 17:58:26 2013 - DEBUG | done with get_items; ['system']
Wed Aug 14 17:58:26 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:58:26 2013 - INFO | generating: /var/lib/tftpboot/pxelinux.cfg/01-44-37-e6-c0-8c-96
Wed Aug 14 17:58:26 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 17:58:26 2013 - INFO | generating: /var/lib/tftpboot/grub/01-44-37-E6-C0-8C-96

2013-08-14 17:58:26,895 rsyslog INFO: Waiting 8966s for: install started
2013-08-14 17:58:29,296 rsyslog INFO: Matched pattern '.*ubiquity\\[\\d+\\]: Ubiquity \\d' for 'install started' message
2013-08-14 17:58:29,297 rsyslog INFO: Waiting 8963s for: install complete
2013-08-14 18:03:33,712 rsyslog INFO: Matched pattern '.*log-output -t ubiquity umount /target$' for 'install complete' message
2013-08-14 18:03:33,712 rsyslog INFO: Waiting 599s for: system booted
2013-08-14 18:04:16,132 rsyslog WARNING: Expected pattern missed, matched future pattern: .*Machine UUID:\s+(.*)
2013-08-14 18:04:16,132 ssh INFO: Checking for ssh availability
2013-08-14 18:04:23,648 ps-intel-3000 INFO: Removing NFS share
2013-08-14 18:04:23,649 root DEBUG: Running command: sudo sed /\/tmp\/ps-intel-3000_JlyzCC\/iso.d/d -i /etc/exports
2013-08-14 18:04:23,649 root DEBUG: Output follows:
2013-08-14 18:04:23,658 root DEBUG: 
2013-08-14 18:04:23,658 root DEBUG: Return code: 0
2013-08-14 18:04:23,658 ps-intel-3000 DEBUG: Reloading NFS config
2013-08-14 18:04:23,658 root DEBUG: Running command: sudo service nfs-kernel-server reload
2013-08-14 18:04:23,658 root DEBUG: Output follows:
2013-08-14 18:04:23,670 root DEBUG: * Re-exporting directories for NFS kernel daemon...
2013-08-14 18:04:23,671 root DEBUG: ...done.
2013-08-14 18:04:23,671 root DEBUG: 
2013-08-14 18:04:23,672 root DEBUG: Return code: 0
2013-08-14 18:04:23,672 ps-intel-3000 INFO: Unmounting ISO
2013-08-14 18:04:23,672 root DEBUG: Running command: sudo umount /tmp/ps-intel-3000_JlyzCC/iso.d
2013-08-14 18:04:23,672 root DEBUG: Output follows:
2013-08-14 18:04:23,794 root DEBUG: 
2013-08-14 18:04:23,794 root DEBUG: Return code: 0
2013-08-14 18:04:23,794 ps-intel-3000 DEBUG: Removing directory
2013-08-14 18:04:23,794 ps-intel-3000 INFO: System installed
2013-08-14 18:04:29,004 ssh INFO: Uploading /usr/share/utah/python-jsonschema_1.3.0~ppa1_all.deb from the host to /tmp on the machine
2013-08-14 18:04:29,111 ps-intel-3000 DEBUG: Checking if machine is active
2013-08-14 18:04:29,111 ps-intel-3000 DEBUG: Checking if machine is provisioned
2013-08-14 18:04:29,111 ssh DEBUG: Connecting SSH
2013-08-14 18:04:34,618 ssh DEBUG: Opening SSH session
2013-08-14 18:04:34,650 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-14 18:04:38,057 ssh DEBUG: Closing SSH connection
2013-08-14 18:04:38,058 ssh DEBUG: Return code: 0
2013-08-14 18:04:38,058 ssh DEBUG: Standard output follows:
2013-08-14 18:04:38,058 ssh DEBUG: Selecting previously unselected package python-jsonschema.
2013-08-14 18:04:38,058 ssh DEBUG: (Reading database ... 165913 files and directories currently installed.)
2013-08-14 18:04:38,058 ssh DEBUG: Unpacking python-jsonschema (from .../python-jsonschema_1.3.0~ppa1_all.deb) ...
2013-08-14 18:04:38,058 ssh DEBUG: Setting up python-jsonschema (1.3.0~ppa1) ...
2013-08-14 18:04:38,058 ssh DEBUG: Standard error follows:
2013-08-14 18:04:38,059 ps-intel-3000 DEBUG: Checking if machine is active
2013-08-14 18:04:38,059 ps-intel-3000 DEBUG: Checking if machine is provisioned
2013-08-14 18:04:43,439 ssh INFO: Uploading /usr/share/utah/utah-common_0.14.8ubuntu1~precise1_all.deb from the host to /tmp on the machine
2013-08-14 18:04:43,543 ps-intel-3000 DEBUG: Checking if machine is active
2013-08-14 18:04:43,543 ps-intel-3000 DEBUG: Checking if machine is provisioned
2013-08-14 18:04:43,543 ssh DEBUG: Connecting SSH
2013-08-14 18:04:48,647 ssh DEBUG: Opening SSH session
2013-08-14 18:04:48,679 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-14 18:04:55,024 ssh DEBUG: Closing SSH connection
2013-08-14 18:04:55,025 ssh DEBUG: Return code: 0
2013-08-14 18:04:55,025 ssh DEBUG: Standard output follows:
2013-08-14 18:04:55,025 ssh DEBUG: Selecting previously unselected package utah-common.
2013-08-14 18:04:55,025 ssh DEBUG: (Reading database ... 165920 files and directories currently installed.)
2013-08-14 18:04:55,025 ssh DEBUG: Unpacking utah-common (from .../utah-common_0.14.8ubuntu1~precise1_all.deb) ...
2013-08-14 18:04:55,025 ssh DEBUG: Setting up utah-common (0.14.8ubuntu1~precise1) ...
2013-08-14 18:04:55,025 ssh DEBUG: Standard error follows:
2013-08-14 18:04:55,025 ps-intel-3000 DEBUG: Checking if machine is active
2013-08-14 18:04:55,026 ps-intel-3000 DEBUG: Checking if machine is provisioned
2013-08-14 18:04:57,919 ssh INFO: Uploading /usr/share/utah/utah-client_0.14.8ubuntu1~precise1_all.deb from the host to /tmp on the machine
2013-08-14 18:04:58,025 ps-intel-3000 DEBUG: Checking if machine is active
2013-08-14 18:04:58,025 ps-intel-3000 DEBUG: Checking if machine is provisioned
2013-08-14 18:04:58,025 ssh DEBUG: Connecting SSH
2013-08-14 18:04:58,157 ssh DEBUG: Opening SSH session
2013-08-14 18:04:58,164 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-14 18:05:14,371 ssh DEBUG: Closing SSH connection
2013-08-14 18:05:14,372 ssh DEBUG: Return code: 0
2013-08-14 18:05:14,372 ssh DEBUG: Standard output follows:
2013-08-14 18:05:14,372 ssh DEBUG: 0% [Working]                                                                    
0% [Connecting to archive.ubuntu.com]                                           
0% [Connecting to archive.ubuntu.com (91.189.91.14)]                            
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-14 18:05:14,372 ssh DEBUG: 6% [1 libyaml-0-2 14.2 kB/57.3 kB 24%]                                          
25% [Working]                                                                   
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-14 18:05:14,372 ssh DEBUG: 29% [2 python-psutil 7577 B/51.4 kB 14%]                                        
49% [Working]                                                                   
49% [Waiting for headers]                                                       
Get:3 http://archive.ubuntu.com/ubuntu/ saucy/main python-yaml amd64 3.10-4build2 [113 kB]
2013-08-14 18:05:14,372 ssh DEBUG: 54% [3 python-yaml 12.7 kB/113 kB 11%]                                          
100% [Working]                                                                  
Fetched 222 kB in 0s (0 B/s)
2013-08-14 18:05:14,372 ssh DEBUG: Selecting previously unselected package libyaml-0-2:amd64.
2013-08-14 18:05:14,373 ssh DEBUG: (Reading database ... 165929 files and directories currently installed.)
2013-08-14 18:05:14,373 ssh DEBUG: Unpacking libyaml-0-2:amd64 (from .../libyaml-0-2_0.1.4-2build1_amd64.deb) ...
2013-08-14 18:05:14,373 ssh DEBUG: Selecting previously unselected package python-psutil.
2013-08-14 18:05:14,373 ssh DEBUG: Unpacking python-psutil (from .../python-psutil_0.6.1-2ubuntu1_amd64.deb) ...
2013-08-14 18:05:14,373 ssh DEBUG: Selecting previously unselected package python-yaml.
2013-08-14 18:05:14,373 ssh DEBUG: Unpacking python-yaml (from .../python-yaml_3.10-4build2_amd64.deb) ...
2013-08-14 18:05:14,373 ssh DEBUG: Setting up libyaml-0-2:amd64 (0.1.4-2build1) ...
2013-08-14 18:05:14,373 ssh DEBUG: Setting up python-psutil (0.6.1-2ubuntu1) ...
2013-08-14 18:05:14,373 ssh DEBUG: Setting up python-yaml (3.10-4build2) ...
2013-08-14 18:05:14,373 ssh DEBUG: Processing triggers for libc-bin ...
2013-08-14 18:05:14,373 ssh DEBUG: Selecting previously unselected package utah-client.
2013-08-14 18:05:14,374 ssh DEBUG: (Reading database ... 166027 files and directories currently installed.)
2013-08-14 18:05:14,374 ssh DEBUG: Unpacking utah-client (from .../utah-client_0.14.8ubuntu1~precise1_all.deb) ...
2013-08-14 18:05:14,374 ssh DEBUG: Setting up utah-client (0.14.8ubuntu1~precise1) ...
2013-08-14 18:05:14,374 ssh DEBUG: Processing triggers for man-db ...
2013-08-14 18:05:14,374 ssh DEBUG: Fetched 222 kB in 0s (0 B/s)
2013-08-14 18:05:14,374 ssh DEBUG: Standard error follows:
2013-08-14 18:05:14,374 ps-intel-3000 DEBUG: Checking if machine is active
2013-08-14 18:05:14,374 ps-intel-3000 DEBUG: Checking if machine is provisioned
2013-08-14 18:05:14,548 ssh INFO: Uploading /data/jenkins/ps-slaves/ps-intel-3000/workspace/openarena-benchmark-ps-intel-3000/master.run from the host to /tmp on the machine
2013-08-14 18:05:14,652 ps-intel-3000 DEBUG: Checking if machine is active
2013-08-14 18:05:14,652 ps-intel-3000 DEBUG: Checking if machine is provisioned
2013-08-14 18:05:14,652 ssh DEBUG: Connecting SSH
2013-08-14 18:05:14,755 ssh DEBUG: Opening SSH session
2013-08-14 18:05:14,819 ssh INFO: Running command through SSH: utah -f yaml -r /tmp/master.run -o /var/lib/utah/utah.out
2013-08-14 18:07:06,984 ssh DEBUG: Closing SSH connection
2013-08-14 18:07:06,985 ssh WARNING: SSH command (utah -f yaml -r /tmp/master.run -o /var/lib/utah/utah.out) failed with return code: 3
2013-08-14 18:07:06,985 ssh DEBUG: Standard output follows:
2013-08-14 18:07:06,985 ssh DEBUG: Standard error follows:
2013-08-14 18:07:06,985 root INFO: System under test is rebooting
2013-08-14 18:07:06,986 root INFO: Checking if UTAH client is finished
2013-08-14 18:07:06,986 ps-intel-3000 DEBUG: Checking if machine is active
2013-08-14 18:07:06,986 ps-intel-3000 DEBUG: Checking if machine is provisioned
2013-08-14 18:07:06,986 ps-intel-3000 DEBUG: Checking if machine is active
2013-08-14 18:07:06,986 ps-intel-3000 DEBUG: Checking if machine is provisioned
2013-08-14 18:07:06,986 ssh DEBUG: Connecting SSH
2013-08-14 18:07:07,422 ssh DEBUG: Opening SSH session
2013-08-14 18:07:07,554 ssh INFO: Running command through SSH: /usr/share/utah/client/utah-done.py
2013-08-14 18:07:07,594 ssh DEBUG: Closing SSH connection
2013-08-14 18:07:07,594 ssh DEBUG: SSH command (/usr/share/utah/client/utah-done.py) failed with return code: -1
2013-08-14 18:07:07,594 ssh DEBUG: Standard output follows:
2013-08-14 18:07:07,594 ssh DEBUG: Standard error follows:
2013-08-14 18:07:07,594 root INFO: UTAH client is finished
2013-08-14 18:07:07,594 ps-intel-3000 DEBUG: Checking if machine is active
2013-08-14 18:07:07,594 ps-intel-3000 DEBUG: Checking if machine is provisioned
2013-08-14 18:07:07,597 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-14 18:07:07,597 root ERROR: Test log not retrieved
2013-08-14 18:07:07,597 cleanup DEBUG: Running cleanup
2013-08-14 18:07:07,597 cleanup DEBUG: Running: release(machine=<utah.provisioning.baremetal.cobbler.CobblerMachine object at 0x310b5d0>)
2013-08-14 18:07:07,597 root DEBUG: Executing SQL statement: UPDATE machines SET state='available' WHERE name=?, [u'ps-intel-3000']
2013-08-14 18:07:07,794 cleanup DEBUG: Running: _removenfs()
2013-08-14 18:07:07,794 cleanup DEBUG: Running: _cleanupcobbler()
2013-08-14 18:07:07,794 ps-intel-3000 DEBUG: Removing old system
2013-08-14 18:07:07,797 root DEBUG: Running command: sudo cobbler system remove --name=ps-intel-3000
2013-08-14 18:07:07,797 root DEBUG: Output follows:
2013-08-14 18:07:08,142 root DEBUG: 
2013-08-14 18:07:08,143 root DEBUG: Return code: 0
2013-08-14 18:07:08,143 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 18:07:07 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 18:07:07 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 18:07:07 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 18:07:07 2013 - INFO | REMOTE remove_item (system, recursive=True); user(<DIRECT>); name(ps-intel-3000)
Wed Aug 14 18:07:07 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 18:07:07 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 18:07:07 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 18:07:07 2013 - INFO | remove_item(system); ['ps-intel-3000']
Wed Aug 14 18:07:07 2013 - DEBUG | get_items; ['system']
Wed Aug 14 18:07:07 2013 - DEBUG | done with get_items; ['system']

2013-08-14 18:07:08,143 ps-intel-3000 INFO: Removing old profile
2013-08-14 18:07:08,146 root DEBUG: Running command: sudo cobbler profile remove --name=ps-intel-3000-x86_64
2013-08-14 18:07:08,146 root DEBUG: Output follows:
2013-08-14 18:07:08,486 root DEBUG: 
2013-08-14 18:07:08,486 root DEBUG: Return code: 0
2013-08-14 18:07:08,486 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 18:07:08 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 18:07:08 2013 - DEBUG | get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 18:07:08 2013 - DEBUG | done with get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 18:07:08 2013 - INFO | REMOTE remove_item (profile, recursive=True); user(<DIRECT>); name(ps-intel-3000-x86_64)
Wed Aug 14 18:07:08 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 18:07:08 2013 - DEBUG | get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 18:07:08 2013 - DEBUG | done with get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 18:07:08 2013 - INFO | remove_item(profile); ['ps-intel-3000-x86_64']
Wed Aug 14 18:07:08 2013 - DEBUG | get_items; ['profile']
Wed Aug 14 18:07:08 2013 - DEBUG | done with get_items; ['profile']
Wed Aug 14 18:07:08 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 18:07:08 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 18:07:08 2013 - DEBUG | get_item; ['system', 'ps-intel-3000']
Wed Aug 14 18:07:08 2013 - DEBUG | done with get_item; ['system', 'ps-intel-3000']
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255
Wed Aug 14 18:07:08 2013 - WARNING | warning: kernel option length exceeds 255

2013-08-14 18:07:08,486 ps-intel-3000 INFO: Removing old distro
2013-08-14 18:07:08,489 root DEBUG: Running command: sudo cobbler distro remove --name=ps-intel-3000-x86_64
2013-08-14 18:07:08,489 root DEBUG: Output follows:
2013-08-14 18:07:08,831 root DEBUG: 
2013-08-14 18:07:08,831 root DEBUG: Return code: 0
2013-08-14 18:07:08,831 ps-intel-3000 DEBUG: Cobbler log follows:
Wed Aug 14 18:07:08 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 18:07:08 2013 - DEBUG | get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 18:07:08 2013 - DEBUG | done with get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 18:07:08 2013 - INFO | REMOTE remove_item (distro, recursive=True); user(<DIRECT>); name(ps-intel-3000-x86_64)
Wed Aug 14 18:07:08 2013 - DEBUG | REMOTE CLI Authorized; user(?)
Wed Aug 14 18:07:08 2013 - DEBUG | get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 18:07:08 2013 - DEBUG | done with get_item; ['distro', 'ps-intel-3000-x86_64']
Wed Aug 14 18:07:08 2013 - INFO | remove_item(distro); ['ps-intel-3000-x86_64']
Wed Aug 14 18:07:08 2013 - DEBUG | get_items; ['distro']
Wed Aug 14 18:07:08 2013 - DEBUG | done with get_items; ['distro']
Wed Aug 14 18:07:08 2013 - DEBUG | get_item; ['profile', 'ps-intel-3000-x86_64']
Wed Aug 14 18:07:08 2013 - DEBUG | done with get_item; ['profile', 'ps-intel-3000-x86_64']

2013-08-14 18:07:08,854 cleanup DEBUG: Recursively Removing directory /tmp/ps-intel-3000_JlyzCC
2013-08-14 18:07:08,898 cleanup DEBUG: Running cleanup