More details...
I've re-installed and I've verified that the same error occurs at the same
step. Below the message:
Configuring flash memory to boot the system  ..33%..66%
Make the system bootable
------------------------

!! ERROR: Installation step failed

An installation step failed. You can try to run the failing item again from
the
menu, or skip it and choose something else. The failing step is: Make the
system bootable
[Press enter to continue]

I've once again chose to continue without that knowing that I could
interrupt the boot sequence and use TFTP.
This time I've managed to boot it almost properly with the following
sequence:
RedBoot> fis load ramdisk.gz
RedBoot> load -r -v -h 192.168.1.101 -b 0x01008000 -m tftp
vmlinuz-2.6.32-5-iop32x
-----------<suppressed output>-------------
Raw file loaded 0x01008000-0x0113a69f, assumed entry at 0x01008000
RedBoot> exec -c "console=ttyS0,115200n8 rw root=/dev/sda1
mem=512M@0xa0000000" -r 0x01800000

Eventually it hangs because of an error in communicating with the disk. Full
log is attached.

Questions:
1. how to correctly write boot image to flash?
2. anybody is able to decode what the disk error (ata4.00: exception Emask
0x0 SAct 0x0 SErr 0x0 action 0x6 frozen) means?

Thanks!



On Thu, Apr 21, 2011 at 9:39 AM, Mello <[email protected]> wrote:

> I've tried installing once again with the stripped zImage. Boot is fine and
> "Machine: Intel IQ31244" is reported in the messages.
> Installation, instead, stops at the boot loader step. I've chosen to
> continue but then the system won't reboot properly.
>
> I assume the installer was not able to flash the proper kernel so I find
> myself booting the previous (unstripped) one. In fact at reboot "Machine:
> Lanner EM7210" is reported despite "force_ep80219" parameter.
>
> Any troubleshooting/tricks?
>
> Thanks,
> mw
>
>
> On Wed, Apr 20, 2011 at 1:20 PM, Mello <[email protected]> wrote:
>
>> Aha, I was looking for them in a reversed fashion :)
>> Thanks for looking into it!
>>
>> Think I've done that:
>> $ hexdump -v -n 96 zImage.stripped
>> 0000000 00 00 a0 e1 00 00 a0 e1 00 00 a0 e1 00 00 a0 e1
>> 0000010 00 00 a0 e1 00 00 a0 e1 00 00 a0 e1 00 00 a0 e1
>> 0000020 02 00 00 ea 18 28 6f 01 00 00 00 00 38 27 13 00
>> 0000030 01 70 a0 e1 02 80 a0 e1 00 20 0f e1 03 00 12 e3
>> 0000040 01 00 00 1a 17 00 a0 e3 56 34 12 ef 00 20 0f e1
>> 0000050 c0 20 82 e3 02 f0 21 e1 1f 20 cf e3 01 38 82 e2
>>
>> For the record, I've used this command:
>> $ dd if=zImage of=zImage.stripped bs=1 skip=8
>> 1255224+0 records in
>> 1255224+0 records out
>> 1255224 bytes transferred in 6.907979 secs (181706 bytes/sec)
>>
>> $ ls -l zImage*
>> ...  1255232 Mar 29 09:11 zImage
>> ...  1255224 Apr 20 13:11 zImage.stripped
>>
>> I'll test it tonight and report back.
>>
>> Thank you!
>>
>>
>>
>> On Wed, Apr 20, 2011 at 12:35 PM, Arnaud Patard <
>> [email protected]> wrote:
>>
>>> Mello <[email protected]> writes:
>>>
>>> Hi,
>>>
>>> > Hi Arnaud,
>>> > sorry for a late reply and thanks a lot for your advice.
>>> > I've done a dump of the entire file but I couldn't find those data.
>>> Just for
>>> > a reference here's the first few lines:
>>> > $ hexdump -n 96 zImage
>>> > 0000000 04 1c a0 e3 bc 10 81 e3 00 00 a0 e1 00 00 a0 e1
>>>           ^^^^^^^^^^^^^^^^^^^^^^^
>>>
>>> theses are the bytes I was talking of.
>>>
>>> Arnaud
>>>
>>
>>
>
+Ethernet eth0: MAC address 00:40:d0:8a:1f:99
IP: 192.168.1.11/255.255.255.0, Gateway: 192.168.1.1
Default server: 192.168.1.122, DNS server IP: 0.0.0.0

