7 Replies Latest reply: Jun 30, 2011 9:34 AM by Anton vanKimmenade RSS

Building EBS on micro AMI, does not finish, ideas?

Anton vanKimmenade Newbie

I read comments about failing atempts building and ebs VM on Amazon. I'm not having having problems with the first steps though, After it has finished the ec2 format the application (the latest) simply doesn't finish nor report.  The VM is running 100% CPU and doesn't progress it has been running without memory adjustments for more than a day, without finishing. It looks like it fails on ec2 without noticing, on the other hand that is not the final output so I may be just the logging telling that there are only 0 bytes. 

 

The logging is pretty long so its short shorted:

The appl specifies the @core and a number of packages like samba, php etc

 

boxgrinder-build f14-base.appl -p ec2 -d ebs -b --debug

 

D, [2011-06-29T15:33:12.489061 #816] DEBUG -- : Launching new BoxGrinder build...

D, [2011-06-29T15:33:12.508489 #816] DEBUG -- : Loading os plugins...

D, [2011-06-29T15:33:12.508696 #816] DEBUG -- : We have 3 os plugin(s) registered

D, [2011-06-29T15:33:12.508875 #816] DEBUG -- : - fedora plugin for Fedora.

D, [2011-06-29T15:33:12.509050 #816] DEBUG -- : - centos plugin for CentOS.

D, [2011-06-29T15:33:12.509224 #816] DEBUG -- : - rhel plugin for Red Hat Enterprise Linux.

D, [2011-06-29T15:33:12.509392 #816] DEBUG -- : Plugins loaded.

D, [2011-06-29T15:33:12.509717 #816] DEBUG -- : Loading platform plugins...

D, [2011-06-29T15:33:12.509910 #816] DEBUG -- : We have 3 platform plugin(s) registered

D, [2011-06-29T15:33:12.510086 #816] DEBUG -- : - virtualbox plugin for VirtualBox.

D, [2011-06-29T15:33:12.510260 #816] DEBUG -- : - ec2 plugin for Amazon Elastic Compute Cloud (Amazon EC2).

D, [2011-06-29T15:33:12.510434 #816] DEBUG -- : - vmware plugin for VMware.

D, [2011-06-29T15:33:12.510753 #816] DEBUG -- : Plugins loaded.

D, [2011-06-29T15:33:12.510927 #816] DEBUG -- : Loading delivery plugins...

D, [2011-06-29T15:33:12.511116 #816] DEBUG -- : We have 7 delivery plugin(s) registered

D, [2011-06-29T15:33:12.511294 #816] DEBUG -- : - ebs plugin for Elastic Block Storage.

D, [2011-06-29T15:33:12.511467 #816] DEBUG -- : - s3 plugin for Amazon Simple Storage Service (Amazon S3).

D, [2011-06-29T15:33:12.511765 #816] DEBUG -- : - elastichosts plugin for ElasticHosts.

D, [2011-06-29T15:33:12.511940 #816] DEBUG -- : - sftp plugin for SSH File Transfer Protocol.

D, [2011-06-29T15:33:12.512113 #816] DEBUG -- : - local plugin for Local file system.

D, [2011-06-29T15:33:12.512285 #816] DEBUG -- : - cloudfront plugin for Amazon Simple Storage Service (Amazon S3).

D, [2011-06-29T15:33:12.512459 #816] DEBUG -- : - ami plugin for Amazon Simple Storage Service (Amazon S3).

D, [2011-06-29T15:33:12.512750 #816] DEBUG -- : Plugins loaded.

I, [2011-06-29T15:33:12.520166 #816]  INFO -- : Validating appliance definition from f14-base.appl file...

I, [2011-06-29T15:33:12.534112 #816]  INFO -- : Appliance definition is valid.

D, [2011-06-29T15:33:12.534451 #816] DEBUG -- : Adding packages to appliance...

D, [2011-06-29T15:33:12.534764 #816] DEBUG -- : 9 package(s) added to appliance.

I, [2011-06-29T15:33:12.544905 #816]  INFO -- : Building 'f14-Speech' appliance for i686 architecture.

I, [2011-06-29T15:33:12.549628 #816]  INFO -- : Deliverables for fedora operating system plugin exists, skipping.

D, [2011-06-29T15:33:12.550723 #816] DEBUG -- : Executing platform plugin for ec2...

I, [2011-06-29T15:33:12.560765 #816]  INFO -- : Converting f14-Speech appliance image to EC2 format...

D, [2011-06-29T15:33:12.561156 #816] DEBUG -- : Executing command: 'dd if=/dev/zero of='build/appliances/i686/fedora/14/f14-Speech/ec2-plugin/tmp/f14-Speech.ec2' bs=1 count=0 seek=10240M'

D, [2011-06-29T15:33:12.583399 #816] DEBUG -- : 0+0 records in

D, [2011-06-29T15:33:12.583847 #816] DEBUG -- : 0+0 records out

D, [2011-06-29T15:33:12.584188 #816] DEBUG -- : 0 bytes (0 B) copied, 1.8539e-05 s, 0.0 kB/s

D, [2011-06-29T15:33:12.585207 #816] DEBUG -- : Preparing guestfs...

D, [2011-06-29T15:33:12.630096 #816] DEBUG -- : Enabling networking for GuestFS...

D, [2011-06-29T15:33:12.630849 #816] DEBUG -- : Launching guestfs...

D, [2011-06-29T15:33:12.642255 #816] DEBUG -- : GFS: [00008ms] febootstrap-supermin-helper --verbose -f checksum '/usr/lib/guestfs/supermin.d' i686

supermin helper [00000ms] whitelist = (not specified), host_cpu = i686, kernel = (null), initrd = (null), appliance = (null)

supermin helper [00000ms] inputs[0] = /usr/lib/guestfs/supermin.d

checking modpath /lib/modules/2.6.38.6-26.rc1.fc15.i686.PAE is a directory

picked vmlinuz-2.6.38.6-26.rc1.fc15.i686.PAE because modpath /lib/modules/2.6.38.6-26.rc1.fc15.i686.PAE exists

supermin helper [00008ms] finished creating kernel

supermin helper [00008ms] visiting /usr/lib/guestfs/supermin.d

supermin helper [00017ms] visiting /usr/lib/guestfs/supermin.d/base.img

supermin helper [00043ms] visiting /usr/lib/guestfs/supermin.d/daemon.img

supermin helper [00045ms] visiting /usr/lib/guestfs/supermin.d/hostfiles

supermin helper [00465ms] visiting /usr/lib/guestfs/supermin.d/init.img

supermin helper [01000ms] finished creating appliance

 

logging skipped

 

D, [2011-06-29T15:35:30.963756 #816] DEBUG -- : SELinux policy was loaded, we're ready to sync filesystem.

I, [2011-06-29T15:35:30.963960 #816]  INFO -- : Synchronizing filesystems...

D, [2011-06-29T15:35:30.965962 #816] DEBUG -- : GFS: send_to_daemon: 52 bytes: 00 00 00 30 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 94 | 00 00 00 00 | ...

D, [2011-06-29T15:35:30.967303 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 45 (umount) took 0.49 seconds

D, [2011-06-29T15:35:30.969144 #816] DEBUG -- : GFS: guestfsd: main_loop: new request, len 0x30

D, [2011-06-29T15:35:30.971042 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 94 | 00 00 00 01 | 00 12 34 11 | ...

D, [2011-06-29T15:35:30.971849 #816] DEBUG -- : GFS: send_to_daemon: 52 bytes: 00 00 00 30 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 94 | 00 00 00 00 | ...

D, [2011-06-29T15:35:30.973821 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 148 (mkmountpoint) took 0.00 seconds

guestfsd: main_loop: new request, len 0x30

D, [2011-06-29T15:35:30.974904 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 94 | 00 00 00 01 | 00 12 34 12 | ...

D, [2011-06-29T15:35:30.975465 #816] DEBUG -- : GFS: send_to_daemon: 56 bytes: 00 00 00 34 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 94 | 00 00 00 00 | ...

D, [2011-06-29T15:35:30.977208 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 148 (mkmountpoint) took 0.00 seconds

guestfsd: main_loop: new request, len 0x34

D, [2011-06-29T15:35:30.978397 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 94 | 00 00 00 01 | 00 12 34 13 | ...

D, [2011-06-29T15:35:30.979097 #816] DEBUG -- : GFS: send_to_daemon: 64 bytes: 00 00 00 3c | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 2a | 00 00 00 00 | ...

D, [2011-06-29T15:35:30.980902 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 148 (mkmountpoint) took 0.00 seconds

guestfsd: main_loop: new request, len 0x3c

D, [2011-06-29T15:35:30.985010 #816] DEBUG -- : GFS: mke2fs -t ext4 -F /dev/vdb

D, [2011-06-29T15:35:31.110156 #816] DEBUG -- : GFS: mke2fs 1.41.14 (22-Dec-2010)

D, [2011-06-29T15:35:57.515534 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 2a | 00 00 00 01 | 00 12 34 14 | ...

D, [2011-06-29T15:35:57.516287 #816] DEBUG -- : GFS: send_to_daemon: 68 bytes: 00 00 00 40 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 00 | ...

D, [2011-06-29T15:35:57.518602 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 42 (mkfs) took 12.45 seconds

guestfsd: main_loop: new request, len 0x40

D, [2011-06-29T15:35:57.522727 #816] DEBUG -- : GFS: e2label /dev/vdb 79d3d2d4

D, [2011-06-29T15:35:57.524533 #816] DEBUG -- : GFS:

D, [2011-06-29T15:35:57.793806 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 01 | 00 12 34 15 | ...

D, [2011-06-29T15:35:57.794812 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 00 | ...

D, [2011-06-29T15:35:57.796893 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 80 (set_e2label) took 0.27 seconds

guestfsd: main_loop: new request, len 0x44

D, [2011-06-29T15:35:57.799600 #816] DEBUG -- : GFS: mount -o  /dev/vdb /sysroot/out/in

D, [2011-06-29T15:35:58.180817 #816] DEBUG -- : GFS: [   51.942588] EXT4-fs (vdb): mounted filesystem with ordered data mode. Opts: (null)

D, [2011-06-29T15:35:58.242592 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 01 | 00 12 34 16 | ...

D, [2011-06-29T15:35:58.243290 #816] DEBUG -- : GFS: send_to_daemon: 68 bytes: 00 00 00 40 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 00 | ...

D, [2011-06-29T15:35:58.245608 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 74 (mount_options) took 0.43 seconds

guestfsd: main_loop: new request, len 0x40

D, [2011-06-29T15:35:58.249234 #816] DEBUG -- : GFS: e2label /dev/vdb a570ee9c

D, [2011-06-29T15:35:58.584214 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 01 | 00 12 34 17 | ...

D, [2011-06-29T15:35:58.585441 #816] DEBUG -- : GFS: send_to_daemon: 44 bytes: 00 00 00 28 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 08 | 00 00 00 00 | ...

D, [2011-06-29T15:35:58.587701 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 80 (set_e2label) took 0.33 seconds

guestfsd: main_loop: new request, len 0x28

D, [2011-06-29T15:35:58.591894 #816] DEBUG -- : GFS: recv_from_daemon: 76 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 08 | 00 00 00 01 | 00 12 34 18 | ...

D, [2011-06-29T15:35:58.592900 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 00 | ...

D, [2011-06-29T15:35:58.596294 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 8 (list_partitions) took 0.00 seconds

guestfsd: main_loop: new request, len 0x44

D, [2011-06-29T15:35:58.598997 #816] DEBUG -- : GFS: mount -o  /dev/vda1 /sysroot/in/

D, [2011-06-29T15:35:58.985736 #816] DEBUG -- : GFS: [   52.732800] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null)

D, [2011-06-29T15:35:59.079258 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 01 | 00 12 34 19 | ...

D, [2011-06-29T15:35:59.080133 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 00 | ...

D, [2011-06-29T15:35:59.102597 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 74 (mount_options) took 0.47 seconds

D, [2011-06-29T15:35:59.105309 #816] DEBUG -- : GFS: guestfsd: main_loop: new request, len 0x44

D, [2011-06-29T15:35:59.121735 #816] DEBUG -- : GFS: e2label /dev/vda1 79d3d2d4

D, [2011-06-29T15:35:59.257056 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 01 | 00 12 34 1a | ...

D, [2011-06-29T15:35:59.258439 #816] DEBUG -- : GFS: send_to_daemon: 84 bytes: 00 00 00 50 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 00 | ...

D, [2011-06-29T15:35:59.260397 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 80 (set_e2label) took 0.15 seconds

guestfsd: main_loop: new request, len 0x50

D, [2011-06-29T15:35:59.274709 #816] DEBUG -- : GFS: mount -o  /dev/vda2 /sysroot/in/SpeechMagic

D, [2011-06-29T15:35:59.621513 #816] DEBUG -- : GFS: [   53.345371] EXT4-fs (vda2): mounted filesystem with ordered data mode. Opts: (null)

D, [2011-06-29T15:35:59.649809 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 01 | 00 12 34 1b | ...

D, [2011-06-29T15:35:59.650726 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 00 | ...

D, [2011-06-29T15:35:59.652580 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 74 (mount_options) took 0.37 seconds

guestfsd: main_loop: new request, len 0x44

D, [2011-06-29T15:35:59.657196 #816] DEBUG -- : GFS: e2label /dev/vda2 d8863c17

D, [2011-06-29T15:35:59.762017 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 01 | 00 12 34 1c | ...

D, [2011-06-29T15:35:59.762861 #816] DEBUG -- : Copying files...

D, [2011-06-29T15:35:59.763310 #816] DEBUG -- : GFS: send_to_daemon: 60 bytes: 00 00 00 38 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 58 | 00 00 00 00 | ...

D, [2011-06-29T15:35:59.765252 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 80 (set_e2label) took 0.10 seconds

guestfsd: main_loop: new request, len 0x38

D, [2011-06-29T15:35:59.792747 #816] DEBUG -- : GFS: cp -a /sysroot/in/ /sysroot/out

 

From here on the system runs at 100% CPU, you cannot even login through another connection

  • 1. Re: Building EBS on micro AMI, does not finish, ideas?
    Marek Goldmann Master

    Anton,

     

    I think you were hit by the low RAM amount on micro isntances. Libguestfs uses by default 500 MB of ram to run which may be a problem on micro isntance You can decrease it for example to 300 by setting the environment variable:

     

    export LIBGUESTFS_MEMSIZE=300
    

     

    --Marek

  • 2. Re: Building EBS on micro AMI, does not finish, ideas?
    Anton vanKimmenade Newbie

    Marek,

     

    You are right for the first couple of tries, I however then read the thread about the memory usage and reduced it with your above setting and ran it with debug information of which the above is the result. So unfortunately there is no info that that it needs more memory in the log. I had to stop the VM as it kept running without making progress and bypassed the problem by building a VM from the base appliance, but really would like this to work. So how can I provide more info?

     

    --Anton

  • 3. Re: Building EBS on micro AMI, does not finish, ideas?
    Marek Goldmann Master

    Basically we would need a trace log level, which can be provided by pasting relevant part of log/boxgrinder.log.

     

    --Marek

  • 4. Re: Building EBS on micro AMI, does not finish, ideas?
    Anton vanKimmenade Newbie

    I have added it completely as it wasn't too big

     

    D, [2011-06-29T15:33:12.489243 #816] DEBUG -- : Launching new BoxGrinder build...

    T, [2011-06-29T15:33:12.508324 #816] TRACE -- : Used configuration: --- !map:BoxGrinder::Config

    :file: /root/.boxgrinder/config

    :force: false

    :platform_config: {}

     

     

    :log_level: :debug

    :delivery_config: {}

     

     

    :additional_plugins: []

     

     

    :backtrace: true

    :dir:

      :cache: /var/cache/boxgrinder

      :root: /appliances

      :build: build

      :tmp: /tmp

    :delivery: :ebs

    :name: BoxGrinder Build

    :os_config: {}

     

     

    :plugins:

      ebs:

        delete_on_termination: false

        access_key: <REDACTED>

        account_number: <REDACTED>

        secret_access_key: <REDACTED>

    :platform: :ec2

     

     

    D, [2011-06-29T15:33:12.508595 #816] DEBUG -- : Loading os plugins...

    D, [2011-06-29T15:33:12.508777 #816] DEBUG -- : We have 3 os plugin(s) registered

    D, [2011-06-29T15:33:12.508954 #816] DEBUG -- : - fedora plugin for Fedora.

    D, [2011-06-29T15:33:12.509129 #816] DEBUG -- : - centos plugin for CentOS.

    D, [2011-06-29T15:33:12.509303 #816] DEBUG -- : - rhel plugin for Red Hat Enterprise Linux.

    D, [2011-06-29T15:33:12.509472 #816] DEBUG -- : Plugins loaded.

    D, [2011-06-29T15:33:12.509816 #816] DEBUG -- : Loading platform plugins...

    D, [2011-06-29T15:33:12.509990 #816] DEBUG -- : We have 3 platform plugin(s) registered

    D, [2011-06-29T15:33:12.510165 #816] DEBUG -- : - virtualbox plugin for VirtualBox.

    D, [2011-06-29T15:33:12.510340 #816] DEBUG -- : - ec2 plugin for Amazon Elastic Compute Cloud (Amazon EC2).

    D, [2011-06-29T15:33:12.510513 #816] DEBUG -- : - vmware plugin for VMware.

    D, [2011-06-29T15:33:12.510836 #816] DEBUG -- : Plugins loaded.

    D, [2011-06-29T15:33:12.511007 #816] DEBUG -- : Loading delivery plugins...

    D, [2011-06-29T15:33:12.511198 #816] DEBUG -- : We have 7 delivery plugin(s) registered

    D, [2011-06-29T15:33:12.511374 #816] DEBUG -- : - ebs plugin for Elastic Block Storage.

    D, [2011-06-29T15:33:12.511658 #816] DEBUG -- : - s3 plugin for Amazon Simple Storage Service (Amazon S3).

    D, [2011-06-29T15:33:12.511846 #816] DEBUG -- : - elastichosts plugin for ElasticHosts.

    D, [2011-06-29T15:33:12.512020 #816] DEBUG -- : - sftp plugin for SSH File Transfer Protocol.

    D, [2011-06-29T15:33:12.512191 #816] DEBUG -- : - local plugin for Local file system.

    D, [2011-06-29T15:33:12.512365 #816] DEBUG -- : - cloudfront plugin for Amazon Simple Storage Service (Amazon S3).

    D, [2011-06-29T15:33:12.512649 #816] DEBUG -- : - ami plugin for Amazon Simple Storage Service (Amazon S3).

    D, [2011-06-29T15:33:12.512831 #816] DEBUG -- : Plugins loaded.

    I, [2011-06-29T15:33:12.520272 #816]  INFO -- : Validating appliance definition from f14-base.appl file...

    I, [2011-06-29T15:33:12.534210 #816]  INFO -- : Appliance definition is valid.

    D, [2011-06-29T15:33:12.534653 #816] DEBUG -- : Adding packages to appliance...

    D, [2011-06-29T15:33:12.534846 #816] DEBUG -- : 9 package(s) added to appliance.

    I, [2011-06-29T15:33:12.545136 #816]  INFO -- : Building 'f14-Speech' appliance for i686 architecture.

    I, [2011-06-29T15:33:12.549749 #816]  INFO -- : Deliverables for fedora operating system plugin exists, skipping.

    D, [2011-06-29T15:33:12.550825 #816] DEBUG -- : Executing platform plugin for ec2...

    I, [2011-06-29T15:33:12.560869 #816]  INFO -- : Converting f14-Speech appliance image to EC2 format...

    T, [2011-06-29T15:33:12.561006 #816] TRACE -- : Preparing disk...

    D, [2011-06-29T15:33:12.561256 #816] DEBUG -- : Executing command: 'dd if=/dev/zero of='build/appliances/i686/fedora/14/f14-Speech/ec2-plugin/tmp/f14-Speech.ec2' bs=1 count=0 seek=10240M'

    D, [2011-06-29T15:33:12.583680 #816] DEBUG -- : 0+0 records in

    D, [2011-06-29T15:33:12.583941 #816] DEBUG -- : 0+0 records out

    D, [2011-06-29T15:33:12.584277 #816] DEBUG -- : 0 bytes (0 B) copied, 1.8539e-05 s, 0.0 kB/s

    T, [2011-06-29T15:33:12.584953 #816] TRACE -- : Disk prepared

    D, [2011-06-29T15:33:12.585305 #816] DEBUG -- : Preparing guestfs...

    T, [2011-06-29T15:33:12.585481 #816] TRACE -- : Setting libguestfs temporary directory to '/tmp'...

    T, [2011-06-29T15:33:12.587288 #816] TRACE -- : We have event callbacks available!

    T, [2011-06-29T15:33:12.589506 #816] TRACE -- : Setting debug + trace...

    T, [2011-06-29T15:33:12.589753 #816] TRACE -- : GFS: set_append "noapic"

    T, [2011-06-29T15:33:12.589904 #816] TRACE -- : GFS: set_append = 0

    T, [2011-06-29T15:33:12.590022 #816] TRACE -- : Enabling SElinux support in guestfs...

    T, [2011-06-29T15:33:12.590158 #816] TRACE -- : GFS: set_selinux true

    T, [2011-06-29T15:33:12.590313 #816] TRACE -- : GFS: set_selinux = 0

    T, [2011-06-29T15:33:12.590426 #816] TRACE -- : Checking if HW virtualization is available...

    T, [2011-06-29T15:33:12.627884 #816] TRACE -- : HW acceleration not available.

    T, [2011-06-29T15:33:12.628278 #816] TRACE -- : Setting QEMU wrapper to /usr/lib/ruby/gems/1.8/gems/boxgrinder-build-0.9.2/lib/boxgrinder-build/helpers/qemu.wrapper...

    T, [2011-06-29T15:33:12.628492 #816] TRACE -- : GFS: set_qemu "/usr/lib/ruby/gems/1.8/gems/boxgrinder-build-0.9.2/lib/boxgrinder-build/helpers/qemu.wrapper"

    T, [2011-06-29T15:33:12.628685 #816] TRACE -- : GFS: set_qemu = 0

    T, [2011-06-29T15:33:12.628802 #816] TRACE -- : QEMU wrapper set.

    T, [2011-06-29T15:33:12.628929 #816] TRACE -- : Adding drive 'build/appliances/i686/fedora/14/f14-Speech/fedora-plugin/f14-Speech-sda.raw'...

    T, [2011-06-29T15:33:12.629058 #816] TRACE -- : GFS: add_drive "build/appliances/i686/fedora/14/f14-Speech/fedora-plugin/f14-Speech-sda.raw"

    T, [2011-06-29T15:33:12.629274 #816] TRACE -- : GFS: add_drive = 0

    T, [2011-06-29T15:33:12.629378 #816] TRACE -- : Drive added.

    T, [2011-06-29T15:33:12.629509 #816] TRACE -- : Adding drive 'build/appliances/i686/fedora/14/f14-Speech/ec2-plugin/tmp/f14-Speech.ec2'...

    T, [2011-06-29T15:33:12.629688 #816] TRACE -- : GFS: add_drive "build/appliances/i686/fedora/14/f14-Speech/ec2-plugin/tmp/f14-Speech.ec2"

    T, [2011-06-29T15:33:12.629869 #816] TRACE -- : GFS: add_drive = 0

    T, [2011-06-29T15:33:12.629981 #816] TRACE -- : Drive added.

    D, [2011-06-29T15:33:12.630233 #816] DEBUG -- : Enabling networking for GuestFS...

    T, [2011-06-29T15:33:12.630368 #816] TRACE -- : GFS: set_network true

    T, [2011-06-29T15:33:12.630491 #816] TRACE -- : GFS: set_network = 0

    D, [2011-06-29T15:33:12.630947 #816] DEBUG -- : Launching guestfs...

    T, [2011-06-29T15:33:12.631084 #816] TRACE -- : GFS: launch

    D, [2011-06-29T15:33:12.642394 #816] DEBUG -- : GFS: [00008ms] febootstrap-supermin-helper --verbose -f checksum '/usr/lib/guestfs/supermin.d' i686

    D, [2011-06-29T15:33:13.681294 #816] DEBUG -- : GFS: [01047ms] begin building supermin appliance

    D, [2011-06-29T15:33:13.681829 #816] DEBUG -- : GFS: [01048ms] run febootstrap-supermin-helper

    D, [2011-06-29T15:33:13.687346 #816] DEBUG -- : GFS: [01053ms] febootstrap-supermin-helper \

        --verbose \

        -f ext2 /usr/lib/guestfs/supermin.d i686 /tmp/guestfs.oefXc0/kernel /tmp/guestfs.oefXc0/initrd /tmp/guestfs.oefXc0/root

    D, [2011-06-29T15:33:45.640749 #816] DEBUG -- : GFS: [33006ms] finished building supermin appliance

    D, [2011-06-29T15:33:45.645834 #816] DEBUG -- : GFS: [33012ms] begin testing qemu features

    D, [2011-06-29T15:33:45.904568 #816] DEBUG -- : GFS: [33270ms] finished testing qemu features

    D, [2011-06-29T15:33:45.915715 #862] DEBUG -- : GFS: is_openable: /dev/kvm: No such file or directory

    D, [2011-06-29T15:33:45.918845 #816] DEBUG -- : GFS: accept_from_daemon: 0x9d7f740 g->state = 1

    D, [2011-06-29T15:33:45.916503 #862] DEBUG -- : GFS: [33282ms] /usr/lib/ruby/gems/1.8/gems/boxgrinder-build-0.9.2/lib/boxgrinder-build/helpers/qemu.wrapper \

        -drive file=build/appliances/i686/fedora/14/f14-Speech/fedora-plugin/f14-Speech-sda.raw,cache=off,if=virtio \

        -drive file=build/appliances/i686/fedora/14/f14-Speech/ec2-plugin/tmp/f14-Speech.ec2,cache=off,if=virtio \

        -nodefconfig \

        -nodefaults \

        -nographic \

        -m 500 \

        -no-reboot \

        -no-hpet \

        -device virtio-serial \

        -serial stdio \

        -chardev socket,path=/tmp/libguestfsh0E3ar/guestfsd.sock,id=channel0 \

        -device virtserialport,chardev=channel0,name=org.libguestfs.channel.0 \

        -netdev user,id=usernet,net=169.254.0.0/16 \

        -device virtio-net-pci,netdev=usernet \

        -kernel /tmp/.guestfs-0/kernel.816 \

        -initrd /tmp/.guestfs-0/initrd.816 \

        -append 'panic=1 console=ttyS0 udevtimeout=300 noapic acpi=off printk.time=1 cgroup_disable=memory selinux=1 enforcing=0 guestfs_verbose=1 TERM=xterm-color noapic' \

        -drive file=/tmp/.guestfs-0/root.816,snapshot=on,if=virtio,cache=unsafe

    D, [2011-06-29T15:33:51.569259 #816] DEBUG -- : GFS: [    0.000000] Initializing cgroup subsys cpuset

    [    0.000000] Initializing cgroup subsys cpu

    [    0.000000] Linux version 2.6.38.6-26.rc1.fc15.i686.PAE (mockbuild@x86-14.phx2.fedoraproject.org) (gcc version 4.6.0 20110428 (Red Ha

    D, [2011-06-29T15:33:51.570776 #816] DEBUG -- : GFS: t 4.6.0-6) (GCC) ) #1 SMP Mon May 9 20:36:50 UTC 2011

    [    0.000000] BIOS-provided physical RAM map:

    [    0.000000]  BIOS-e820: 0000000000000000 - 000000000009f400

    D, [2011-06-29T15:33:51.572235 #816] DEBUG -- : GFS: (usable)

    [    0.000000]  BIOS-e820: 000000000009f400 - 00000000000a0000 (reserved)

    [    0.000000]  BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)

    [    0.000000]  BIOS-e820: 0000000000100000 - 000000001f3fd000 (usab

    D, [2011-06-29T15:33:51.573715 #816] DEBUG -- : GFS: le)

    [    0.000000]  BIOS-e820: 000000001f3fd000 - 000000001f400000 (reserved)

    [    0.000000]  BIOS-e820: 00000000fffc0000 - 0000000100000000 (reserved)

    [    0.000000] Using x86 segment limits to approximate NX protection

    D, [2011-06-29T15:33:51.575154 #816] DEBUG -- : GFS:   0.000000] DMI 2.4 present.

    [    0.000000] last_pfn = 0x1f3fd max_arch_pfn = 0x1000000

    [    0.000000] found SMP MP-table at [c00f7b80] f7b80

    [    0.000000] init_memory_mapping: 0000000000000000-000000001f3fd000

    [    0.000000] RAMDIS

    D, [2011-06-29T15:33:51.576635 #816] DEBUG -- : GFS: K: 1f26b000 - 1f3f0000

    [    0.000000] 0MB HIGHMEM available.

    [    0.000000] 499MB LOWMEM available.

    [    0.000000]   mapped low ram: 0 - 1f3fd000

    [    0.000000]   low ram: 0 - 1f3fd000

    [    0.000000] Zone PFN ranges:

    [    0.000000]

    D, [2011-06-29T15:33:51.578154 #816] DEBUG -- : GFS:    DMA      0x00000010 -> 0x00001000

    [    0.000000]   Normal   0x00001000 -> 0x0001f3fd

    [    0.000000]   HighMem  empty

    [    0.000000] Movable zone start PFN for each node

    [    0.000000] early_node_map[2] active

    D, [2011-06-29T15:33:51.579611 #816] DEBUG -- : GFS:  PFN ranges

    [    0.000000]     0: 0x00000010 -> 0x0000009f

    [    0.000000]     0: 0x00000100 -> 0x0001f3fd

    [    0.000000] Using APIC driver default

    [    0.000000] SFI: Simple Firmware Interface v0.81 http://simplefirmware.org

    [   

    D, [2011-06-29T15:33:51.581147 #816] DEBUG -- : GFS: 0.000000] Intel MultiProcessor Specification v1.4

    [    0.000000]     Virtual Wire compatibility mode.

    [    0.000000] MPTABLE: OEM ID: BOCHSCPU

    [    0.000000] MPTABLE: Product ID: 0.1        

    [    0.000000] MPTABLE: APIC at: 0xFEE000

    D, [2011-06-29T15:33:51.582596 #816] DEBUG -- : GFS: 00

    [    0.000000] Processor #0 (Bootup-CPU)

    [    0.000000] IOAPIC[0]: apic_id 1, version 17, address 0xfec00000, GSI 0-23

    [    0.000000] Processors: 1

    [    0.000000] SMP: Allowing 1 CPUs, 0 hotplug CPUs

    [    0.000000] PM: Registere

    D, [2011-06-29T15:33:51.584023 #816] DEBUG -- : GFS: d nosave memory: 000000000009f000 - 00000000000a0000

    [    0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000f0000

    [    0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000000100000

    [    0.000000] Alloca

    D, [2011-06-29T15:33:51.585430 #816] DEBUG -- : GFS: ting PCI resources starting at 1f400000 (gap: 1f400000:e0bc0000)

    [    0.000000] Booting paravirtualized kernel on bare hardware

    [    0.000000] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:1 nr_node_ids:1

    [    0

    D, [2011-06-29T15:33:51.586942 #816] DEBUG -- : GFS: .000000] PERCPU: Embedded 13 pages/cpu @dec00000 s31040 r0 d22208 u2097152

    [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 126884

    [    0.000000] Kernel command line: panic=1 console=ttyS0 udevtimeout=300 noapic acpi=off print

    D, [2011-06-29T15:33:51.588363 #816] DEBUG -- : GFS: k.time=1 cgroup_disable=memory selinux=1 enforcing=0 guestfs_verbose=1 TERM=xterm-color noapic

    [    0.000000] Disabling memory control group subsystem

    [    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)

    [

    D, [2011-06-29T15:33:51.589726 #816] DEBUG -- : GFS:     0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)

    [    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)

    [    0.000000] Initializing CPU#0

    [    0.000000] Initializing HighMem fo

    D, [2011-06-29T15:33:51.591134 #816] DEBUG -- : GFS: r node 0 (00000000:00000000)

    [    0.000000] Memory: 497240k/511988k available (4028k kernel code, 14296k reserved, 2570k data, 592k init, 0k highmem)

    [    0.000000] virtual kernel memory layout:

    [    0.000000]     fixmap  : 0xff575000

    D, [2011-06-29T15:33:51.592620 #816] DEBUG -- : GFS: - 0xfffff000   (10792 kB)

    [    0.000000]     pkmap   : 0xff200000 - 0xff400000   (2048 kB)

    [    0.000000]     vmalloc : 0xdfbfd000 - 0xff1fe000   ( 502 MB)

    [    0.000000]     lowmem  : 0xc0000000 - 0xdf3fd000   ( 499 MB)

    [    0.000000]

    D, [2011-06-29T15:33:51.593991 #816] DEBUG -- : GFS:        .init : 0xc0a72000 - 0xc0b06000   ( 592 kB)

    [    0.000000]       .data : 0xc07ef342 - 0xc0a71c00   (2570 kB)

    [    0.000000]       .text : 0xc0400000 - 0xc07ef342   (4028 kB)

    [    0.000000] Checking if this processor honours the WP bit even in supervis

    D, [2011-06-29T15:33:51.595410 #816] DEBUG -- : GFS: or mode...Ok.

    [    0.000000] SLUB: Genslabs=15, HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1

    [    0.000000] Hierarchical RCU implementation.

    [    0.000000]           RCU dyntick-idle grace-period acceleration is enabled.

    [    0.00000

    D, [2011-06-29T15:33:51.596905 #816] DEBUG -- : GFS: 0]           RCU-based detection of stalled CPUs is disabled.

    [    0.000000] NR_IRQS:2304

    [    0.000000] Console: colour dummy device 80x25

    [    0.000000] console [ttyS0] enabled

    D, [2011-06-29T15:33:51.617444 #816] DEBUG -- : GFS: [    0.000000] Fast TSC calibration using PIT

    [    0.000000] Detected 2266.677 MHz processor.

    D, [2011-06-29T15:33:51.619723 #816] DEBUG -- : GFS: [    0.005999] Calibrating delay loop (skipped), value calculated using timer frequency.. 4533.35 BogoMIPS (lpj=2266677)

    [    0.006342] pid_max: defa

    D, [2011-06-29T15:33:51.621142 #816] DEBUG -- : GFS: ult: 32768 minimum: 301

    D, [2011-06-29T15:33:51.624330 #816] DEBUG -- : GFS: [    0.010617] Security Framework initialized

    D, [2011-06-29T15:33:51.627124 #816] DEBUG -- : GFS: [    0.013395] SELinux:  Initializing.

    D, [2011-06-29T15:33:51.632684 #816] DEBUG -- : GFS: [    0.018792] Mount-cache hash table entries: 512

    D, [2011-06-29T15:33:51.648585 #816] DEBUG -- : GFS: [    0.034427] Initializing cgroup subsys ns

    [    0.035203] ns_cgroup deprecated: consider using the 'clone_children' flag without

    D, [2011-06-29T15:33:51.650093 #816] DEBUG -- : GFS: the ns_cgroup.

    [    0.036200] Initializing cgroup subsys cpuacct

    D, [2011-06-29T15:33:51.652216 #816] DEBUG -- : GFS: [    0.038360] Initializing cgroup subsys memory

    D, [2011-06-29T15:33:51.653784 #816] DEBUG -- : GFS: [    0.039530] Initializing cgroup subsys devices

    [    0.040138] Initializing cgroup subsys freezer

    [    0.040417] Initializing cgroup su

    D, [2011-06-29T15:33:51.655280 #816] DEBUG -- : GFS: bsys net_cls

    [    0.041088] Initializing cgroup subsys blkio

    D, [2011-06-29T15:33:51.668782 #816] DEBUG -- : GFS: [    0.051620] SMP alternatives: switching to UP code

    D, [2011-06-29T15:33:51.722918 #816] DEBUG -- : GFS: [    0.105866] Freeing SMP alternatives: 12k freed

    D, [2011-06-29T15:33:51.726930 #816] DEBUG -- : GFS: [    0.109541] ftrace: allocating 23362 entries in 46 pages

    D, [2011-06-29T15:33:51.789010 #816] DEBUG -- : GFS: [    0.128859] Enabling APIC mode:  Flat.  Using 1 I/O APICs

    [    0.

    D, [2011-06-29T15:33:51.790486 #816] DEBUG -- : GFS: 130173] CPU0: Intel QEMU Virtual CPU version 0.14.0 stepping 03

    D, [2011-06-29T15:33:51.995831 #816] DEBUG -- : GFS: [    0.233973] Performance Events: Broken PMU hardware detected, using software events only.

    D, [2011-06-29T15:33:52.012748 #816] DEBUG -- : GFS: [    0.249907] NMI watchdog disabled (cpu0): hardware events not enabled

    [    0.250095] Brought up 1 CPUs

    [    0.250319] Total of 1 processors activated (4533.35 BogoMIPS).

    D, [2011-06-29T15:33:52.022164 #816] DEBUG -- : GFS: [    0.259545] devtmpfs: initialized

    D, [2011-06-29T15:33:52.041154 #816] DEBUG -- : GFS: [    0.278637] atomic64 test passed for i586+ platform with CX8 and with SSE

    [    0.279417] Time: 19:33:50  Date: 06/29/11

    D, [2011-06-29T15:33:52.045327 #816] DEBUG -- : GFS: [    0.282880] NET: Registered protocol family 16

    D, [2011-06-29T15:33:52.072115 #816] DEBUG -- : GFS: [    0.298041] PCI: PCI BIOS revision 2.10 entry at 0xfdf30, last bus=0

    [    0.298495] PCI: Using configuration type 1 for base access

    D, [2011-06-29T15:33:52.101219 #816] DEBUG -- : GFS: [    0.327711] bio: create slab <bio-0> at 0

    D, [2011-06-29T15:33:52.105391 #816] DEBUG -- : GFS: [    0.331789] ACPI: Interpreter disabled.

    D, [2011-06-29T15:33:52.110469 #816] DEBUG -- : GFS: [    0.337056] vgaarb: loaded

    D, [2011-06-29T15:33:52.113691 #816] DEBUG -- : GFS: [    0.340012] SCSI subsystem initialized

    D, [2011-06-29T15:33:52.117848 #816] DEBUG -- : GFS: [    0.344376] usbcore: registered new interface driver usbfs

    [    0.345240] usbcore: registered new interfa

    D, [2011-06-29T15:33:52.119186 #816] DEBUG -- : GFS: ce driver hub

    [    0.346652] usbcore: registered new de

    D, [2011-06-29T15:33:52.120585 #816] DEBUG -- : GFS: vice driver usb

    D, [2011-06-29T15:33:52.124023 #816] DEBUG -- : GFS: [    0.350543] PCI: Probing PCI hardware

    D, [2011-06-29T15:33:52.129824 #816] DEBUG -- : GFS: [    0.356363] pci 0000:00:01.3: quirk: [io  0xb000-0xb03f] claimed by PIIX4 ACPI

    [    0.356783] pci 0000:00:01.3: quirk: [io  0xb100-0xb10f] claimed by PIIX4 SMB

    D, [2011-06-29T15:33:52.140115 #816] DEBUG -- : GFS: [    0.366623] pci 0000:00:01.0: PIIX/ICH IRQ router [8086:7000]

    D, [2011-06-29T15:33:52.149396 #816] DEBUG -- : GFS: [    0.375798] NetLabel: Initializing

    [    0.376030] NetLabel:  domain hash size = 128

    [    0.376261] NetLabel:  protocols = UNLABELED CIPSOv4

    D, [2011-06-29T15:33:52.151733 #816] DEBUG -- : GFS: [    0.378371] NetLabel:  unlabeled traffic allowed by default

    D, [2011-06-29T15:33:52.321318 #816] DEBUG -- : GFS: [    0.547627] pnp: PnP ACPI: disabled

    D, [2011-06-29T15:33:52.365203 #816] DEBUG -- : GFS: [    0.591631] NET: Registered protocol family 2

    D, [2011-06-29T15:33:52.369751 #816] DEBUG -- : GFS: [    0.596266] IP route cache hash table entries: 4096 (order: 2, 16384 bytes)

    D, [2011-06-29T15:33:52.376199 #816] DEBUG -- : GFS: [    0.602702] TCP established hash table entries: 16384 (order: 5, 131072 bytes)

    D, [2011-06-29T15:33:52.377650 #816] DEBUG -- : GFS: [    0.603539] TCP bind hash table entries: 16384 (order: 5, 131072 bytes)

    D, [2011-06-29T15:33:52.379391 #816] DEBUG -- : GFS: [    0.605952] TCP: Hash tables configured (established 16384 bind 16384)

    [    0.606537] TCP reno registered

    [    0.606813] UDP hash table entries: 256 (ord

    D, [2011-06-29T15:33:52.380991 #816] DEBUG -- : GFS: er: 1, 8192 bytes)

    [    0.607974] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)

    D, [2011-06-29T15:33:52.386770 #816] DEBUG -- : GFS: [    0.613315] NET: Registered protocol family 1

    [    0.613891] pci 0000:00:00.0: Limiting direct PCI/PCI transfers

    D, [2011-06-29T15:33:52.388265 #816] DEBUG -- : GFS: [    0.614510] pci 0000:00:01.0: PIIX3: Enabling Passive Release

    [    0.615365] pci 0000:00:01.0: Activating ISA DMA hang

    D, [2011-06-29T15:33:52.389697 #816] DEBUG -- : GFS:  workarounds

    D, [2011-06-29T15:33:52.395445 #816] DEBUG -- : GFS: [    0.622006] Trying to unpack rootfs image as initramfs...

    D, [2011-06-29T15:33:52.425766 #816] DEBUG -- : GFS: [    0.652123] Freeing initrd memory: 1556k freed

    D, [2011-06-29T15:33:52.433232 #816] DEBUG -- : GFS: [    0.659757] platform rtc_cmos: registered platform RTC device (no PNP device found)

    D, [2011-06-29T15:33:52.439670 #816] DEBUG -- : GFS: [    0.666202] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac)

    D, [2011-06-29T15:33:52.447616 #816] DEBUG -- : GFS: [    0.674063] audit: initializing netlink socket (disabled)

    [  

    D, [2011-06-29T15:33:52.449052 #816] DEBUG -- : GFS:  0.675241] type=2000 audit(1309376030.673:1): initialized

    D, [2011-06-29T15:33:52.530961 #816] DEBUG -- : GFS: [    0.757426] HugeTLB registered 2 MB page size, pre-allocated 0 pages

    D, [2011-06-29T15:33:52.563799 #816] DEBUG -- : GFS: [    0.790245] VFS: Disk quotas dquot_6.5.2

    D, [2011-06-29T15:33:52.566932 #816] DEBUG -- : GFS: [    0.793477] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)

    D, [2011-06-29T15:33:52.581011 #816] DEBUG -- : GFS: [    0.807529] msgmni has been set to 974

    D, [2011-06-29T15:33:52.592885 #816] DEBUG -- : GFS: [    0.819433] NET: Registered protocol family 38

    D, [2011-06-29T15:33:52.594679 #816] DEBUG -- : GFS: [    0.821078] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)

    D, [2011-06-29T15:33:52.596634 #816] DEBUG -- : GFS: [    0.823246] io scheduler noop registered

    [    0.823652] io scheduler deadline registered

    [    0

    D, [2011-06-29T15:33:52.598033 #816] DEBUG -- : GFS: .824262] io scheduler cfq registered (default)

    D, [2011-06-29T15:33:52.601561 #816] DEBUG -- : GFS: [    0.828220] pci_hotplug: PCI Hot Plug PCI Core version: 0.5

    D, [2011-06-29T15:33:52.603637 #816] DEBUG -- : GFS: [    0.830163] pciehp: PCI Express Hot Plug Controller Driver version: 0.4

    [    0.830676] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5

    D, [2011-06-29T15:33:52.611060 #816] DEBUG -- : GFS: [    0.837563] isapnp: Scanning for PnP cards...

    D, [2011-06-29T15:33:52.972090 #816] DEBUG -- : GFS: [    1.198538] isapnp: No Plug & Play device found

    D, [2011-06-29T15:33:52.974670 #816] DEBUG -- : GFS: [    1.201107] virtio-pci 0000:00:02.0: found PCI INT A -> IRQ 10

    [    1.201965] virtio-pci 0000:00:02.0: sharing IRQ 10 with 0000:00:06.0

    D, [2011-06-29T15:33:52.977903 #816] DEBUG -- : GFS: [    1.204457] virtio-pci 0000:00:03.0: found PCI INT A -> IRQ 11

    [    1.205435] virtio-pci 0

    D, [2011-06-29T15:33:52.979647 #816] DEBUG -- : GFS: 000:00:04.0: found PCI INT A -> IRQ 11

    D, [2011-06-29T15:33:52.981263 #816] DEBUG -- : GFS: [    1.207903] virtio-pci 0000:00:05.0: found PCI INT A -> IRQ 10

    [    1.208932

    D, [2011-06-29T15:33:52.982732 #816] DEBUG -- : GFS: ] virtio-pci 0000:00:06.0: found PCI INT A -> IRQ 10

    [    1.209918] virtio-pci 0000:00:06.0: sharing IRQ 10 with 0000:00:02.0

    D, [2011-06-29T15:33:52.986043 #816] DEBUG -- : GFS: [    1.212666] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled

    D, [2011-06-29T15:33:53.010318 #816] DEBUG -- : GFS: [    1.216955] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A

    D, [2011-06-29T15:33:53.038113 #816] DEBUG -- : GFS: [    1.244527] Non-volatile memory driver v1.3

    [    1.244916] Linux agpgart interface v0.103

    D, [2011-06-29T15:33:53.078177 #816] DEBUG -- : GFS: [    1.273619] brd: module loaded

    D, [2011-06-29T15:33:53.090077 #816] DEBUG -- : GFS: [    1.285642] loop: module loaded

    D, [2011-06-29T15:33:53.103393 #816] DEBUG -- : GFS: [    1.298915] scsi0 : ata_piix

    D, [2011-06-29T15:33:53.106938 #816] DEBUG -- : GFS: [    1.302590] scsi1 : ata_piix

    D, [2011-06-29T15:33:53.108604 #816] DEBUG -- : GFS: [    1.303923] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc000 irq 14

    [    1.304878] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc008 irq 15

    D, [2011-06-29T15:33:53.115635 #816] DEBUG -- : GFS: [    1.311269] Fixed MDIO Bus: probed

    D, [2011-06-29T15:33:53.117985 #816] DEBUG -- : GFS: [    1.313448] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver

    D, [2011-06-29T15:33:53.119626 #816] DEBUG -- : GFS: [    1.314933] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver

    [    1.316085] uhci_hcd: USB Universal Host Controller Interface d

    D, [2011-06-29T15:33:53.121073 #816] DEBUG -- : GFS: river

    D, [2011-06-29T15:33:53.123219 #816] DEBUG -- : GFS: [    1.318876] i8042: PNP: No PS/2 controller found. Probing ports directly.

    D, [2011-06-29T15:33:53.126646 #816] DEBUG -- : GFS: [    1.322272] serio: i8042 KBD port at 0x60,0x64 irq 1

    [    1.322859] serio: i8042 AUX port at 0x60,0x64 irq 12

    D, [2011-06-29T15:33:53.130572 #816] DEBUG -- : GFS: [    1.326209] mousedev: PS/2 mouse device common for all mice

    D, [2011-06-29T15:33:53.136216 #816] DEBUG -- : GFS: [    1.331854] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0

    D, [2011-06-29T15:33:53.141619 #816] DEBUG -- : GFS: [    1.337213] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0

    [    1.338116] rtc0:

    D, [2011-06-29T15:33:53.143033 #816] DEBUG -- : GFS: alarms up to one day, 114 bytes nvram

    D, [2011-06-29T15:33:53.144957 #816] DEBUG -- : GFS: [    1.340673] device-mapper: uevent: version 1.0.3

    D, [2011-06-29T15:33:53.150645 #816] DEBUG -- : GFS: [    1.346271] device-mapper: ioctl: 4.19.1-ioctl (2011-01-07) initialised: dm-devel@redhat.com

    D, [2011-06-29T15:33:53.152699 #816] DEBUG -- : GFS: [    1.348236] cpuidle: using governor ladder

    [    1.348849] cpuidle: using governor menu

    D, [2011-06-29T15:33:53.157823 #816] DEBUG -- : GFS: [    1.353473] usbcore: registered new interface driver usbhid

    [    1.353855] usbhid: USB HID core driver

    D, [2011-06-29T15:33:53.159273 #816] DEBUG -- : GFS: [    1.354560] nf_conntrack version 0.5.0 (7793 buckets, 31172 max)

    D, [2011-06-29T15:33:53.164615 #816] DEBUG -- : GFS: [    1.360285] ip_tables: (C) 2000-2006 Netfilter Core Team

    D, [2011-06-29T15:33:53.167269 #816] DEBUG -- : GFS: [    1.362905] TCP cubic registered

    [    1.363164] Initializing XFRM netlink socket

    [    1.363552] NET: Registered protocol family 17

    D, [2011-06-29T15:33:53.169138 #816] DEBUG -- : GFS: [    1.364834] Registering the dns_resolver key type

    D, [2011-06-29T15:33:53.171415 #816] DEBUG -- : GFS: [    1.366648] Using IPI No-Shortcut mode

    D, [2011-06-29T15:33:53.174902 #816] DEBUG -- : GFS: [    1.370583] registered taskstats version 1

    [    1.371046] IMA: No TPM chip found, activating TPM-bypass!

    D, [2011-06-29T15:33:53.179344 #816] DEBUG -- : GFS: [    1.375074]   Magic number: 15:225:598

    D, [2011-06-29T15:33:53.181493 #816] DEBUG -- : GFS: [    1.376854] rtc_cmos rtc_cmos: setting system clock to 2011-06-29 19:33:52 UTC (1309376032)

    D, [2011-06-29T15:33:53.182917 #816] DEBUG -- : GFS: [    1.378216] Initalizing network drop monitor service

    D, [2011-06-29T15:33:53.269775 #816] DEBUG -- : GFS: [    1.465386] Freeing unused kernel memory: 592k freed

    D, [2011-06-29T15:33:53.296154 #816] DEBUG -- : GFS: [    1.491777] Write protecting the kernel text: 4032k

    D, [2011-06-29T15:33:53.298068 #816] DEBUG -- : GFS: [    1.493710] Write protecting the kernel read-only data: 1932k

    D, [2011-06-29T15:33:53.339819 #816] DEBUG -- : GFS: /proc/uptime: No such file or directory

    D, [2011-06-29T15:33:53.341364 #816] DEBUG -- : GFS:

    D, [2011-06-29T15:33:53.345019 #816] DEBUG -- : GFS: febootstrap: ext2 mini initrd starting up

    D, [2011-06-29T15:33:53.347807 #816] DEBUG -- : GFS: febootstrap: mounting /sys

    D, [2011-06-29T15:33:53.358507 #816] DEBUG -- : GFS: febootstrap: insmod crc-ccitt.ko

    D, [2011-06-29T15:33:53.391840 #816] DEBUG -- : GFS: febootstrap: insmod crc-itu-t.ko

    D, [2011-06-29T15:33:53.412497 #816] DEBUG -- : GFS: febootstrap: insmod crc32c-intel.ko

    D, [2011-06-29T15:33:53.427870 #816] DEBUG -- : GFS: insmod: error inserting 'crc32c-intel.ko': -1 No such device

    D, [2011-06-29T15:33:53.431451 #816] DEBUG -- : GFS: insmod: wait: File exists

    febootstrap: insmod crc7.ko

    D, [2011-06-29T15:33:53.442200 #816] DEBUG -- : GFS: febootstrap: insmod ext2.ko

    D, [2011-06-29T15:33:53.473828 #816] DEBUG -- : GFS: [    1.669319] Switching to clocksource tsc

    D, [2011-06-29T15:33:53.505056 #816] DEBUG -- : GFS: febootstrap: insmod libcrc32c.ko

    D, [2011-06-29T15:33:53.517873 #816] DEBUG -- : GFS: febootstrap: insmod rfkill.ko

    D, [2011-06-29T15:33:53.525711 #816] DEBUG -- : GFS: insmod: can't read 'rfkill.ko': No such file or directory

    D, [2011-06-29T15:33:53.528190 #816] DEBUG -- : GFS: insmod: wait: File exists

    febootstrap: insmod scsi_transport_spi.ko

    D, [2011-06-29T15:33:53.570895 #816] DEBUG -- : GFS: febootstrap: insmod virtio-rng.ko

    D, [2011-06-29T15:33:53.588333 #816] DEBUG -- : GFS: [    1.783585] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input1

    D, [2011-06-29T15:33:53.591701 #816] DEBUG -- : GFS: febootstrap: insmod virtio_balloon.ko

    D, [2011-06-29T15:33:53.611868 #816] DEBUG -- : GFS: febootstrap: insmod virtio_blk.ko

    D, [2011-06-29T15:33:53.670934 #816] DEBUG -- : GFS: [    1.866229]  vda: vda1 vda2

    D, [2011-06-29T15:33:53.692978 #816] DEBUG -- : GFS: [    1.888247]  vdb: unknown partition table

    D, [2011-06-29T15:33:53.699418 #816] DEBUG -- : GFS: [    1.894814]  vdc: unknown partition table

    D, [2011-06-29T15:33:53.702999 #816] DEBUG -- : GFS: febootstrap: insmod virtio_net.ko

    D, [2011-06-29T15:33:53.761817 #816] DEBUG -- : GFS: febootstrap: insmod ideapad-laptop.ko

    D, [2011-06-29T15:33:53.771573 #816] DEBUG -- : GFS: [    1.966844] ideapad_laptop: Unknown symbol sparse_keymap_free (err 0)

    D, [2011-06-29T15:33:53.774438 #816] DEBUG -- : GFS: [    1.969978] ideapad_laptop: Unknown symbol rfkill_unregister (err 0)

    D, [2011-06-29T15:33:53.777153 #816] DEBUG -- : GFS: [    1.972631] ideapad_laptop: Unknown symbol rfkill_destroy (err 0)

    D, [2011-06-29T15:33:53.780638 #816] DEBUG -- : GFS: [    1.976165] ideapad_laptop: Unknown symbol rfkill_init_sw_state (err 0)

    D, [2011-06-29T15:33:53.784770 #816] DEBUG -- : GFS: [    1.980307] ideapad_laptop: Unknown symbol rfkill_set_hw_state (err 0)

    D, [2011-06-29T15:33:53.790242 #816] DEBUG -- : GFS: [    1.985806] ideapad_laptop: Unknown symbol rfkill_alloc (err 0)

    D, [2011-06-29T15:33:53.792322 #816] DEBUG -- : GFS: [    1.987887] ideapad_laptop: Unknown symbol sparse_keymap_report_event (err 0)

    D, [2011-06-29T15:33:53.794439 #816] DEBUG -- : GFS: [    1.989970] ideapad_laptop: Unknown symbol sparse_keymap_setup (err 0)

    D, [2011-06-29T15:33:53.797789 #816] DEBUG -- : GFS: [    1.993301] ideapad_laptop: Unknown symbol rfkill_register (err 0)

    D, [2011-06-29T15:33:53.802740 #816] DEBUG -- : GFS: insmod: error inserting 'ideapad-laptop.ko': -1 Unknown symbol in module

    D, [2011-06-29T15:33:53.805580 #816] DEBUG -- : GFS: insmod: wait: File exists

    febootstrap: insmod sym53c8xx.ko

    D, [2011-06-29T15:33:53.857758 #816] DEBUG -- : GFS: febootstrap: picked /sys/block/vdc/dev as root device

    D, [2011-06-29T15:33:53.864797 #816] DEBUG -- : GFS: febootstrap: creating /dev/root as block special 252:32

    febootstrap: mounting new root on /root

    D, [2011-06-29T15:33:53.876343 #816] DEBUG -- : GFS: febootstrap: chroot

    D, [2011-06-29T15:33:53.881357 #816] DEBUG -- : GFS: /proc/uptime: No such file or directory

    D, [2011-06-29T15:33:54.022792 #816] DEBUG -- : GFS: Starting /init script ...

    D, [2011-06-29T15:33:54.153739 #816] DEBUG -- : GFS: rm: cannot remove `/proc': Is a directory

    D, [2011-06-29T15:33:54.155492 #816] DEBUG -- : GFS:

    D, [2011-06-29T15:33:54.200884 #816] DEBUG -- : GFS: mkdir: cannot create directory `/proc': File exists

    D, [2011-06-29T15:33:54.202415 #816] DEBUG -- : GFS:

    D, [2011-06-29T15:33:54.722904 #816] DEBUG -- : GFS: Starting udev:

    D, [2011-06-29T15:33:54.882880 #816] DEBUG -- : GFS: udevd[59]: specified group 'dialout' unknown

     

     

    D, [2011-06-29T15:33:54.890114 #816] DEBUG -- : GFS: udevd[59]: specified group 'floppy' unknown

     

     

    D, [2011-06-29T15:33:54.892742 #816] DEBUG -- : GFS: udevd[59]: specified group 'cdrom' unknown

     

     

    D, [2011-06-29T15:33:54.895597 #816] DEBUG -- : GFS: udevd[59]: specified group 'tape' unknown

     

     

    D, [2011-06-29T15:34:02.777330 #816] DEBUG -- : GFS: [   10.972743] Clocksource tsc unstable (delta = 67687613 ns)

    D, [2011-06-29T15:34:02.785113 #816] DEBUG -- : GFS: [   10.980501] Switching to clocksource jiffies

    D, [2011-06-29T15:34:02.880083 #816] DEBUG -- : GFS: [   11.059536] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0

    D, [2011-06-29T15:34:03.400980 #816] DEBUG -- : GFS: [  OK 

    D, [2011-06-29T15:34:03.402729 #816] DEBUG -- : GFS: ]

    D, [2011-06-29T15:34:03.404191 #816] DEBUG -- : GFS:

    D, [2011-06-29T15:34:03.406247 #816] DEBUG -- : GFS:

    D, [2011-06-29T15:34:11.905447 #816] DEBUG -- : GFS:   Reading all physical volumes.  This may take a while...

    D, [2011-06-29T15:34:14.610057 #816] DEBUG -- : GFS:   No volume groups found

    D, [2011-06-29T15:34:14.761426 #816] DEBUG -- : GFS:   No volume groups found

    D, [2011-06-29T15:34:14.998086 #816] DEBUG -- : GFS: /dev:

    D, [2011-06-29T15:34:15.034493 #816] DEBUG -- : GFS: total 60

    D, [2011-06-29T15:34:15.042554 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root       13 Jun 29 15:33 MAKEDEV -> /sbin/MAKEDEV

    D, [2011-06-29T15:34:15.044358 #816] DEBUG -- : GFS: crw------- 1 root root  10, 134 Jun 29 15:33 apm_bios

    crw------- 1 root root  10, 235 Jun 29 15:33 autofs

    D, [2011-06-29T15:34:15.045813 #816] DEBUG -- : GFS: drwxr-xr-x 2 root root     4096 Jun 29 15:34 block

    crw------- 1 root root  10, 234 Jun 29 15:33 btrfs-control

    D, [2011-06-29T15:34:15.047320 #816] DEBUG -- : GFS: drwxr-xr-x 2 root root     4096 Jun 29 15:34 char

    crw------- 1 root root   5,   1 Jun 29 15:33 console

    D, [2011-06-29T15:34:15.048986 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root       11 Jun 29 15:33 core -> /proc/kcore

    drwxr-xr-x 3 root root     4096 Jun 29 15:33 cpu

    D, [2011-06-29T15:34:15.050471 #816] DEBUG -- : GFS: crw------- 1 root root  10,  62 Jun 29 15:33 cpu_dma_latency

    drwxr-xr-x 5 root root     4096 Jun 29 15:34 disk

    D, [2011-06-29T15:34:15.052199 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root       13 Jun 29 15:33 fd -> /proc/self/fd

    crw-rw-rw- 1 root root   1,   7 Jun 29 15:33 full

    D, [2011-06-29T15:34:15.053622 #816] DEBUG -- : GFS: crw-rw-rw- 1 root root  10, 229 Jun 29 15:33 fuse

    drwxr-xr-x 3 root root     4096 Jun 29 15:34 input

    D, [2011-06-29T15:34:15.066221 #816] DEBUG -- : GFS: crw------- 1 root root   1,  11 Jun 29 15:33 kmsg

    brw-rw---- 1 root disk   7,

    D, [2011-06-29T15:34:15.067715 #816] DEBUG -- : GFS:   0 Jun 29 15:33 loop0

    brw-rw---- 1 root disk   7,   1 Jun 29 15:33 loop1

    D, [2011-06-29T15:34:15.069251 #816] DEBUG -- : GFS: brw-rw---- 1 root disk   7,   2 Jun 29 15:33 loop2

    brw-rw---- 1 root disk   7,   3 Jun 29 15:33 loop3

    D, [2011-06-29T15:34:15.071186 #816] DEBUG -- : GFS: brw-rw---- 1 root disk   7,   4 Jun 29 15:33 loop4

    brw-rw---- 1 root disk   7,   5 Jun 29 15:33 loop5

    brw-rw---- 1 r

    D, [2011-06-29T15:34:15.072586 #816] DEBUG -- : GFS: oot disk   7,   6 Jun 29 15:33 loop6

    brw-rw---- 1 root disk   7,   7 Jun 29 15:33 loop7

    D, [2011-06-29T15:34:15.075022 #816] DEBUG -- : GFS: drwxr-xr-x 2  422  497     4096 Jun 29 15:33 mapper

    crw-r----- 1 root kmem   1,   1 Jun 29 15:33 mem

    D, [2011-06-29T15:34:15.076592 #816] DEBUG -- : GFS: drwxr-xr-x 2 root root     4096 Jun 29 15:33 net

    crw------- 1 root root  10,  61 Jun 29 15:33 network_latency

    D, [2011-06-29T15:34:15.078566 #816] DEBUG -- : GFS: crw------- 1 root root  10,  60 Jun 29 15:33 network_throughput

    crw-rw-rw- 1 root root   1,   3 Jun 29 15:33 null

    D, [2011-06-29T15:34:15.080425 #816] DEBUG -- : GFS: crw-r----- 1 root kmem  10, 144 Jun 29 15:33 nvram

    crw------- 1 root root   1,  12 Jun 29 15:33 oldmem

    crw-r----

    D, [2011-06-29T15:34:15.081828 #816] DEBUG -- : GFS: - 1 root kmem   1,   4 Jun 29 15:33 port

    crw------- 1 root root 108,   0 Jun 29 15:33 ppp

    D, [2011-06-29T15:34:15.083294 #816] DEBUG -- : GFS: crw-rw-rw- 1 root tty    5,   2 Jun 29 15:33 ptmx

    drwxr

    D, [2011-06-29T15:34:15.084637 #816] DEBUG -- : GFS: -xr-x 2  422  497     4096 Apr 24 05:36 pts

    brw-rw---- 1 root disk   1,   0 Jun 29 15:33 ram0

    b

    D, [2011-06-29T15:34:15.086050 #816] DEBUG -- : GFS: rw-rw---- 1 root disk   1,   1 Jun 29 15:33 ram1

    brw-rw---- 1 root disk   1,  10 Jun 29 15:33 ram10

    D, [2011-06-29T15:34:15.087476 #816] DEBUG -- : GFS: brw-rw---- 1 root disk   1,  11 Jun 29 15:33 ram11

    brw-rw---- 1 root disk   1,  12 Jun 29 15:33 ram12

    D, [2011-06-29T15:34:15.089140 #816] DEBUG -- : GFS: brw-rw---- 1 root disk   1,  13 Jun 29 15:33 ram13

    brw-rw---- 1 root disk   1,  14 Jun 29 15:33 ram14

    D, [2011-06-29T15:34:15.090703 #816] DEBUG -- : GFS: brw-rw---- 1 root disk   1,  15 Jun 29 15:33 ram15

    brw-rw---- 1 root disk   1,   2 Jun 29 15:33 ram2

    D, [2011-06-29T15:34:15.092143 #816] DEBUG -- : GFS: brw-rw---- 1 root disk   1,   3 Jun 29 15:33 ram3

    brw-rw---- 1 root disk   1,   4 Jun 29 15:33 ram4

    D, [2011-06-29T15:34:15.093845 #816] DEBUG -- : GFS: brw-rw---- 1 root disk   1,   5 Jun 29 15:33 ram5

    brw-rw---- 1 root disk   1,   6 Jun 29 15:33 ram6

    D, [2011-06-29T15:34:15.095342 #816] DEBUG -- : GFS: brw-rw---- 1 root disk   1,   7 Jun 29 15:33 ram7

    brw-rw---- 1 root disk   1,   8 Jun 29 15:34 ram8

    D, [2011-06-29T15:34:15.096835 #816] DEBUG -- : GFS: brw-rw---- 1 root disk   1,   9 Jun 29 15:33 ram9

    crw-rw-rw- 1 root root   1,   8 Jun 29 15:33 random

    D, [2011-06-29T15:34:15.098368 #816] DEBUG -- : GFS: drwxr-xr-x 2 root root     4096 Jun 29 15:33 raw

    lrwxrwxrwx 1 root root        4 Jun 29 15:33 rtc -> rtc0

    D, [2011-06-29T15:34:15.099971 #816] DEBUG -- : GFS:

    crw------- 1 root root 254,   0 Jun 29 15:33 rtc0

    drwxr-xr-x 2  422  497     409

    D, [2011-06-29T15:34:15.101408 #816] DEBUG -- : GFS: 6 Apr 24 05:36 shm

    crw------- 1 root root  10, 231 Jun 29 15:33 snapshot

    drwxr-xr-x 2 root root

    D, [2011-06-29T15:34:15.103003 #816] DEBUG -- : GFS:      4096 Jun 29 15:33 snd

    lrwxrwxrwx 1 root root       15 Jun 29 15:33 stderr -> /proc/self/fd/2

    D, [2011-06-29T15:34:15.104481 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root       15 Jun 29 15:33 stdin -> /proc/self/fd/0

    lrwxrwxrwx 1 root root       15 Jun 29 15:33 stdout -> /proc/self/fd/1

    D, [2011-06-29T15:34:15.106225 #816] DEBUG -- : GFS: crw-rw-rw- 1 root tty    5,   0 Jun 29 15:33 tty

    crw--w---- 1 root tty    4,   0 Jun 29 15:33 tty0

    crw--w---- 1 roo

    D, [2011-06-29T15:34:15.107656 #816] DEBUG -- : GFS: t tty    4,   1 Jun 29 15:33 tty1

    crw--w---- 1 root tty    4,  10 Jun 29 15:33 tty10

    crw--w-

    D, [2011-06-29T15:34:15.109168 #816] DEBUG -- : GFS: --- 1 root tty    4,  11 Jun 29 15:33 tty11

    crw--w---- 1 root tty    4,  12 Jun 29 15:33 tty12

    D, [2011-06-29T15:34:15.110641 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  13 Jun 29 15:33 tty13

    crw--w---- 1 root tty    4,  14 Jun 29 15:33 tty14

    D, [2011-06-29T15:34:15.112027 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  15 Jun 29 15:33 tty15

    crw--w---- 1 root tty    4,  16 Jun 29 15:33 tty16

    D, [2011-06-29T15:34:15.113540 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  17 Jun 29 15:33 tty17

    crw--w---- 1 root tty    4,  18 Jun 29 15:33 tty18

    D, [2011-06-29T15:34:15.115146 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  19 Jun 29 15:33 tty19

    crw--w---- 1 root tty    4,   2 Jun 29 15:33 tty2

    crw--w--

    D, [2011-06-29T15:34:15.116508 #816] DEBUG -- : GFS: -- 1 root tty    4,  20 Jun 29 15:33 tty20

    crw--w---- 1 root tty    4,  21 Jun 29 15:33 tty21

    cr

    D, [2011-06-29T15:34:15.117951 #816] DEBUG -- : GFS: w--w---- 1 root tty    4,  22 Jun 29 15:33 tty22

    crw--w---- 1 root tty    4,  23 Jun 29 15:33 tty23

    D, [2011-06-29T15:34:15.119433 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  24 Jun 29 15:33 tty24

    crw--w---- 1 root tty    4,  25 Jun 29 15:33 tty25

    D, [2011-06-29T15:34:15.121111 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  26 Jun 29 15:33 tty26

    crw--w---- 1 root tty    4,  27 Jun 29 15:33 tty27

    crw--w---

    D, [2011-06-29T15:34:15.122522 #816] DEBUG -- : GFS: - 1 root tty    4,  28 Jun 29 15:33 tty28

    crw--w---- 1 root tty    4,  29 Jun 29 15:33 tty29

    cr

    D, [2011-06-29T15:34:15.123949 #816] DEBUG -- : GFS: w--w---- 1 root tty    4,   3 Jun 29 15:33 tty3

    crw--w---- 1 root tty    4,  30 Jun 29 15:33 tty30

    D, [2011-06-29T15:34:15.125451 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  31 Jun 29 15:33 tty31

    crw--w---- 1 root tty    4,  32 Jun 29 15:34 tty32

    D, [2011-06-29T15:34:15.127017 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  33 Jun 29 15:34 tty33

    crw--w---- 1 root tty    4,  34 Jun 29 15:33 tty34

    D, [2011-06-29T15:34:15.128645 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  35 Jun 29 15:34 tty35

    crw--w---- 1 root tty    4,  36 Jun 29 15:34 tty36

    D, [2011-06-29T15:34:15.130090 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  37 Jun 29 15:34 tty37

    crw--w---- 1 root tty    4,  38 Jun 29 15:34 tty38

    D, [2011-06-29T15:34:15.131687 #816] DEBUG -- : GFS:

    crw--w---- 1 root tty    4,  39 Jun 29 15:34 tty39

    crw--w---- 1 root tty    4,   4 Jun 29 15:34 tty4

    D, [2011-06-29T15:34:15.133193 #816] DEBUG -- : GFS:

    crw--w---- 1 root tty    4,  40 Jun 29 15:34 tty40

    crw--w---- 1 root tty    4,  41 Jun 29 15:34 tty41

    D, [2011-06-29T15:34:15.134979 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  42 Jun 29 15:34 tty42

    crw--w---- 1 root tty    4,  43 Jun 29 15:34 tty43

    crw--w-

    D, [2011-06-29T15:34:15.136432 #816] DEBUG -- : GFS: --- 1 root tty    4,  44 Jun 29 15:34 tty44

    crw--w---- 1 root tty    4,  45 Jun 29 15:34 tty45

    D, [2011-06-29T15:34:15.138188 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  46 Jun 29 15:34 tty46

    crw--w---- 1 root tty    4,  47 Jun 29 15:34 tty47

    crw--w-

    D, [2011-06-29T15:34:15.139580 #816] DEBUG -- : GFS: --- 1 root tty    4,  48 Jun 29 15:34 tty48

    crw--w---- 1 root tty    4,  49 Jun 29 15:34 tty49

    D, [2011-06-29T15:34:15.141227 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,   5 Jun 29 15:34 tty5

    crw--w---- 1 root tty    4,  50 Jun 29 15:34 tty50

    crw--w--

    D, [2011-06-29T15:34:15.142604 #816] DEBUG -- : GFS: -- 1 root tty    4,  51 Jun 29 15:34 tty51

    crw--w---- 1 root tty    4,  52 Jun 29 15:34 tty52

    cr

    D, [2011-06-29T15:34:15.143932 #816] DEBUG -- : GFS: w--w---- 1 root tty    4,  53 Jun 29 15:34 tty53

    crw--w---- 1 root tty    4,  54 Jun 29 15:34 tty54

    D, [2011-06-29T15:34:15.145446 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  55 Jun 29 15:34 tty55

    crw--w---- 1 root tty    4,  56 Jun 29 15:34 tty56

    D, [2011-06-29T15:34:15.146970 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  57 Jun 29 15:34 tty57

    crw--w---- 1 root tty    4,  58 Jun 29 15:34 tty58

    D, [2011-06-29T15:34:15.148323 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  59 Jun 29 15:34 tty59

    crw--w---- 1 root tty    4,   6 Jun 29 15:34 tty6

    D, [2011-06-29T15:34:15.149787 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  60 Jun 29 15:34 tty60

    crw--w---- 1 root tty    4,  61 Jun 29 15:34 tty61

    D, [2011-06-29T15:34:15.151349 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,  62 Jun 29 15:34 tty62

    crw--w---- 1 root tty    4,  63 Jun 29 15:34 tty63

    D, [2011-06-29T15:34:15.152786 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,   7 Jun 29 15:34 tty7

    crw--w---- 1 root tty    4,   8 Jun 29 15:34 tty8

    D, [2011-06-29T15:34:15.154200 #816] DEBUG -- : GFS: crw--w---- 1 root tty    4,   9 Jun 29 15:34 tty9

    crw------- 1 root root   4,  64 Jun 29 15:33 ttyS0

    D, [2011-06-29T15:34:15.155600 #816] DEBUG -- : GFS: crw------- 1 root root   4,  65 Jun 29 15:33 ttyS1

    crw------- 1 root root   4,  66 Jun 29 15:33 ttyS2

    D, [2011-06-29T15:34:15.157112 #816] DEBUG -- : GFS: crw------- 1 root root   4,  67 Jun 29 15:33 ttyS3

    crw------- 1 root root  10, 223 Jun 29 15:33 uinput

    D, [2011-06-29T15:34:15.158574 #816] DEBUG -- : GFS: crw-rw-rw- 1 root root   1,   9 Jun 29 15:33 urandom

    crw------- 1 root root 251,   0 Jun 29 15:34 usbmon0

    D, [2011-06-29T15:34:15.160143 #816] DEBUG -- : GFS: crw-rw---- 1 root tty    7,   0 Jun 29 15:34 vcs

    crw-rw---- 1 root tty    7,   1 Jun 29 15:34 vcs1

    D, [2011-06-29T15:34:15.161504 #816] DEBUG -- : GFS: crw-rw---- 1 root tty    7, 128 Jun 29 15:34 vcsa

    crw-rw---- 1 root tty    7, 129 Jun 29 15:34 vcsa1

    D, [2011-06-29T15:34:15.163067 #816] DEBUG -- : GFS: brw-rw---- 1 root disk 252,   0 Jun 29 15:34 vda

    brw-rw---- 1 root disk 252,   1 Jun 29 15:34 vda1

    D, [2011-06-29T15:34:15.164425 #816] DEBUG -- : GFS: brw-rw---- 1 root disk 252,   2 Jun 29 15:34 vda2

    brw-rw---- 1 root disk 252,  16 Jun 29 15:34 vdb

    D, [2011-06-29T15:34:15.165925 #816] DEBUG -- : GFS: brw-rw---- 1 root disk 252,  32 Jun 29 15:34 vdc

    crw------- 1 root root  10,  63 Jun 29 15:33 vga_arbiter

    D, [2011-06-29T15:34:15.167357 #816] DEBUG -- : GFS: drwxr-xr-x 2 root root     4096 Jun 29 15:33 virtio-ports

    crw------- 1 root root 252,   1 Jun 29 15:33 vport0p1

    D, [2011-06-29T15:34:15.169120 #816] DEBUG -- : GFS: crw-rw-rw- 1 root root   1,   5 Jun 29 15:33 zero

     

     

    /dev/block:

    D, [2011-06-29T15:34:15.177020 #816] DEBUG -- : GFS: total 0

    lrwxrwxrwx 1 root root 7 Jun 29 15:33 1:0 -> ../ram0

    lrwxrwxrwx 1 root root 7 Jun 29 15:33 1:1 -> ../ra

    D, [2011-06-29T15:34:15.178416 #816] DEBUG -- : GFS: m1

    lrwxrwxrwx 1 root root 8 Jun 29 15:33 1:10 -> ../ram10

    lrwxr

    D, [2011-06-29T15:34:15.179924 #816] DEBUG -- : GFS: wxrwx 1 root root 8 Jun 29 15:33 1:11 -> ../ram11

    lrwxrwxrwx 1 root root 8 Jun 29 15:33 1:12 -> ../ram12

    D, [2011-06-29T15:34:15.181403 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 8 Jun 29 15:33 1:13 -> ../ram13

    lrwxrwxrwx 1 root root 8 Jun 29 15:33 1:14 -> ../ram14

    D, [2011-06-29T15:34:15.183042 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 8 Jun 29 15:33 1:15 -> ../ram15

    lrwxrwxrwx 1 root root 7 Jun 29 15:33 1:2 -> ../ram2

    lr

    D, [2011-06-29T15:34:15.184412 #816] DEBUG -- : GFS: wxrwxrwx 1 root root 7 Jun 29 15:33 1:3 -> ../ram3

    lrwxrwxrwx 1 root root 7 Jun 29 15:33 1:4 -> ../ram4

    D, [2011-06-29T15:34:15.186080 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 7 Jun 29 15:33 1:5 -> ../ram5

    lrwxrwxrwx 1 root root 7 Jun 29 15:33 1:6 -> ../ram6

    D, [2011-06-29T15:34:15.187476 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 7 Jun 29 15:33 1:7 -> ../ram7

    lrwxrwxrwx 1 root root 7 Jun 29 15:34 1:8 -> ../ram8

    D, [2011-06-29T15:34:15.189168 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 7 Jun 29 15:33 1:9 -> ../ram9

    lrwxrwxrwx 1 root root 6 Jun 29 15:34 252:0 -> ../vda

    lrwx

    D, [2011-06-29T15:34:15.190558 #816] DEBUG -- : GFS: rwxrwx 1 root root 7 Jun 29 15:34 252:1 -> ../vda1

    lrwxrwxrwx 1 root root 6 Jun 29 15:34 252:16 -> ../vdb

    D, [2011-06-29T15:34:15.192249 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 7 Jun 29 15:34 252:2 -> ../vda2

    lrwxrwxrwx 1 root root 6 Jun 29 15:34 252:32 -> ../vdc

    lr

    D, [2011-06-29T15:34:15.193631 #816] DEBUG -- : GFS: wxrwxrwx 1 root root 8 Jun 29 15:33 7:0 -> ../loop0

    lrwxrwxrwx 1 root root 8 Jun 29 15:33 7:1 -> ../loop1

    D, [2011-06-29T15:34:15.195116 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 8 Jun 29 15:33 7:2 -> ../loop2

    lrwxrwxrwx 1 root root 8 Jun 29 15:33 7:3 -> ../loop3

    D, [2011-06-29T15:34:15.196596 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 8 Jun 29 15:33 7:4 -> ../loop4

    lrwxrwxrwx 1 root root 8 Jun 29 15:33 7:5 -> ../loop5

    D, [2011-06-29T15:34:15.198066 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 8 Jun 29 15:33 7:6 -> ../loop6

    lrwxrwxrwx 1 root root 8 Jun 29 15:33 7:7 -> ../loop7

    D, [2011-06-29T15:34:15.200008 #816] DEBUG -- : GFS:

    /dev/char:

    D, [2011-06-29T15:34:15.220400 #816] DEBUG -- : GFS: total 0

    lrwxrwxrwx 1 root root 11 Jun 29 15:33 10:134 -> ../apm_bios

    lrwxrw

    D, [2011-06-29T15:34:15.221926 #816] DEBUG -- : GFS: xrwx 1 root root  8 Jun 29 15:33 10:144 -> ../nvram

    lrwxrwxrwx 1 root root 11 Jun 29 15:33 10:231 -> ../snapshot

    D, [2011-06-29T15:34:15.223442 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  9 Jun 29 15:33 10:235 -> ../autofs

    lrwxrwxrwx 1 root root 17 Jun 29 15:33 10:236 -> ../mapper/control

    D, [2011-06-29T15:34:15.225157 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 21 Jun 29 15:33 10:60 -> ../network_throughput

    lrwxrwxrwx 1 root root 18 Jun 29 15:33 10:61 -> ../network_latency

    D, [2011-06-29T15:34:15.226746 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 18 Jun 29 15:33 10:62 -> ../cpu_dma_latency

    lrwxrwxrwx 1 root root 14 Jun 29 15:33 10:63 -> ../vga_arbiter

    D, [2011-06-29T15:34:15.228349 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 15 Jun 29 15:34 13:32 -> ../input/mouse0

    lrwxrwxrwx 1 root root 13 Jun 29 15:34 13:63 -> ../input/mice

    D, [2011-06-29T15:34:15.230185 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 15 Jun 29 15:34 13:64 -> ../input/event0

    lrwxrwxrwx 1 root root 15 Jun 29 15:34 13:65 -> ../input/event1

    D, [2011-06-29T15:34:15.231660 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 13 Jun 29 15:33 162:0 -> ../raw/rawctl

    lrwxrwxrwx 1 root root  6 Jun 29 15:33 1:1 -> ../mem

    D, [2011-06-29T15:34:15.233238 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  7 Jun 29 15:33 1:11 -> ../kmsg

    lrwxrwxrwx 1 root root  9 Jun 29 15:33 1:12 -> ../oldmem

    D, [2011-06-29T15:34:15.235015 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  7 Jun 29 15:33 1:3 -> ../null

    lrwxrwxrwx 1 root root  7 Jun 29 15:33 1:4 -> ../port

    lrwxrwx

    D, [2011-06-29T15:34:15.236355 #816] DEBUG -- : GFS: rwx 1 root root  7 Jun 29 15:33 1:5 -> ../zero

    lrwxrwxrwx 1 root root  7 Jun 29 15:33 1:7 -> ../full

    D, [2011-06-29T15:34:15.238004 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  9 Jun 29 15:33 1:8 -> ../random

    lrwxrwxrwx 1 root root 10 Jun 29 15:33 1:9 -> ../urandom

    D, [2011-06-29T15:34:15.239430 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 12 Jun 29 15:33 202:0 -> ../cpu/0/msr

    lrwxrwxrwx 1 root root 14 Jun 29 15:33 203:0 -> ../cpu/0/cpuid

    D, [2011-06-29T15:34:15.241143 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 10 Jun 29 15:34 251:0 -> ../usbmon0

    lrwxrwxrwx 1 root root 11 Jun 29 15:33 252:1 -> ../vport0p1

    D, [2011-06-29T15:34:15.242652 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  7 Jun 29 15:33 254:0 -> ../rtc0

    lrwxrwxrwx 1 root root  7 Jun 29 15:33 4:0 -> ../tty0

    D, [2011-06-29T15:34:15.244220 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  7 Jun 29 15:33 4:1 -> ../tty1

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:10 -> ../tty10

    D, [2011-06-29T15:34:15.245777 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:11 -> ../tty11

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:12 -> ../tty12

    D, [2011-06-29T15:34:15.247292 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:13 -> ../tty13

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:14 -> ../tty14

    D, [2011-06-29T15:34:15.248922 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:15 -> ../tty15

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:16 -> ../tty16

    lrwxr

    D, [2011-06-29T15:34:15.250351 #816] DEBUG -- : GFS: wxrwx 1 root root  8 Jun 29 15:33 4:17 -> ../tty17

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:18 -> ../tty18

    D, [2011-06-29T15:34:15.251994 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:19 -> ../tty19

    lrwxrwxrwx 1 root root  7 Jun 29 15:33 4:2 -> ../tty2

    lrwxrwxr

    D, [2011-06-29T15:34:15.253348 #816] DEBUG -- : GFS: wx 1 root root  8 Jun 29 15:33 4:20 -> ../tty20

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:21 -> ../tty21

    D, [2011-06-29T15:34:15.254932 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:22 -> ../tty22

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:23 -> ../tty23

    D, [2011-06-29T15:34:15.256337 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:24 -> ../tty24

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:25 -> ../tty25

    D, [2011-06-29T15:34:15.257907 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:26 -> ../tty26

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:27 -> ../tty27

    D, [2011-06-29T15:34:15.259309 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:28 -> ../tty28

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:29 -> ../tty29

    D, [2011-06-29T15:34:15.260873 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  7 Jun 29 15:33 4:3 -> ../tty3

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:30 -> ../tty30

    D, [2011-06-29T15:34:15.262269 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:31 -> ../tty31

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:32 -> ../tty32

    D, [2011-06-29T15:34:15.263656 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:33 -> ../tty33

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:34 -> ../tty34

    D, [2011-06-29T15:34:15.265187 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:35 -> ../tty35

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:36 -> ../tty36

    D, [2011-06-29T15:34:15.266620 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:37 -> ../tty37

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:38 -> ../tty38

    D, [2011-06-29T15:34:15.268223 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:39 -> ../tty39

    lrwxrwxrwx 1 root root  7 Jun 29 15:34 4:4 -> ../tty4

    D, [2011-06-29T15:34:15.269618 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:40 -> ../tty40

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:41 -> ../tty41

    D, [2011-06-29T15:34:15.271174 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:42 -> ../tty42

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:43 -> ../tty43

    D, [2011-06-29T15:34:15.272904 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:44 -> ../tty44

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:45 -> ../tty45

    D, [2011-06-29T15:34:15.274268 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:46 -> ../tty46

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:47 -> ../tty47

    D, [2011-06-29T15:34:15.275753 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:48 -> ../tty48

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:49 -> ../tty49

    D, [2011-06-29T15:34:15.277251 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  7 Jun 29 15:34 4:5 -> ../tty5

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:50 -> ../tty50

    D, [2011-06-29T15:34:15.278903 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:51 -> ../tty51

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:52 -> ../tty52

    D, [2011-06-29T15:34:15.280314 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:53 -> ../tty53

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:54 -> ../tty54

    D, [2011-06-29T15:34:15.282089 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:55 -> ../tty55

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:56 -> ../tty56

    lrwxrwxrwx

    D, [2011-06-29T15:34:15.283470 #816] DEBUG -- : GFS:  1 root root  8 Jun 29 15:34 4:57 -> ../tty57

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:58 -> ../tty58

    D, [2011-06-29T15:34:15.285086 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:59 -> ../tty59

    lrwxrwxrwx 1 root root  7 Jun 29 15:34 4:6 -> ../tty6

    lr

    D, [2011-06-29T15:34:15.286411 #816] DEBUG -- : GFS: wxrwxrwx 1 root root  8 Jun 29 15:34 4:60 -> ../tty60

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:61 -> ../tty61

    D, [2011-06-29T15:34:15.288047 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:62 -> ../tty62

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 4:63 -> ../tty63

    D, [2011-06-29T15:34:15.289396 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:64 -> ../ttyS0

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:65 -> ../ttyS1

    D, [2011-06-29T15:34:15.291017 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:66 -> ../ttyS2

    lrwxrwxrwx 1 root root  8 Jun 29 15:33 4:67 -> ../ttyS3

    D, [2011-06-29T15:34:15.292424 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  7 Jun 29 15:34 4:7 -> ../tty7

    lrwxrwxrwx 1 root root  7 Jun 29 15:34 4:8 -> ../tty8

    D, [2011-06-29T15:34:15.294044 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  7 Jun 29 15:34 4:9 -> ../tty9

    lrwxrwxrwx 1 root root  6 Jun 29 15:33 5:0 -> ../tty

    lr

    D, [2011-06-29T15:34:15.295390 #816] DEBUG -- : GFS: wxrwxrwx 1 root root 10 Jun 29 15:33 5:1 -> ../console

    lrwxrwxrwx 1 root root  7 Jun 29 15:33 5:2 -> ../ptmx

    D, [2011-06-29T15:34:15.297005 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  6 Jun 29 15:34 7:0 -> ../vcs

    lrwxrwxrwx 1 root root  7 Jun 29 15:34 7:1 -> ../vcs1

    D, [2011-06-29T15:34:15.298507 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  7 Jun 29 15:34 7:128 -> ../vcsa

    lrwxrwxrwx 1 root root  8 Jun 29 15:34 7:129 -> ../vcsa1

    D, [2011-06-29T15:34:15.300313 #816] DEBUG -- : GFS:

    /dev/cpu:

    D, [2011-06-29T15:34:15.302096 #816] DEBUG -- : GFS: total 4

    drwxr-xr-x 2 root root    4096 Jun 29 15:33 0

    crw------- 1 root root 10, 184 Jun 29 15:33 microcode

    D, [2011-06-29T15:34:15.303502 #816] DEBUG -- : GFS:

     

     

    /dev/cpu/0:

    D, [2011-06-29T15:34:15.305282 #816] DEBUG -- : GFS: total 0

    cr--r--r-- 1 root root 203, 0 Jun 29 15:33 cpuid

    crw------- 1 root root 202, 0 Jun 29 15:33 msr

    D, [2011-06-29T15:34:15.306613 #816] DEBUG -- : GFS:

     

     

    /dev/disk:

    D, [2011-06-29T15:34:15.308432 #816] DEBUG -- : GFS: total 12

    drwxr-xr-x 2 root root 4096 Jun 29 15:34 by-label

    drwxr-xr-x 2 root root 4096

    D, [2011-06-29T15:34:15.310015 #816] DEBUG -- : GFS:  Jun 29 15:34 by-path

    drwxr-xr-x 2 root root 4096 Jun 29 15:34 by-uuid

     

     

    /dev/dis

    D, [2011-06-29T15:34:15.311355 #816] DEBUG -- : GFS: k/by-label:

    t

    D, [2011-06-29T15:34:15.312745 #816] DEBUG -- : GFS: otal 0

    lrwxrwxrwx 1 root root 10 Jun 29 15:34 79d3d2d4 -> ../../vda1

    lrwxrwxrwx 1 root ro

    D, [2011-06-29T15:34:15.314125 #816] DEBUG -- : GFS: ot 10 Jun 29 15:34 d8863c17 -> ../../vda2

     

     

    /dev/disk/by-path:

    D, [2011-06-29T15:34:15.317073 #816] DEBUG -- : GFS: total 0

    lrwxrwxrwx 1 root root  9 Jun 29 15:34 pci-0000:00:04.0-virtio-pci-virtio2 -> ../../vda

    lrwxrwxrwx 1 ro

    D, [2011-06-29T15:34:15.318478 #816] DEBUG -- : GFS: ot root 10 Jun 29 15:34 pci-0000:00:04.0-virtio-pci-virtio2-part1 -> ../../vda1

    lrwxrwxrwx 1 root root 10 Jun 29 15:34 pci-0000:00:04.0

    D, [2011-06-29T15:34:15.320008 #816] DEBUG -- : GFS: -virtio-pci-virtio2-part2 -> ../../vda2

    lrwxrwxrwx 1 root root  9 Jun 29 15:34 pci-0000:00:05.0-virtio-pci-virtio3 -> ../../vdb

    D, [2011-06-29T15:34:15.321481 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  9 Jun 29 15:34 pci-0000:00:06.0-virtio-pci-virtio4 -> ../../vdc

     

     

    /dev/disk/by-uui

    D, [2011-06-29T15:34:15.323081 #816] DEBUG -- : GFS: d:

    D, [2011-06-29T15:34:15.324573 #816] DEBUG -- : GFS: total 0

    lrwxrwxrwx 1 root root 10 Jun 29 15:34 61f43e3f-8860-4f72-a73f-6ab2fa73d0ab -> ../../vda2

    D, [2011-06-29T15:34:15.326236 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root  9 Jun 29 15:34 b9ba6a3e-3b23-4510-b08e-552661a93da7 -> ../../vdc

    lrwxrwxrwx 1 root root 10 Jun 29 15:34 ba254e2b-bc04-45fb-ab7a-e6cab5513050 -> ../

    D, [2011-06-29T15:34:15.327578 #816] DEBUG -- : GFS: ../vda1

     

     

    /dev/input:

    D, [2011-06-29T15:34:15.330180 #816] DEBUG -- : GFS: total 4

    drwxr-xr-x 2 root root   4096 Jun 29 15:34 by-path

    crw-r----- 1 root root 13, 64 Jun 29 15:

    D, [2011-06-29T15:34:15.331520 #816] DEBUG -- : GFS: 34 event0

    crw-r----- 1 root root 13, 65 Jun 29 15:34 event1

    D, [2011-06-29T15:34:15.333204 #816] DEBUG -- : GFS: crw-r----- 1 root root 13, 63 Jun 29 15:34 mice

    crw-r----- 1 root root 13, 32 Jun 29 15:34 mouse0

     

     

    D, [2011-06-29T15:34:15.334732 #816] DEBUG -- : GFS: /dev/input/by-path:

    D, [2011-06-29T15:34:15.336538 #816] DEBUG -- : GFS: total 0

    lrwxrwxrwx 1 root root 9 Jun 29 15:34 platform-i8042-serio-0-event-kbd -> ../event0

    D, [2011-06-29T15:34:15.338317 #816] DEBUG -- : GFS: lrwxrwxrwx 1 root root 9 Jun 29 15:34 platform-i8042-serio-1-event-mouse -> ../event1

    lrwxrwxrwx 1 root root 9 Jun 29 15:34 platform-i8042-serio-1-mouse -> ../mouse0

    D, [2011-06-29T15:34:15.340166 #816] DEBUG -- : GFS:

    /dev/mapper:

    total 0

    crw------- 1

    D, [2011-06-29T15:34:15.341690 #816] DEBUG -- : GFS: root root 10, 236 Jun 29 15:33 control

     

     

    /dev/net:

    D, [2011-06-29T15:34:15.343488 #816] DEBUG -- : GFS: total 0

    crw-rw-rw- 1 root root 10, 200 Jun 29 15:33 tun

     

     

    /d

    D, [2011-06-29T15:34:15.344955 #816] DEBUG -- : GFS: ev/pts:

    total 0

     

     

    /de

    D, [2011-06-29T15:34:15.346311 #816] DEBUG -- : GFS: v/raw:

    total 0

    crw-rw---- 1

    D, [2011-06-29T15:34:15.347702 #816] DEBUG -- : GFS:  root disk 162, 0 Jun 29 15:33 rawctl

     

     

    /dev/shm:

    total 0

    D, [2011-06-29T15:34:15.349168 #816] DEBUG -- : GFS:

     

     

    /dev/snd:

    D, [2011-06-29T15:34:15.350586 #816] DEBUG -- : GFS: total 0

    crw------- 1 root root 116,  1 Jun 29 15:33 seq

    crw------- 1 root root 116, 33

    D, [2011-06-29T15:34:15.351971 #816] DEBUG -- : GFS: Jun 29 15:33 timer

     

     

    /dev/virtio-ports:

    D, [2011-06-29T15:34:15.353622 #816] DEBUG -- : GFS: total 0

    lrwxrwxrwx 1 root root 11 Jun 29 15:33 org.libguestfs.channel.0 -> ../vport0p1

    D, [2011-06-29T15:34:15.414665 #816] DEBUG -- : GFS: rootfs / rootfs rw 0 0

    /dev/root / ext2 rw,noatime,errors=continue 0 0

    /proc /proc proc rw,relatime 0 0

    /sys /sys sysfs rw,relatime 0 0

    D, [2011-06-29T15:34:15.416128 #816] DEBUG -- : GFS:

    none /selinux selinuxfs rw,relatime 0 0

    D, [2011-06-29T15:34:15.746776 #816] DEBUG -- : GFS:   No volume groups found

    D, [2011-06-29T15:34:15.748296 #816] DEBUG -- : GFS:

    D, [2011-06-29T15:34:15.919476 #816] DEBUG -- : GFS:   No volume groups found

    D, [2011-06-29T15:34:16.020418 #816] DEBUG -- : GFS: eth0      Link encap:Ethernet  HWaddr 52:54:00:12:34:56 

    D, [2011-06-29T15:34:16.022957 #816] DEBUG -- : GFS:           inet addr:169.254.2.10  Bcast:169.254.255.255  Mask:255.255.0.0

    D, [2011-06-29T15:34:16.025111 #816] DEBUG -- : GFS:           UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1

              RX packets:0 errors:0 dropped:0 o

    D, [2011-06-29T15:34:16.026425 #816] DEBUG -- : GFS: verruns:0 frame:0

              TX packets:0 errors:0 dropped:0 overruns:0 carrier:0

    D, [2011-06-29T15:34:16.028183 #816] DEBUG -- : GFS:           collisions:0 txqueuelen:1000

              RX bytes:0 (0.0 b)  TX bytes:0 (0.0 b)

     

     

    D, [2011-06-29T15:34:16.031258 #816] DEBUG -- : GFS: lo        Link encap:Local Loopback 

              inet addr:127.0.0.1  Mask:255.0.0.0

    D, [2011-06-29T15:34:16.033017 #816] DEBUG -- : GFS:           UP LOOPBACK RUNNING  MTU:16436  Metric:1

              RX packets:0 errors:0 dropped:0 overruns:0 frame:0

              TX packets:0 errors:0 dropped:0 over

    D, [2011-06-29T15:34:16.034350 #816] DEBUG -- : GFS: runs:0 carrier:0

              collisions:0 txqueuelen:0

              RX bytes:0 (0.0 b)  TX bytes:0 (0.0 b)

    D, [2011-06-29T15:34:16.035970 #816] DEBUG -- : GFS:

    D, [2011-06-29T15:34:16.129059 #816] DEBUG -- : GFS: Kernel IP routing table

    D, [2011-06-29T15:34:16.130667 #816] DEBUG -- : GFS: Destination     Gateway         Genmask         Flags   MSS Window  irtt Iface

    D, [2011-06-29T15:34:16.143452 #816] DEBUG -- : GFS: 169.254.0.0     0.0.0.0         255.255.0.0     U         0 0          0 eth0

    0.0.0.0         169.254.2.2     0.0.0.0         UG        0 0          0 eth0

    D, [2011-06-29T15:34:16.145166 #816] DEBUG -- : GFS:

    D, [2011-06-29T15:34:16.212162 #816] DEBUG -- : GFS: Module                  Size  Used by

    D, [2011-06-29T15:34:16.219963 #816] DEBUG -- : GFS: i2c_piix4               9270  0

    D, [2011-06-29T15:34:16.223370 #816] DEBUG -- : GFS: i2c_core               21344  1 i2c_piix4

    D, [2011-06-29T15:34:16.226421 #816] DEBUG -- : GFS: sym53c8xx              59715  0

    D, [2011-06-29T15:34:16.230754 #816] DEBUG -- : GFS:

    virtio_net             10627  0

    virtio_blk              4067  1

    D, [2011-06-29T15:34:16.232236 #816] DEBUG -- : GFS: virtio_balloon          3499  0

    virtio_rng              1582  0

    scsi_transport_spi     17542 

    D, [2011-06-29T15:34:16.233633 #816] DEBUG -- : GFS: 1 sym53c8xx

    libcrc32c                818  0

    D, [2011-06-29T15:34:16.234998 #816] DEBUG -- : GFS:

    ext2                   50364  1

    crc7                     993  0

    D, [2011-06-29T15:34:16.236385 #816] DEBUG -- : GFS:

    crc_itu_t               1251  0

    crc_ccitt               1253  0

    D, [2011-06-29T15:34:16.428167 #816] DEBUG -- : GFS: Wed Jun 29 15:34:05 EDT 2011

    D, [2011-06-29T15:34:16.440091 #816] DEBUG -- : GFS: uptime:

    D, [2011-06-29T15:34:16.513095 #816] DEBUG -- : GFS: 14.74 0.60

    D, [2011-06-29T15:34:16.705092 #816] DEBUG -- : GFS: verbose daemon enabled

    D, [2011-06-29T15:34:16.709021 #816] DEBUG -- : GFS: linux commmand line: panic=1 console=ttyS0 udevtimeout=300 noapic acpi=off printk.time=1 cgroup_disable=memory selinux=1 enforcing=0 guestfs_verbose=1 TERM

    =xterm-color noapic

     

     

    D, [2011-06-29T15:34:16.717577 #816] DEBUG -- : GFS: recv_from_daemon: received GUESTFS_LAUNCH_FLAG

    D, [2011-06-29T15:34:16.717802 #816] DEBUG -- : GFS: [64084ms] appliance is up

    T, [2011-06-29T15:34:16.717994 #816] TRACE -- : GFS: launch = 0

    T, [2011-06-29T15:34:16.718118 #816] TRACE -- : Guestfs launched.

    T, [2011-06-29T15:34:16.720489 #816] TRACE -- : GFS: list_devices

    D, [2011-06-29T15:34:16.722423 #816] DEBUG -- : GFS: send_to_daemon: 44 bytes: 00 00 00 28 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 07 | 00 00 00 00 | ...

    D, [2011-06-29T15:34:16.726131 #816] DEBUG -- : GFS: guestfsd: main_loop: new request, len 0x28

    D, [2011-06-29T15:34:16.733848 #816] DEBUG -- : GFS: recv_from_daemon: 68 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 07 | 00 00 00 01 | 00 12 34 00 | ...

    T, [2011-06-29T15:34:16.734033 #816] TRACE -- : GFS: list_devices = ["/dev/vda", "/dev/vdb"]

    T, [2011-06-29T15:34:16.734209 #816] TRACE -- : GFS: list_partitions

    D, [2011-06-29T15:34:16.734696 #816] DEBUG -- : GFS: send_to_daemon: 44 bytes: 00 00 00 28 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 08 | 00 00 00 00 | ...

    D, [2011-06-29T15:34:16.737699 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 7 (list_devices) took 0.00 seconds

    guestfsd: main_loop: new request, len 0x28

    D, [2011-06-29T15:34:16.751218 #816] DEBUG -- : GFS: recv_from_daemon: 76 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 08 | 00 00 00 01 | 00 12 34 01 | ...

    T, [2011-06-29T15:34:16.751408 #816] TRACE -- : GFS: list_partitions = ["/dev/vda1", "/dev/vda2"]

    D, [2011-06-29T15:34:16.751923 #816] DEBUG -- : Loading SELinux policy to sync filesystem...

    T, [2011-06-29T15:34:16.752075 #816] TRACE -- : Mounting partitions...

    T, [2011-06-29T15:34:16.752371 #816] TRACE -- : GFS: list_partitions

    D, [2011-06-29T15:34:16.752841 #816] DEBUG -- : GFS: send_to_daemon: 44 bytes: 00 00 00 28 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 08 | 00 00 00 00 | ...

    D, [2011-06-29T15:34:16.757872 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 8 (list_partitions) took 0.01 seconds

    D, [2011-06-29T15:34:16.759696 #816] DEBUG -- : GFS: guestfsd: main_loop: new request, len 0x28

    D, [2011-06-29T15:34:16.762330 #816] DEBUG -- : GFS: recv_from_daemon: 76 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 08 | 00 00 00 01 | 00 12 34 02 | ...

    T, [2011-06-29T15:34:16.762507 #816] TRACE -- : GFS: list_partitions = ["/dev/vda1", "/dev/vda2"]

    T, [2011-06-29T15:34:16.762985 #816] TRACE -- : Mounting /dev/vda1 partition to /...

    T, [2011-06-29T15:34:16.764320 #816] TRACE -- : GFS: mount_options "" "/dev/vda1" "/"

    D, [2011-06-29T15:34:16.764704 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 00 | ...

    D, [2011-06-29T15:34:16.766055 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 8 (list_partitions) took 0.00 seconds

    D, [2011-06-29T15:34:16.768064 #816] DEBUG -- : GFS: guestfsd: main_loop: new request, len 0x44

    D, [2011-06-29T15:34:16.774583 #816] DEBUG -- : GFS: mount -o  /dev/vda1 /sysroot/

    D, [2011-06-29T15:34:17.170148 #816] DEBUG -- : GFS: [   15.392125] EXT4-fs (vda1): recovery complete

    D, [2011-06-29T15:34:17.180964 #816] DEBUG -- : GFS: [   15.402884] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null)

    D, [2011-06-29T15:34:17.217140 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 01 | 00 12 34 03 | ...

    T, [2011-06-29T15:34:17.217307 #816] TRACE -- : GFS: mount_options = 0

    T, [2011-06-29T15:34:17.218255 #816] TRACE -- : GFS: set_e2label "/dev/vda1" "79d3d2d4"

    D, [2011-06-29T15:34:17.218579 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 00 | ...

    D, [2011-06-29T15:34:17.220725 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 74 (mount_options) took 0.43 seconds

    guestfsd: main_loop: new request, len 0x44

    D, [2011-06-29T15:34:17.225880 #816] DEBUG -- : GFS: e2label /dev/vda1 79d3d2d4

    D, [2011-06-29T15:34:17.424647 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 01 | 00 12 34 04 | ...

    T, [2011-06-29T15:34:17.424948 #816] TRACE -- : GFS: set_e2label = 0

    T, [2011-06-29T15:34:17.425085 #816] TRACE -- : Partition mounted.

    T, [2011-06-29T15:34:17.425241 #816] TRACE -- : Mounting /dev/vda2 partition to /SpeechMagic...

    T, [2011-06-29T15:34:17.425399 #816] TRACE -- : GFS: mount_options "" "/dev/vda2" "/SpeechMagic"

    D, [2011-06-29T15:34:17.425935 #816] DEBUG -- : GFS: send_to_daemon: 80 bytes: 00 00 00 4c | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 00 | ...

    D, [2011-06-29T15:34:17.428021 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 80 (set_e2label) took 0.20 seconds

    guestfsd: main_loop: new request, len 0x4c

    D, [2011-06-29T15:34:17.446082 #816] DEBUG -- : GFS: mount -o  /dev/vda2 /sysroot/SpeechMagic

    D, [2011-06-29T15:34:17.725789 #816] DEBUG -- : GFS: [   15.946757] EXT4-fs (vda2): recovery complete

    [   15.947095] EXT4-fs (vda2): mounted filesystem with ordered data mode. Opts: (null)

    D, [2011-06-29T15:34:17.801089 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 01 | 00 12 34 05 | ...

    T, [2011-06-29T15:34:17.801252 #816] TRACE -- : GFS: mount_options = 0

    T, [2011-06-29T15:34:17.802446 #816] TRACE -- : GFS: set_e2label "/dev/vda2" "d8863c17"

    D, [2011-06-29T15:34:17.802854 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 00 | ...

    D, [2011-06-29T15:34:17.805578 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 74 (mount_options) took 0.37 seconds

    D, [2011-06-29T15:34:17.807020 #816] DEBUG -- : GFS:

    D, [2011-06-29T15:34:17.809864 #816] DEBUG -- : GFS: guestfsd: main_loop: new request, len 0x44

    D, [2011-06-29T15:34:17.814698 #816] DEBUG -- : GFS: e2label /dev/vda2 d8863c17

    D, [2011-06-29T15:34:18.011111 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 01 | 00 12 34 06 | ...

    T, [2011-06-29T15:34:18.011292 #816] TRACE -- : GFS: set_e2label = 0

    T, [2011-06-29T15:34:18.011422 #816] TRACE -- : Partition mounted.

    T, [2011-06-29T15:34:18.011770 #816] TRACE -- : GFS: exists "/etc/sysconfig/selinux"

    D, [2011-06-29T15:34:18.012112 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 24 | 00 00 00 00 | ...

    D, [2011-06-29T15:34:18.014135 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 80 (set_e2label) took 0.20 seconds

    guestfsd: main_loop: new request, len 0x44

    D, [2011-06-29T15:34:18.137694 #816] DEBUG -- : GFS: recv_from_daemon: 44 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 24 | 00 00 00 01 | 00 12 34 07 | ...

    T, [2011-06-29T15:34:18.137878 #816] TRACE -- : GFS: exists = 1

    T, [2011-06-29T15:34:18.138017 #816] TRACE -- : Loading SElinux policy...

    T, [2011-06-29T15:34:18.138200 #816] TRACE -- : GFS: aug_init "/" 32

    D, [2011-06-29T15:34:18.138499 #816] DEBUG -- : GFS: send_to_daemon: 56 bytes: 00 00 00 34 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 10 | 00 00 00 00 | ...

    D, [2011-06-29T15:34:18.140620 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 36 (exists) took 0.10 seconds

    guestfsd: main_loop: new request, len 0x34

    D, [2011-06-29T15:34:44.566798 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 10 | 00 00 00 01 | 00 12 34 08 | ...

    T, [2011-06-29T15:34:44.566970 #816] TRACE -- : GFS: aug_init = 0

    T, [2011-06-29T15:34:44.567133 #816] TRACE -- : GFS: aug_rm "/augeas/load//incl[. != '/etc/sysconfig/selinux']"

    D, [2011-06-29T15:34:44.567436 #816] DEBUG -- : GFS: send_to_daemon: 100 bytes: 00 00 00 60 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 16 | 00 00 00 00 | ...

    D, [2011-06-29T15:34:44.569860 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 16 (aug_init) took 4.77 seconds

    guestfsd: main_loop: new request, len 0x60

    D, [2011-06-29T15:34:44.577648 #816] DEBUG -- : GFS: recv_from_daemon: 44 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 16 | 00 00 00 01 | 00 12 34 09 | ...

    T, [2011-06-29T15:34:44.577785 #816] TRACE -- : GFS: aug_rm = 0

    T, [2011-06-29T15:34:44.578021 #816] TRACE -- : GFS: aug_load

    D, [2011-06-29T15:34:44.578329 #816] DEBUG -- : GFS: send_to_daemon: 44 bytes: 00 00 00 28 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 1b | 00 00 00 00 | ...

    D, [2011-06-29T15:34:44.580238 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 22 (aug_rm) took 0.00 seconds

    guestfsd: main_loop: new request, len 0x28

    D, [2011-06-29T15:35:06.773650 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 1b | 00 00 00 01 | 00 12 34 0a | ...

    T, [2011-06-29T15:35:06.773820 #816] TRACE -- : GFS: aug_load = 0

    T, [2011-06-29T15:35:06.774078 #816] TRACE -- : GFS: aug_get "/files/etc/sysconfig/selinux/SELINUX"

    D, [2011-06-29T15:35:06.774369 #816] DEBUG -- : GFS: send_to_daemon: 84 bytes: 00 00 00 50 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 13 | 00 00 00 00 | ...

    D, [2011-06-29T15:35:06.776518 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 27 (aug_load) took 11.22 seconds

    guestfsd: main_loop: new request, len 0x50

    D, [2011-06-29T15:35:06.777794 #816] DEBUG -- : GFS: recv_from_daemon: 56 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 13 | 00 00 00 01 | 00 12 34 0b | ...

    T, [2011-06-29T15:35:06.777994 #816] TRACE -- : GFS: aug_get = "permissive"

    T, [2011-06-29T15:35:06.778166 #816] TRACE -- : GFS: sh "/usr/sbin/load_policy"

    D, [2011-06-29T15:35:06.778484 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 6f | 00 00 00 00 | ...

    D, [2011-06-29T15:35:06.780285 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds

    guestfsd: main_loop: new request, len 0x44

    D, [2011-06-29T15:35:06.784017 #816] DEBUG -- : GFS: mount --bind /dev /sysroot/dev

    D, [2011-06-29T15:35:06.905296 #816] DEBUG -- : GFS: mount --bind /dev/pts /sysroot/dev/pts

    D, [2011-06-29T15:35:07.003493 #816] DEBUG -- : GFS: mount --bind /proc /sysroot/proc

    D, [2011-06-29T15:35:07.155966 #816] DEBUG -- : GFS: mount --bind /selinux /sysroot/selinux

    D, [2011-06-29T15:35:07.307261 #816] DEBUG -- : GFS: mount --bind /sys /sysroot/sys

    D, [2011-06-29T15:35:07.456231 #816] DEBUG -- : GFS: /bin/sh -c /usr/sbin/load_policy

    D, [2011-06-29T15:35:29.105041 #816] DEBUG -- : GFS: [   36.983021] SELinux:  Permission read_policy in class security not defined in policy.

    D, [2011-06-29T15:35:29.108346 #816] DEBUG -- : GFS: [   36.986179] SELinux:  Permission syslog in class capability2 not defined in policy.

    [   36.986322] SELinux: the above unknown classes and permissions will be allowed

    D, [2011-06-29T15:35:29.179090 #816] DEBUG -- : GFS: [   37.057006] type=1403 audit(1309376067.857:2): policy loaded auid=4294967295 ses=4294967295

    D, [2011-06-29T15:35:29.219377 #816] DEBUG -- : GFS: umount /sysroot/sys

    D, [2011-06-29T15:35:29.374630 #816] DEBUG -- : GFS: umount /sysroot/selinux

    D, [2011-06-29T15:35:29.525355 #816] DEBUG -- : GFS: umount /sysroot/proc

    D, [2011-06-29T15:35:29.671276 #816] DEBUG -- : GFS: umount /sysroot/dev/pts

    D, [2011-06-29T15:35:29.810361 #816] DEBUG -- : GFS: umount /sysroot/dev

    D, [2011-06-29T15:35:29.950734 #816] DEBUG -- : GFS: recv_from_daemon: 44 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 6f | 00 00 00 01 | 00 12 34 0c | ...

    T, [2011-06-29T15:35:29.950945 #816] TRACE -- : GFS: sh = ""

    T, [2011-06-29T15:35:29.951076 #816] TRACE -- : SElinux policy loaded.

    T, [2011-06-29T15:35:29.951234 #816] TRACE -- : GFS: aug_close

    D, [2011-06-29T15:35:29.951511 #816] DEBUG -- : GFS: send_to_daemon: 44 bytes: 00 00 00 28 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 1a | 00 00 00 00 | ...

    D, [2011-06-29T15:35:29.953593 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 111 (sh) took 5.47 seconds

    guestfsd: main_loop: new request, len 0x28

    D, [2011-06-29T15:35:30.279176 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 1a | 00 00 00 01 | 00 12 34 0d | ...

    T, [2011-06-29T15:35:30.279358 #816] TRACE -- : GFS: aug_close = 0

    T, [2011-06-29T15:35:30.279512 #816] TRACE -- : GFS: list_partitions

    D, [2011-06-29T15:35:30.280024 #816] DEBUG -- : GFS: send_to_daemon: 44 bytes: 00 00 00 28 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 08 | 00 00 00 00 | ...

    D, [2011-06-29T15:35:30.282037 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 26 (aug_close) took 0.31 seconds

    guestfsd: main_loop: new request, len 0x28

    D, [2011-06-29T15:35:30.289422 #816] DEBUG -- : GFS: recv_from_daemon: 76 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 08 | 00 00 00 01 | 00 12 34 0e | ...

    T, [2011-06-29T15:35:30.289746 #816] TRACE -- : GFS: list_partitions = ["/dev/vda1", "/dev/vda2"]

    T, [2011-06-29T15:35:30.290015 #816] TRACE -- : Unmounting partitions...

    T, [2011-06-29T15:35:30.290128 #816] TRACE -- : Unmounting partition /dev/vda2...

    T, [2011-06-29T15:35:30.290314 #816] TRACE -- : GFS: umount "/dev/vda2"

    D, [2011-06-29T15:35:30.290630 #816] DEBUG -- : GFS: send_to_daemon: 60 bytes: 00 00 00 38 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 2d | 00 00 00 00 | ...

    D, [2011-06-29T15:35:30.292458 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 8 (list_partitions) took 0.00 seconds

    guestfsd: main_loop: new request, len 0x38

    D, [2011-06-29T15:35:30.294876 #816] DEBUG -- : GFS: umount /dev/vda2

    D, [2011-06-29T15:35:30.467352 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 2d | 00 00 00 01 | 00 12 34 0f | ...

    T, [2011-06-29T15:35:30.467753 #816] TRACE -- : GFS: umount = 0

    T, [2011-06-29T15:35:30.467876 #816] TRACE -- : Partition unmounted.

    T, [2011-06-29T15:35:30.468014 #816] TRACE -- : Unmounting partition /dev/vda1...

    T, [2011-06-29T15:35:30.468159 #816] TRACE -- : GFS: umount "/dev/vda1"

    D, [2011-06-29T15:35:30.468448 #816] DEBUG -- : GFS: send_to_daemon: 60 bytes: 00 00 00 38 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 2d | 00 00 00 00 | ...

    D, [2011-06-29T15:35:30.470479 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 45 (umount) took 0.17 seconds

    guestfsd: main_loop: new request, len 0x38

    D, [2011-06-29T15:35:30.472830 #816] DEBUG -- : GFS: umount /dev/vda1

    D, [2011-06-29T15:35:30.963031 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 2d | 00 00 00 01 | 00 12 34 10 | ...

    T, [2011-06-29T15:35:30.963192 #816] TRACE -- : GFS: umount = 0

    T, [2011-06-29T15:35:30.963327 #816] TRACE -- : Partition unmounted.

    T, [2011-06-29T15:35:30.963428 #816] TRACE -- : All partitions unmounted.

    D, [2011-06-29T15:35:30.963864 #816] DEBUG -- : SELinux policy was loaded, we're ready to sync filesystem.

    I, [2011-06-29T15:35:30.964060 #816]  INFO -- : Synchronizing filesystems...

    T, [2011-06-29T15:35:30.965754 #816] TRACE -- : GFS: mkmountpoint "/in"

    D, [2011-06-29T15:35:30.966054 #816] DEBUG -- : GFS: send_to_daemon: 52 bytes: 00 00 00 30 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 94 | 00 00 00 00 | ...

    D, [2011-06-29T15:35:30.967411 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 45 (umount) took 0.49 seconds

    D, [2011-06-29T15:35:30.969245 #816] DEBUG -- : GFS: guestfsd: main_loop: new request, len 0x30

    D, [2011-06-29T15:35:30.971189 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 94 | 00 00 00 01 | 00 12 34 11 | ...

    T, [2011-06-29T15:35:30.971327 #816] TRACE -- : GFS: mkmountpoint = 0

    T, [2011-06-29T15:35:30.971458 #816] TRACE -- : GFS: mkmountpoint "/out"

    D, [2011-06-29T15:35:30.971972 #816] DEBUG -- : GFS: send_to_daemon: 52 bytes: 00 00 00 30 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 94 | 00 00 00 00 | ...

    D, [2011-06-29T15:35:30.973942 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 148 (mkmountpoint) took 0.00 seconds

    guestfsd: main_loop: new request, len 0x30

    D, [2011-06-29T15:35:30.974999 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 94 | 00 00 00 01 | 00 12 34 12 | ...

    T, [2011-06-29T15:35:30.975152 #816] TRACE -- : GFS: mkmountpoint = 0

    T, [2011-06-29T15:35:30.975297 #816] TRACE -- : GFS: mkmountpoint "/out/in"

    D, [2011-06-29T15:35:30.975683 #816] DEBUG -- : GFS: send_to_daemon: 56 bytes: 00 00 00 34 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 94 | 00 00 00 00 | ...

    D, [2011-06-29T15:35:30.977335 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 148 (mkmountpoint) took 0.00 seconds

    guestfsd: main_loop: new request, len 0x34

    D, [2011-06-29T15:35:30.978507 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 94 | 00 00 00 01 | 00 12 34 13 | ...

    T, [2011-06-29T15:35:30.978759 #816] TRACE -- : GFS: mkmountpoint = 0

    T, [2011-06-29T15:35:30.978943 #816] TRACE -- : GFS: mkfs "ext4" "/dev/vdb"

    D, [2011-06-29T15:35:30.979187 #816] DEBUG -- : GFS: send_to_daemon: 64 bytes: 00 00 00 3c | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 2a | 00 00 00 00 | ...

    D, [2011-06-29T15:35:30.981001 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 148 (mkmountpoint) took 0.00 seconds

    guestfsd: main_loop: new request, len 0x3c

    D, [2011-06-29T15:35:30.985156 #816] DEBUG -- : GFS: mke2fs -t ext4 -F /dev/vdb

    D, [2011-06-29T15:35:31.110359 #816] DEBUG -- : GFS: mke2fs 1.41.14 (22-Dec-2010)

    D, [2011-06-29T15:35:57.515756 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 2a | 00 00 00 01 | 00 12 34 14 | ...

    T, [2011-06-29T15:35:57.515948 #816] TRACE -- : GFS: mkfs = 0

    T, [2011-06-29T15:35:57.516103 #816] TRACE -- : GFS: set_e2label "/dev/vdb" "79d3d2d4"

    D, [2011-06-29T15:35:57.516375 #816] DEBUG -- : GFS: send_to_daemon: 68 bytes: 00 00 00 40 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 00 | ...

    D, [2011-06-29T15:35:57.518811 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 42 (mkfs) took 12.45 seconds

    guestfsd: main_loop: new request, len 0x40

    D, [2011-06-29T15:35:57.522874 #816] DEBUG -- : GFS: e2label /dev/vdb 79d3d2d4

    D, [2011-06-29T15:35:57.524659 #816] DEBUG -- : GFS:

    D, [2011-06-29T15:35:57.794028 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 01 | 00 12 34 15 | ...

    T, [2011-06-29T15:35:57.794212 #816] TRACE -- : GFS: set_e2label = 0

    T, [2011-06-29T15:35:57.794320 #816] TRACE -- : Mounting /dev/vdb partition to /out/in...

    T, [2011-06-29T15:35:57.794433 #816] TRACE -- : GFS: mount_options "" "/dev/vdb" "/out/in"

    D, [2011-06-29T15:35:57.794920 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 00 | ...

    D, [2011-06-29T15:35:57.797001 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 80 (set_e2label) took 0.27 seconds

    guestfsd: main_loop: new request, len 0x44

    D, [2011-06-29T15:35:57.799761 #816] DEBUG -- : GFS: mount -o  /dev/vdb /sysroot/out/in

    D, [2011-06-29T15:35:58.181019 #816] DEBUG -- : GFS: [   51.942588] EXT4-fs (vdb): mounted filesystem with ordered data mode. Opts: (null)

    D, [2011-06-29T15:35:58.242795 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 01 | 00 12 34 16 | ...

    T, [2011-06-29T15:35:58.242981 #816] TRACE -- : GFS: mount_options = 0

    T, [2011-06-29T15:35:58.243110 #816] TRACE -- : GFS: set_e2label "/dev/vdb" "a570ee9c"

    D, [2011-06-29T15:35:58.243400 #816] DEBUG -- : GFS: send_to_daemon: 68 bytes: 00 00 00 40 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 00 | ...

    D, [2011-06-29T15:35:58.245778 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 74 (mount_options) took 0.43 seconds

    guestfsd: main_loop: new request, len 0x40

    D, [2011-06-29T15:35:58.249422 #816] DEBUG -- : GFS: e2label /dev/vdb a570ee9c

    D, [2011-06-29T15:35:58.584445 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 01 | 00 12 34 17 | ...

    T, [2011-06-29T15:35:58.584856 #816] TRACE -- : GFS: set_e2label = 0

    T, [2011-06-29T15:35:58.584978 #816] TRACE -- : Partition mounted.

    T, [2011-06-29T15:35:58.585104 #816] TRACE -- : Mounting partitions...

    T, [2011-06-29T15:35:58.585238 #816] TRACE -- : GFS: list_partitions

    D, [2011-06-29T15:35:58.585655 #816] DEBUG -- : GFS: send_to_daemon: 44 bytes: 00 00 00 28 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 08 | 00 00 00 00 | ...

    D, [2011-06-29T15:35:58.587811 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 80 (set_e2label) took 0.33 seconds

    guestfsd: main_loop: new request, len 0x28

    D, [2011-06-29T15:35:58.592045 #816] DEBUG -- : GFS: recv_from_daemon: 76 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 08 | 00 00 00 01 | 00 12 34 18 | ...

    T, [2011-06-29T15:35:58.592213 #816] TRACE -- : GFS: list_partitions = ["/dev/vda1", "/dev/vda2"]

    T, [2011-06-29T15:35:58.592445 #816] TRACE -- : Mounting /dev/vda1 partition to /...

    T, [2011-06-29T15:35:58.592719 #816] TRACE -- : GFS: mount_options "" "/dev/vda1" "/in/"

    D, [2011-06-29T15:35:58.593007 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 00 | ...

    D, [2011-06-29T15:35:58.596409 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 8 (list_partitions) took 0.00 seconds

    guestfsd: main_loop: new request, len 0x44

    D, [2011-06-29T15:35:58.599148 #816] DEBUG -- : GFS: mount -o  /dev/vda1 /sysroot/in/

    D, [2011-06-29T15:35:58.985933 #816] DEBUG -- : GFS: [   52.732800] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null)

    D, [2011-06-29T15:35:59.079589 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 01 | 00 12 34 19 | ...

    T, [2011-06-29T15:35:59.079768 #816] TRACE -- : GFS: mount_options = 0

    T, [2011-06-29T15:35:59.079935 #816] TRACE -- : GFS: set_e2label "/dev/vda1" "79d3d2d4"

    D, [2011-06-29T15:35:59.097807 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 00 | ...

    D, [2011-06-29T15:35:59.102737 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 74 (mount_options) took 0.47 seconds

    D, [2011-06-29T15:35:59.105434 #816] DEBUG -- : GFS: guestfsd: main_loop: new request, len 0x44

    D, [2011-06-29T15:35:59.121933 #816] DEBUG -- : GFS: e2label /dev/vda1 79d3d2d4

    D, [2011-06-29T15:35:59.257268 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 01 | 00 12 34 1a | ...

    T, [2011-06-29T15:35:59.257431 #816] TRACE -- : GFS: set_e2label = 0

    T, [2011-06-29T15:35:59.257566 #816] TRACE -- : Partition mounted.

    T, [2011-06-29T15:35:59.258127 #816] TRACE -- : Mounting /dev/vda2 partition to /SpeechMagic...

    T, [2011-06-29T15:35:59.258265 #816] TRACE -- : GFS: mount_options "" "/dev/vda2" "/in/SpeechMagic"

    D, [2011-06-29T15:35:59.258563 #816] DEBUG -- : GFS: send_to_daemon: 84 bytes: 00 00 00 50 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 00 | ...

    D, [2011-06-29T15:35:59.260533 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 80 (set_e2label) took 0.15 seconds

    guestfsd: main_loop: new request, len 0x50

    D, [2011-06-29T15:35:59.274954 #816] DEBUG -- : GFS: mount -o  /dev/vda2 /sysroot/in/SpeechMagic

    D, [2011-06-29T15:35:59.621727 #816] DEBUG -- : GFS: [   53.345371] EXT4-fs (vda2): mounted filesystem with ordered data mode. Opts: (null)

    D, [2011-06-29T15:35:59.650033 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 4a | 00 00 00 01 | 00 12 34 1b | ...

    T, [2011-06-29T15:35:59.650198 #816] TRACE -- : GFS: mount_options = 0

    T, [2011-06-29T15:35:59.650327 #816] TRACE -- : GFS: set_e2label "/dev/vda2" "d8863c17"

    D, [2011-06-29T15:35:59.650840 #816] DEBUG -- : GFS: send_to_daemon: 72 bytes: 00 00 00 44 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 00 | ...

    D, [2011-06-29T15:35:59.652723 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 74 (mount_options) took 0.37 seconds

    guestfsd: main_loop: new request, len 0x44

    D, [2011-06-29T15:35:59.657418 #816] DEBUG -- : GFS: e2label /dev/vda2 d8863c17

    D, [2011-06-29T15:35:59.762214 #816] DEBUG -- : GFS: recv_from_daemon: 40 bytes: 20 00 f5 f5 | 00 00 00 04 | 00 00 00 50 | 00 00 00 01 | 00 12 34 1c | ...

    T, [2011-06-29T15:35:59.762436 #816] TRACE -- : GFS: set_e2label = 0

    T, [2011-06-29T15:35:59.762742 #816] TRACE -- : Partition mounted.

    D, [2011-06-29T15:35:59.762945 #816] DEBUG -- : Copying files...

    T, [2011-06-29T15:35:59.763121 #816] TRACE -- : GFS: cp_a "/in/" "/out"

    D, [2011-06-29T15:35:59.763399 #816] DEBUG -- : GFS: send_to_daemon: 60 bytes: 00 00 00 38 | 20 00 f5 f5 | 00 00 00 04 | 00 00 00 58 | 00 00 00 00 | ...

    D, [2011-06-29T15:35:59.765395 #816] DEBUG -- : GFS: guestfsd: main_loop: proc 80 (set_e2label) took 0.10 seconds

    guestfsd: main_loop: new request, len 0x38

    D, [2011-06-29T15:35:59.792945 #816] DEBUG -- : GFS: cp -a /sysroot/in/ /sysroot/out

     

    --Anton

  • 5. Re: Building EBS on micro AMI, does not finish, ideas?
    Marek Goldmann Master

    As you can see, your libguestfs memory setting wasn't set. The default value of 500 MB is being used:

     

    D, [2011-06-29T15:33:45.916503 #862] DEBUG -- : GFS: [33282ms] /usr/lib/ruby/gems/1.8/gems/boxgrinder-build-0.9.2/lib/boxgrinder-build/helpers/qemu.wrapper \

        -drive file=build/appliances/i686/fedora/14/f14-Speech/fedora-plugin/f14-Speech-sda.raw,cache=off,if=virtio \

        -drive file=build/appliances/i686/fedora/14/f14-Speech/ec2-plugin/tmp/f14-Speech.ec2,cache=off,if=virtio \

        -nodefconfig \

        -nodefaults \

        -nographic \

        -m 500 \

        -no-reboot \

        -no-hpet \

        -device virtio-serial \

        -serial stdio \

        -chardev socket,path=/tmp/libguestfsh0E3ar/guestfsd.sock,id=channel0 \

        -device virtserialport,chardev=channel0,name=org.libguestfs.channel.0 \

        -netdev user,id=usernet,net=169.254.0.0/16 \

        -device virtio-net-pci,netdev=usernet \

        -kernel /tmp/.guestfs-0/kernel.816 \

        -initrd /tmp/.guestfs-0/initrd.816 \

        -append 'panic=1 console=ttyS0 udevtimeout=300 noapic acpi=off printk.time=1 cgroup_disable=memory selinux=1

     

    Most probably you execute the build using sudo and you export the the value for current user, not root. Please execute:

     

    $ sudo bash
    $ export LIBGUESTFS_MEMSIZE=300
    $ boxgrinder-build f14-base.appl -p ec2 -d ebs -b --debug
    

     

    This should do the trick.

     

    --Marek

  • 6. Re: Building EBS on micro AMI, does not finish, ideas?
    Marek Goldmann Master

    Btw, we have created https://issues.jboss.org/browse/BGBUILD-246 so we want to make sure it'll not happen again.

     

    --Marek

  • 7. Re: Building EBS on micro AMI, does not finish, ideas?
    Anton vanKimmenade Newbie

    Yes you are right I didn't pay attention to setting it to root. Applied the sudo bash and started it again

    After 0.5 hour it finished

    D, [2011-06-30T11:32:02.661954 #4064] DEBUG -- : Launching new BoxGrinder build...

    I, [2011-06-30T12:08:54.019298 #4064]  INFO -- : EBS AMI 'f14-Speech/fedora/14/1.0/i686' registered: ami-0cd12a65 (region: us-east-1)

     

    Thanks for the support and glad to hear the situation will be prevented in future.

     

    --Anton