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