RedBoot(tm) bootstrap and debug environment [ROM]
Intel IOP RedBoot release
         version 2.2.V6-IOP-RedBoot
         built 13:51:51, Jan 12 2006

Platform: EP80219 (XScale) 
Copyright (C) 2000, 2001, 2002, Red Hat, Inc.

RAM: 0x00000000-0x20000000, 0x00021790-0x1ffd1000 available
FLASH: 0xf0000000 - 0xf0800000, 64 blocks of 0x00020000 bytes each.
== Executing boot script in 2.000 seconds - enter ^C to abort
^C

RedBoot> fis load ramdisk.gz
RedBoot> load -r -v -h 192.168.1.101 -b 0x01008000 -m tftp 
vmlinuz-2.6.32-5-iop32x
-----------<suppressed output>-------------
Raw file loaded 0x01008000-0x0113a69f, assumed entry at 0x01008000
RedBoot> exec -c "console=ttyS0,115200n8 rw root=/dev/sda1 mem=512M@0xa0000000" 
-r 0x01800000
Using base address 0x01008000 and length 0x001326a0
The boot tags are located at 0xA0000100
Booting the kernel...
Uncompressing Linux... done, booting the kernel.
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 2.6.32-5-iop32x (Debian 2.6.32-29) 
([email protected]) (gcc version 4.3.5 (Debian 4.3.5-4) ) #1 Fri Dec 10 
19:47:27 UTC 2010
[    0.000000] CPU: XScale-80219 [69052e20] revision 0 (ARMv5TE), cr=0000397f
[    0.000000] CPU: VIVT data cache, VIVT instruction cache
[    0.000000] Machine: Intel IQ31244
[    0.000000] Memory policy: ECC disabled, Data cache writeback
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total 
pages: 130048
[    0.000000] Kernel command line: console=ttyS0,115200n8 rw root=/dev/sda1 
mem=512M@0xa0000000
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    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] Memory: 512MB = 512MB total
[    0.000000] Memory: 511744KB available (3080K code, 444K data, 112K init, 0K 
highmem)
[    0.000000] SLUB: Genslabs=11, HWalign=32, Order=0-3, MinObjects=0, CPUs=1, 
Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS:32
[    0.000000] Console: colour dummy device 80x30
[    0.000000] Calibrating delay loop... 394.85 BogoMIPS (lpj=1974272)
[    0.220000] Security Framework initialized
[    0.220000] SELinux:  Disabled at boot.
[    0.220000] Mount-cache hash table entries: 512
[    0.220000] Initializing cgroup subsys ns
[    0.220000] Initializing cgroup subsys cpuacct
[    0.220000] Initializing cgroup subsys devices
[    0.220000] Initializing cgroup subsys freezer
[    0.220000] Initializing cgroup subsys net_cls
[    0.220000] CPU: Testing write buffer coherency: ok
[    0.220000] devtmpfs: initialized
[    0.220000] regulator: core version 0.5
[    0.220000] NET: Registered protocol family 16
[    0.220000] note: iq31244 board type has been selected
[    0.220000] note: to select ep80219 operation:
[    0.220000]  1/ specify "force_ep80219" on the kernel command line
[    0.220000]  2/ update boot loader to pass the ep80219 id: 1281
[    0.230000] pci 0000:00:01.0: PME# supported from D0 D3hot D3cold
[    0.230000] pci 0000:00:01.0: PME# disabled
[    0.230000] PCI: bus0: Fast back to back transfers disabled
[    0.230000] bio: create slab <bio-0> at 0
[    0.230000] vgaarb: loaded
[    0.250000] NET: Registered protocol family 2
[    0.250000] IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
[    0.250000] TCP established hash table entries: 16384 (order: 5, 131072 
bytes)
[    0.250000] TCP bind hash table entries: 16384 (order: 4, 65536 bytes)
[    0.250000] TCP: Hash tables configured (established 16384 bind 16384)
[    0.250000] TCP reno registered
[    0.250000] NET: Registered protocol family 1
[    0.250000] Unpacking initramfs...
[    1.020000] Freeing initrd memory: 4096K
[    1.020000] NetWinder Floating Point Emulator V0.97 (double precision)
[    1.020000] audit: initializing netlink socket (disabled)
[    1.020000] type=2000 audit(1.020:1): initialized
[    1.040000] VFS: Disk quotas dquot_6.5.2
[    1.040000] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.040000] msgmni has been set to 1007
[    1.040000] alg: No test for stdrng (krng)
[    1.040000] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 
253)
[    1.040000] io scheduler noop registered
[    1.040000] io scheduler anticipatory registered
[    1.040000] io scheduler deadline registered
[    1.040000] io scheduler cfq registered (default)
[    1.060000] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[    1.060000] serial8250.0: ttyS0 at MMIO 0xfe800000 (irq = 28) is a 16550A
[    1.400000] console [ttyS0] enabled
[    1.410000] physmap platform flash device: 00800000 at f0000000
[    1.410000] physmap-flash.0: Found 1 x16 devices at 0x0 in 16-bit bank
[    1.420000]  Intel/Sharp Extended Query Table at 0x0031
[    1.430000]  Intel/Sharp Extended Query Table at 0x0031
[    1.430000] Using buffer write method
[    1.430000] cfi_cmdset_0001: Erase suspend on write enabled
[    1.430000] Searching for RedBoot partition table in physmap-flash.0 at 
offset 0x7e0000
[    1.760000] 6 RedBoot partitions found on MTD device physmap-flash.0
[    1.760000] Creating 6 MTD partitions on "physmap-flash.0":
[    1.780000] 0x000000000000-0x000000040000 : "RedBoot"
[    1.780000] 0x000000040000-0x000000240000 : "zImage"
[    1.780000] 0x000000240000-0x000000640000 : "ramdisk.gz"
[    1.800000] 0x0000007a0000-0x0000007c0000 : "vendor"
[    1.800000] 0x0000007c0000-0x0000007c1000 : "RedBoot config"
[    1.800000] mtd: partition "RedBoot config" doesn't end on an erase block -- 
force read-only
[    1.820000] 0x0000007e0000-0x000000800000 : "FIS directory"
[    1.830000] mice: PS/2 mouse device common for all mice
[    1.840000] i2c /dev entries driver
[    1.860000] iop-adma iop-adma.0: Intel(R) IOP: ( cpy intr )
[    1.880000] iop-adma iop-adma.1: Intel(R) IOP: ( cpy intr )
[    1.880000] TCP cubic registered
[    1.900000] NET: Registered protocol family 17
[    1.900000] XScale DSP coprocessor detected.
[    1.910000] registered taskstats version 1
[    1.910000] 
/build/buildd-linux-2.6_2.6.32-29-armel-2LYIJf/linux-2.6-2.6.32/debian/build/source_armel_none/drivers/rtc/hctosys.c:
 unable to open rtc device (rtc0)
[    1.930000] Initalizing network drop monitor service
[    1.930000] Freeing init memory: 112K
Loading, please wait...
[    2.230000] udev[39]: starting version 164
[    3.190000] SCSI subsystem initialized
[    3.540000] scsi0 : sata_vsc
[    3.560000] scsi1 : sata_vsc
[    3.560000] scsi2 : sata_vsc
[    3.570000] scsi3 : sata_vsc
[    3.570000] ata1: SATA max UDMA/133 mmio m4096@0x80060000 port 0x80060200 
irq 27
[    3.570000] ata2: SATA max UDMA/133 mmio m4096@0x80060000 port 0x80060400 
irq 27
[    3.590000] ata3: SATA max UDMA/133 mmio m4096@0x80060000 port 0x80060600 
irq 27
[    3.600000] ata4: SATA max UDMA/133 mmio m4096@0x80060000 port 0x80060800 
irq 27
[    3.960000] ata1: SATA link down (SStatus 0 SControl 300)
[    4.310000] ata2: SATA link down (SStatus 0 SControl 300)
[    4.660000] ata3: SATA link down (SStatus 0 SControl 300)
[    5.010000] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[   10.030000] ata4.00: qc timeout (cmd 0x27)
[   10.030000] ata4.00: failed to read native max address (err_mask=0x4)
[   10.050000] ata4.00: HPA support seems broken, skipping HPA handling
[   10.400000] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[   10.420000] ata4.00: ATA-7: ST3320620AS, 3.AAE, max UDMA/133
[   10.420000] ata4.00: 625142448 sectors, multi 0: LBA48 NCQ (depth 0/32)
[   10.480000] ata4.00: configured for UDMA/133
[   10.480000] scsi 3:0:0:0: Direct-Access     ATA      ST3320620AS      3.AA 
PQ: 0 ANSI: 5
[   10.620000] sd 3:0:0:0: [sda] 625142448 512-byte logical blocks: (320 GB/298 
GiB)
[   10.640000] sd 3:0:0:0: [sda] Write Protect is off
[   10.640000] sd 3:0:0:0: [sda] Write cache: enabled, read cache: enabled, 
doesn't support DPO or FUA
[   10.650000]  sda:
[   41.040000] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[   41.040000] ata4.00: failed command: READ DMA
[   41.060000] ata4.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 dma 4096 
in
[   41.060000]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 
(timeout)
[   41.070000] ata4.00: status: { DRDY }
[   41.070000] ata4: hard resetting link
[   41.420000] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[   41.480000] ata4.00: configured for UDMA/133
[   41.480000] ata4.00: device reported invalid CHS sector 0
[   41.490000] ata4: EH complete
[   72.040000] ata4: limiting SATA link speed to 1.5 Gbps
[   72.040000] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[   72.060000] ata4.00: failed command: READ DMA
[   72.060000] ata4.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 dma 4096 
in
[   72.060000]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 
(timeout)
[   72.080000] ata4.00: status: { DRDY }
[   72.080000] ata4: hard resetting link
[   72.430000] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[   72.490000] ata4.00: configured for UDMA/133
[   72.490000] ata4.00: device reported invalid CHS sector 0
[   72.500000] ata4: EH complete
[  103.040000] ata4.00: limiting speed to UDMA/100:PIO4
[  103.040000] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[  103.060000] ata4.00: failed command: READ DMA
[  103.060000] ata4.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 dma 4096 
in
[  103.060000]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 
(timeout)
[  103.080000] ata4.00: status: { DRDY }
[  103.080000] ata4: hard resetting link
[  103.430000] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  103.490000] ata4.00: configured for UDMA/100
[  103.490000] ata4.00: device reported invalid CHS sector 0
[  103.500000] ata4: EH complete
[  134.040000] ata4.00: limiting speed to UDMA/33:PIO4
[  134.040000] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[  134.060000] ata4.00: failed command: READ DMA
[  134.060000] ata4.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 dma 4096 
in
[  134.060000]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 
(timeout)
[  134.080000] ata4.00: status: { DRDY }
[  134.080000] ata4: hard resetting link
[  134.430000] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  134.490000] ata4.00: configured for UDMA/33
[  134.490000] ata4.00: device reported invalid CHS sector 0
[  134.500000] ata4: EH complete
[  165.040000] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[  165.040000] ata4.00: failed command: READ DMA
[  165.060000] ata4.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 dma 4096 
in
[  165.060000]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 
(timeout)
[  165.070000] ata4.00: status: { DRDY }
[  165.080000] ata4: hard resetting link
[  165.430000] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  165.490000] ata4.00: configured for UDMA/33
[  165.490000] ata4.00: device reported invalid CHS sector 0
[  165.500000] ata4: EH complete

udevadm settle - timeout of 180 seconds reached, the event queue contains:
  /sys/devices/pci0000:00/0000:00:03.0/host3/target3:0:0/3:0:0:0 (396)
  
/sys/devices/pci0000:00/0000:00:03.0/host3/target3:0:0/3:0:0:0/scsi_device/3:0:0:0
 (397)
  /sys/devices/pci0000:00/0000:00:03.0/host3/target3:0:0/3:0:0:0/bsg/3:0:0:0 
(398)
  
/sys/devices/pci0000:00/0000:00:03.0/host3/target3:0:0/3:0:0:0/scsi_disk/3:0:0:0
 (402)
udevd[39]: worker [41] unexpectedly returned with status 0x0100

udevd[39]: worker [41] failed while handling 
'/devices/pci0000:00/0000:00:03.0/host3/target3:0:0/3:0:0:0'

[  196.040000] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[  196.040000] ata4.00: failed command: READ DMA
[  196.060000] ata4.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 dma 4096 
in
[  196.060000]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 
(timeout)
[  196.070000] ata4.00: status: { DRDY }
[  196.070000] ata4: hard resetting link
[  196.420000] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  196.480000] ata4.00: configured for UDMA/33
[  196.480000] ata4.00: device reported invalid CHS sector 0
[  196.490000] sd 3:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
[  196.490000] sd 3:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
[  196.510000] Descriptor sense data with sense descriptors (in hex):
[  196.510000]         72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00 
[  196.520000]         00 00 00 00 
[  196.520000] sd 3:0:0:0: [sda] ASC=0x0 ASCQ=0x0
[  196.530000] sd 3:0:0:0: [sda] CDB: cdb[0]=0x28: 28 00 00 00 00 00 00 00 08 00
[  196.530000] end_request: I/O error, dev sda, sector 0
[  196.530000] Buffer I/O error on device sda, logical block 0
[  196.550000] ata4: EH complete
[  227.040000] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[  227.040000] ata4.00: failed command: READ DMA
[  227.060000] ata4.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 dma 4096 
in
[  227.060000]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 
(timeout)
[  227.070000] ata4.00: status: { DRDY }
[  227.070000] ata4: hard resetting link
[  227.420000] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  227.480000] ata4.00: configured for UDMA/33
[  227.480000] ata4.00: device reported invalid CHS sector 0
[  227.490000] ata4: EH complete
[  241.030000] INFO: task modprobe:66 blocked for more than 120 seconds.
[  241.030000] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this 
message.
[  241.050000] modprobe      D c025bce4     0    66      1 0x00000000
[  241.060000] [<c025bce4>] (schedule+0x424/0x488) from [<c005f3f0>] 
(async_synchronize_cookie_domain+0x1a0/0x264)
[  241.070000] [<c005f3f0>] (async_synchronize_cookie_domain+0x1a0/0x264) from 
[<c005f4d8>] (async_synchronize_full+0x18/0x40)
[  241.080000] [<c005f4d8>] (async_synchronize_full+0x18/0x40) from 
[<c006c454>] (sys_init_module+0x170/0x1f0)
[  241.090000] [<c006c454>] (sys_init_module+0x170/0x1f0) from [<c0024e80>] 
(ret_fast_syscall+0x0/0x38)
[  258.040000] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[  258.040000] ata4.00: failed command: READ DMA
[  258.060000] ata4.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 dma 4096 
in
[  258.060000]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 
(timeout)
[  258.070000] ata4.00: status: { DRDY }
[  258.070000] ata4: hard resetting link
[  258.420000] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  258.480000] ata4.00: configured for UDMA/33
[  258.480000] ata4.00: device reported invalid CHS sector 0
[  258.490000] ata4: EH complete
[  289.040000] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[  289.040000] ata4.00: failed command: READ DMA
[  289.060000] ata4.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 dma 4096 
in
[  289.060000]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 
(timeout)
[  289.070000] ata4.00: status: { DRDY }
[  289.080000] ata4: hard resetting link
[  289.430000] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  289.490000] ata4.00: configured for UDMA/33
[  289.490000] ata4.00: device reported invalid CHS sector 0
[  289.500000] ata4: EH complete
[  319.500000] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[  319.500000] ata4.00: failed command: READ DMA
[  319.520000] ata4.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 dma 4096 
in
[  319.520000]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 
(timeout)
[  319.530000] ata4.00: status: { DRDY }
[  319.530000] ata4: hard resetting link
[  319.880000] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  319.940000] ata4.00: configured for UDMA/33
[  319.940000] ata4.00: device reported invalid CHS sector 0
[  319.950000] ata4: EH complete

Reply via email to