Flash image from SD card failed (sometimes)


#1

Hi All,

I tried to flash new image into eMCP from SD card, but we sometimes find some error and flash failed.
I’m not sure whether the following log is the root cause and why the error happened.

Failed Log:
mmcblk0: mmc0:000 16 8.00 KiB
mmcblk0: error -110

Normal Log:
mmcblk0: mmc0:0001 Q2J95Q 7.28 GiB

Does anyone can give me some hint or advice that what going on with my board?
I’ll post full log in the next comment.

Thanks.

Best regards,
Johnny


#2

The following is the FULL error log.

Full Log (Click here)

Format: Log Type - Time(microsec) - Message - Optiona
Log Type: B - Since Boot(Power On Reset), D - Delta,Statistic
S - QC_IMAGE_VERSION_STRING=BOOT.BF.3.0-00261
S - IMAGE_VARIANT_STRING=HAAAANAAA
S - OEM_IMAGE_VERSION_STRING=C-BPATTH
S - Boot Config, 0x000002e3
S - Core 0 Frequency, 0 MHz
B - 1544 - PBL, Start
B - 3489 - bootable_media_detect_entry, Start
B - 57365 - bootable_media_detect_success, Start
B - 57369 - elf_loader_entry, Start
B - 60270 - auth_hash_seg_entry, Start
B - 60480 - auth_hash_seg_exit, Start
B - 89470 - elf_segs_hash_verify_entry, Start
B - 148346 - PBL, End
B - 155306 - SBL1, Start
B - 218441 - pm_device_init, Start
D - 14701 - pm_device_init, Delta
B - 233752 - boot_flash_init, Start
D - 0 - boot_flash_init, Delta
B - 237778 - boot_config_data_table_init, Start
D - 504958 - boot_config_data_table_init, Delta - es)
B - 747280 - CDT version:3,Platform ID:24,Major IDor ID:0,Subtype:0
B - 753502 - sbl1_ddr_set_params, Start
B - 757193 - cpr_init, Start
D - 0 - cpr_init, Delta
B - 762805 - Pre_DDR_clock_init, Start
D - 183 - Pre_DDR_clock_init, Delta
D - 0 - sbl1_ddr_set_params, Delta
B - 775279 - pm_driver_init, Start
D - 6832 - pm_driver_init, Delta
B - 790743 - clock_init, Start
D - 30 - clock_init, Delta
B - 800899 - Image Load, Start
D - 48343 - QSEE Image Loaded, Delta - (567100 By
B - 849272 - Image Load, Start
D - 701 - SEC Image Loaded, Delta - (2048 Bytes
B - 856440 - sbl1_efs_handle_cookies, Start
D - 0 - sbl1_efs_handle_cookies, Delta
B - 864339 - Image Load, Start
D - 15098 - QHEE Image Loaded, Delta - (51952 Byt
B - 879467 - Image Load, Start
D - 20862 - RPM Image Loaded, Delta - (149492 Byt
B - 900360 - Image Load, Start
D - 24644 - APPSBL Image Loaded, Delta - (480308
B - 925034 - QSEE Execution, Start
D - 61 - QSEE Execution, Delta
B - 930707 - SBL1, End
D - 777780 - SBL1, Delta
S - Flash Throughput, 57000 KB/s (1250900 Bytes, 21)
S - DDR Frequency, 400 MHz
Android Bootloader - UART_DM Initialized!!!
[0] welcome to lk

[10] platform_init()
[10] target_init()
[20] initialising mmc_slot =2
[20] Error: Command timeout error
[20] Failure getting OCR response from MMC Card
[30] MMC card failed to respond, try for SD card
[90] Done initialization of the card
[90] pm8x41_get_is_cold_boot: cold boot
[100] Not able to search the panel:
[100] Display not enabled for 24 HW type
[100] Target panel init not found!
[100] pm8x41_get_is_cold_boot: cold boot
[110] partition misc doesn’t exist
[110] error in emmc_recovery_init
[110] Unable to locate /bootselect partition
[120] No ‘misc’ partition found
[120] Error reading MISC partition
[120] failed to get ffbm cookie[130] use_signed_kernes_unlocked=0, is_tampered=0.
[130] Loading boot image (17672192): start
[960] Loading boot image (17672192): done
[1090] DTB Total entry: 15, DTB version: 2
[1090] Using DTB entry 0x000000f7/00000000/0x00010018 device 0x000000f7/00010000/0x00010018/0
[1100] Using pmic info 0x2000b/0x0/0x0/0x0 for device0b/0x0/0x0/0x0
[1110] cmdline: root=/dev/disk/by-partlabel/rootfs rwait console=ttyMSM0,115200n8 androidboot.emmc=true anoot.serialno=2d5 androidboot.baseband=apq mdss_mdp.padsi:0:
[1130] Updating device tree: start
[1260] Setting mac address in DT: 2:0:0:0:2:d5
[1260] Updating device tree: done
[1260] Error: Command timeout error
[1270] card sleep error: mmc_put_card_to_sleep
[1270] booting linux @ 0x80080000, ramdisk @ 0x82000014984), tags/device tree @ 0x81e00000
[1280] Jumping to kernel via monitor
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Initializing cgroup subsys cpuset
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Initializing cgroup subsys cpuacct
[ 0.000000] Linux version 4.4.23-linaro-lt-qcom (rbian) (gcc version 4.9.3 20141031 (prerelease) (Linar2014.11) ) #17 SMP PREEMPT Fri Sep 22 13:05:27 JST 20
[ 0.000000] Boot CPU: AArch64 Processor [410fd030]
[ 0.000000] efi: Getting EFI parameters from FDT:
[ 0.000000] efi: UEFI not found.
[ 0.000000] Reserved memory: OVERLAP DETECTED!
[ 0.000000] hypervisor_region@86400000 (0x0000000000–0x0000000086800000) overlaps with rmtfs@86700000 0000086700000–0x00000000867e0000)
[ 0.000000] Reserved memory: OVERLAP DETECTED!
[ 0.000000] modem_adsp_region@86800000 (0x0000000000–0x000000008b000000) overlaps with wcnss@89300000 0000089300000–0x0000000089900000)
[ 0.000000] cma: Reserved 64 MiB at 0x00000000bc00
[ 0.000000] psci: probing for conduit method from
[ 0.000000] psci: PSCIv1.0 detected in firmware.
[ 0.000000] psci: Using standard PSCI v0.2 functio
[ 0.000000] psci: MIGRATE_INFO_TYPE not supported.
[ 0.000000] PERCPU: Embedded 16 pages/cpu @ffffffc000 s25216 r8192 d32128 u65536
[ 0.000000] Detected VIPT I-cache on CPU0
[ 0.000000] CPU features: enabling workaround for rata 826319, 827319, 824069
[ 0.000000] CPU features: enabling workaround for ratum 845719
[ 0.000000] Built 1 zonelists in Zone order, mobilouping on. Total pages: 234108
[ 0.000000] Kernel command line: root=/dev/disk/byabel/rootfs rw rootwait console=ttyMSM0,115200n8 andrt.emmc=true androidboot.serialno=2d5 androidboot.basepq mdss_mdp.panel=0:dsi:0:
[ 0.000000] log_buf_len individual max cpu contrib 4096 bytes
[ 0.000000] log_buf_len total cpu_extra contributi2288 bytes
[ 0.000000] log_buf_len min size: 16384 bytes
[ 0.000000] log_buf_len: 32768 bytes
[ 0.000000] early log buf free: 14080(85%)
[ 0.000000] PID hash table entries: 4096 (order: 38 bytes)
[ 0.000000] Dentry cache hash table entries: 13107er: 8, 1048576 bytes)
[ 0.000000] Inode-cache hash table entries: 65536 : 7, 524288 bytes)
[ 0.000000] software IO TLB [mem 0xb6c00000-0xbac0(64MB) mapped at [ffffffc036c00000-ffffffc03abfffff]
[ 0.000000] Memory: 783568K/951296K available (835nel code, 800K rwdata, 3668K rodata, 576K init, 277K 02192K reserved, 65536K cma-reserved)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vmalloc : 0xffffff8000000000 - 0xfdbfff0000 ( 246 GB)
[ 0.000000] vmemmap : 0xffffffbdc0000000 - 0xffc0000000 ( 8 GB maximum)
[ 0.000000] 0xffffffbdc0000000 - 0xfdc1000000 ( 16 MB actual)
[ 0.000000] fixed : 0xffffffbffa7fd000 - 0xfffac00000 ( 4108 KB)
[ 0.000000] PCI I/O : 0xffffffbffae00000 - 0xfffbe00000 ( 16 MB)
[ 0.000000] modules : 0xffffffbffc000000 - 0xf000000000 ( 64 MB)
[ 0.000000] memory : 0xffffffc000000000 - 0xf040000000 ( 1024 MB)
[ 0.000000] .init : 0xffffffc000c40000 - 0xf000cd0000 ( 576 KB)
[ 0.000000] .text : 0xffffffc000080000 - 0xf000c3f404 ( 12030 KB)
[ 0.000000] .data : 0xffffffc000ce6000 - 0xf000dae200 ( 801 KB)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjectPUs=4, Nodes=1
[ 0.000000] Preemptible hierarchical RCU implement
[ 0.000000] Build-time adjustment of leaf fanout
[ 0.000000] RCU restricting CPUs from NR_CPUS=64 cpu_ids=4.
[ 0.000000] RCU: Adjusting geometry for rcu_fanout64, nr_cpu_ids=4
[ 0.000000] NR_IRQS:64 nr_irqs:64 0
[ 0.000000] Architected cp15 and mmio timer(s) runt 19.20MHz (virt/virt).
[ 0.000000] clocksource: arch_sys_counter: mask: 0fffffffff max_cycles: 0x46d987e47, max_idle_ns: 440797 ns
[ 0.000004] sched_clock: 56 bits at 19MHz, resolutns, wraps every 4398046511078ns
[ 0.000426] Console: colour dummy device 80x25
[ 0.000446] Calibrating delay loop (skipped), valuulated using timer frequency… 38.40 BogoMIPS (lpj=76
[ 0.000456] pid_max: default: 32768 minimum: 301
[ 0.000529] Security Framework initialized
[ 0.000578] Mount-cache hash table entries: 2048 ( 2, 16384 bytes)
[ 0.000588] Mountpoint-cache hash table entries: 2rder: 2, 16384 bytes)
[ 0.001163] Initializing cgroup subsys io
[ 0.001180] Initializing cgroup subsys memory
[ 0.001205] Initializing cgroup subsys devices
[ 0.001217] Initializing cgroup subsys freezer
[ 0.001227] Initializing cgroup subsys net_cls
[ 0.001240] Initializing cgroup subsys perf_event
[ 0.001250] Initializing cgroup subsys net_prio
[ 0.001264] Initializing cgroup subsys hugetlb
[ 0.001272] Initializing cgroup subsys pids
[ 0.001535] EFI services will not be available.
[ 0.001557] ASID allocator initialised with 65536 s
[ 0.035960] Detected VIPT I-cache on CPU1
[ 0.035999] CPU1: Booted secondary processor [410f
[ 0.047975] Detected VIPT I-cache on CPU2
[ 0.048005] CPU2: Booted secondary processor [410f
[ 0.060004] Detected VIPT I-cache on CPU3
[ 0.060031] CPU3: Booted secondary processor [410f
[ 0.060085] Brought up 4 CPUs
[ 0.060110] SMP: Total of 4 processors activated.
[ 0.060119] CPU: All CPU(s) started at EL1
[ 0.060142] alternatives: patching kernel code
[ 0.060758] devtmpfs: initialized
[ 0.079481] DMI not present or invalid.
[ 0.079758] clocksource: jiffies: mask: 0xffffffffycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.082108] pinctrl core: initialized pinctrl subs
[ 0.083778] NET: Registered protocol family 16
[ 0.099978] cpuidle: using governor ladder
[ 0.120000] cpuidle: using governor menu
[ 0.120293] vdso: 2 pages (1 code @ ffffffc000ced0data @ ffffffc000cec000)
[ 0.120324] hw-breakpoint: found 6 breakpoint and hpoint registers.
[ 0.121157] DMA: preallocated 256 KiB pool for atolocations
[ 0.121344] Serial: AMBA PL011 UART driver
[ 0.134795] irq: no irq domain found for /hexagon-slave-kernel !
[ 0.135598] irq: no irq domain found for /wcnss-smave-kernel !
[ 0.139066] irq: no irq domain found for /soc/qcommdp@1a00000 !
[ 0.173635] msm_bus_fabric_init_driver
[ 0.173814] msm_bus_device 580000.ad-hoc-bus: Dev
[ 0.173832] msm_bus_device 580000.ad-hoc-bus: Utilis missing, default to 100
[ 0.173842] msm_bus_device 580000.ad-hoc-bus: Vrai is missing, default to 100
[ 0.174097] msm_bus_device 580000.ad-hoc-bus: Dev
[ 0.174113] msm_bus_device 580000.ad-hoc-bus: Utilis missing, default to 100
[ 0.174122] msm_bus_device 580000.ad-hoc-bus: Vrai is missing, default to 100
[ 0.174274] msm_bus_device 580000.ad-hoc-bus: Dev
[ 0.174289] msm_bus_device 580000.ad-hoc-bus: Utilis missing, default to 100
[ 0.174298] msm_bus_device 580000.ad-hoc-bus: Vrai is missing, default to 100
[ 0.180367] msm_bus_init_clk: Valid node clk node tx 0
[ 0.180375] msm_bus_init_clk: Valid node clk node tx 1msm_bus_init_clk: Valid node clk node 0 ctx 0
[ 0.180471] msm_bus_init_clk: Valid node clk node 1msm_bus_init_clk: Valid node clk node 4096 ctx 0
[ 0.180562] msm_bus_init_clk: Valid node clk node tx 1[ 0.283916] vgaarb: loaded
[ 0.289181] SCSI subsystem initialized
[ 0.321393] usbcore: registered new interface drivfs
[ 0.322255] usbcore: registered new interface driv
[ 0.323776] usbcore: registered new device driver
[ 0.326161] media: Linux media interface: v0.10
[ 0.327006] Linux video capture interface: v2.00
[ 0.330807] dmi: Firmware registration failed.
[ 0.333498] Advanced Linux Sound Architecture Drivtialized.
[ 0.343623] Bluetooth: Core ver 2.21
[ 0.344065] NET: Registered protocol family 31
[ 0.344088] Bluetooth: HCI device and connection m initialized
[ 0.344482] Bluetooth: HCI socket layer initialize
[ 0.344626] Bluetooth: L2CAP socket layer initiali
[ 0.344899] Bluetooth: SCO socket layer initialize
[ 0.356751] clocksource: Switched to clocksource as_counter
[ 0.468638] NET: Registered protocol family 2
[ 0.476394] TCP established hash table entries: 81der: 4, 65536 bytes)
[ 0.478187] TCP bind hash table entries: 8192 (ord 131072 bytes)
[ 0.483182] TCP: Hash tables configured (establish2 bind 8192)
[ 0.487132] UDP hash table entries: 512 (order: 2, bytes)
[ 0.487412] UDP-Lite hash table entries: 512 (orde16384 bytes)
[ 0.491020] NET: Registered protocol family 1
[ 0.496226] RPC: Registered named UNIX socket tranmodule.
[ 0.496275] RPC: Registered udp transport module.
[ 0.496301] RPC: Registered tcp transport module.
[ 0.496324] RPC: Registered tcp NFSv4.1 backchannesport module.
[ 0.499279] Unpacking initramfs…
[ 1.200993] Freeing initrd memory: 3624K (ffffffc000 - ffffffc00238a000)
[ 1.209287] hw perfevents: enabled with armv8_pmuvdriver, 7 counters available
[ 1.214173] kvm [1]: HYP mode not available
[ 1.241637] futex hash table entries: 1024 (order:1072 bytes)
[ 1.245586] audit: initializing netlink subsys (di)
[ 1.247187] audit: type=2000 audit(1.235:1): initi
[ 1.257185] HugeTLB registered 2 MB page size, preated 0 pages
[ 1.379764] VFS: Disk quotas dquot_6.6.0
[ 1.382434] VFS: Dquot-cache hash table entries: 5der 0, 4096 bytes)
[ 1.421020] NFS: Registering the id_resolver key t
[ 1.421814] Key type id_resolver registered
[ 1.421846] Key type id_legacy registered
[ 1.425270] fuse init (API version 7.23)
[ 1.432839] 9p: Installing v9fs 9p2000 file systemrt
[ 1.451008] io scheduler noop registered
[ 1.454637] io scheduler cfq registered (default)
[ 1.581541] Serial: 8250/16550 driver, 4 ports, IRing disabled
[ 1.607622] msm_serial 78af000.serial: msm_serial:ted port #1
[ 1.611184] msm_serial 78af000.serial: uartclk = 3
[ 1.612287] 78af000.serial: ttyMSM1 at MMIO 0x78afrq = 137, base_baud = 230400) is a MSM
[ 1.617523] msm_serial 78b0000.serial: msm_serial:ted port #0
[ 1.618427] msm_serial 78b0000.serial: uartclk = 3
[ 1.618895] 78b0000.serial: ttyMSM0 at MMIO 0x78b0rq = 138, base_baud = 230400) is a MSM
[ 1.619083] msm_serial: console setup on port #0
[ 2.558058] console [ttyMSM0] enabled
[ 2.567176] msm_serial: driver initialized
[ 2.574637] [drm] Initialized drm 1.1.0 20060810
[ 2.685004] loop: module loaded
[ 2.713466] spi_qup 78b7000.spi: IN:block:16, fifoUT:block:16, fifo:64
[ 2.726732] spi_qup 78b9000.spi: IN:block:16, fifoUT:block:16, fifo:64
[ 2.736998] spmi spmi-0: PMIC Arb Version-2 (0x200
[ 2.838631] tun: Universal TUN/TAP device driver,
[ 2.838939] tun: © 1999-2004 Max Krasnyansky <malcomm.com>
[ 2.850033] sky2: driver version 1.30
[ 2.854751] usbcore: registered new interface drivx
[ 2.855841] usbcore: registered new interface driv8179_178a
[ 2.859887] usbcore: registered new interface driv_ether
[ 2.865805] usbcore: registered new interface driv601
[ 2.871608] usbcore: registered new interface driv1080
[ 2.876974] usbcore: registered new interface drivsb
[ 2.882884] usbcore: registered new interface driv_subset
[ 2.888169] usbcore: registered new interface drivrus
[ 2.894498] usbcore: registered new interface driv_ncm
[ 2.919555] ehci_hcd: USB 2.0 ‘Enhanced’ Host Cont (EHCI) Driver
[ 2.919896] ehci-pci: EHCI PCI platform driver
[ 2.925971] ehci-platform: EHCI generic platform d
[ 2.931257] ehci-msm: Qualcomm On-Chip EHCI Host Cler
[ 2.937904] msm_hsusb_host 78d9000.ehci: unable totransceiver
[ 2.941450] ohci_hcd: USB 1.1 ‘Open’ Host ControllCI) Driver
[ 2.946724] ohci-pci: OHCI PCI platform driver
[ 2.953475] ohci-platform: OHCI generic platform d
[ 2.959297] usbcore: registered new interface driv_acm
[ 2.962379] cdc_acm: USB Abstract Control Model dror USB modems and ISDN adapters
[ 2.969024] usbcore: registered new interface drivlp
[ 2.976553] usbcore: registered new interface driv_wdm
[ 2.981924] usbcore: registered new interface driv
[ 2.987987] usbcore: registered new interface driv-storage
[ 2.994095] usbcore: registered new interface drivserial
[ 2.999010] usbcore: registered new interface drivserial_generic
[ 3.004794] usbserial: USB Serial support register generic
[ 3.024955] usb3503 usb2513: switched to HUB mode
[ 3.025072] usb3503 usb2513: usb3503_probe: probedb mode
[ 3.035466] usbip_core: USB/IP Core v1.0.0
[ 3.036356] usbcore: registered new device driver host
[ 3.038754] usbip_host: USB/IP Host Driver v1.0.0
[ 3.050455] mousedev: PS/2 mouse device common forice
[ 3.062851] input: pm8941_pwrkey as /devices/platfc/200f000.spmi/spmi-0/0-00/200f000.spmi:pm8916@0:pwrk/input/input0
[ 3.075336] rtc-pm8xxx 200f000.spmi:pm8916@0:rtc@6tc core: registered pm8xxx_rtc as rtc0
[ 3.078194] i2c /dev entries driver
[ 3.088459] i2c_qup 78b6000.i2c:
[ 3.088459] tx channel not available
[ 3.099202] i2c_qup 78ba000.i2c:
[ 3.099202] tx channel not available
[ 3.111778] qcom-camss 1b0ac00.camss: notifier->nuevs = 0
[ 3.119755] msm_cci_init_gpio_params:1371 gpio cou
[ 3.134839] l3: supplied by s3
[ 3.138693] CPR closed loop is enabled
[ 3.142249] CPR is enabled!
[ 3.159777] qcom-tsens 4a8000.thermal-sensor: tsenbration failed
[ 3.161534] Bluetooth: HCI UART driver ver 2.3
[ 3.165445] Bluetooth: HCI UART protocol H4 regist
[ 3.169810] Bluetooth: HCI UART protocol BCSP regi
[ 3.174968] Bluetooth: HCI UART protocol Three-wir registered
[ 3.181563] usbcore: registered new interface drivsb
[ 3.192840] cpu: dev_pm_opp_of_cpumask_add_table: 't find opp table for cpu:0, -19
[ 3.216246] sdhci: Secure Digital Host Controller ace driver
[ 3.216489] sdhci: Copyright© Pierre Ossman
[ 3.222867] Synopsys Designware Multimedia Card Ine Driver
[ 3.232102] sdhci-pltfm: SDHCI platform and OF drilper
[ 3.278497] l5: supplied by s4
[ 3.358499] mmc0: SDHCI controller on 7824900.sdhc4900.sdhci] using ADMA 64-bit
[ 3.368233] sdhci_msm 7864900.sdhci: Got CD GPIO
[ 3.522792] mmc1: SDHCI controller on 7864900.sdhc4900.sdhci] using ADMA 64-bit
[ 3.577194] ledtrig-cpu: registered to indicate ac on CPUs
[ 3.607051] usbcore: registered new interface drivhid
[ 3.607733] usbhid: USB HID core driver
[ 3.660013] 4080000.hexagon supply cx not found, uummy regulator
[ 3.668321] 4080000.hexagon supply mss not found, dummy regulator
[ 3.672403] l7: supplied by s4
[ 3.696406] remoteproc0: 4080000.hexagon is avail
[ 3.696681] remoteproc0: Note: remoteproc is stilr development and considered experimental.
[ 3.700702] remoteproc0: THE BINARY FORMAT IS NOTINALIZED, and backward compatibility isn’t yet guaran
[ 3.712228] remoteproc0: Direct firmware load forbn failed with error -2
[ 3.734991] qcom-tz-pil soc:vidc_tzpil@0: no crashn id
[ 3.739908] soc:vidc_tzpil@0 supply qcom,pll not fusing dummy regulator
[ 3.740623] qcom-tz-pil soc:vidc_tzpil@0: failed t qcom,pll_uV, skipping
[ 3.747103] qcom-tz-pil soc:vidc_tzpil@0: failed t qcom,pll_uA, skipping
[ 3.754253] qcom-tz-pil soc:vidc_tzpil@0: no wdog fined
[ 3.761557] qcom-tz-pil soc:vidc_tzpil@0: no fatalefined
[ 3.767170] qcom-tz-pil soc:vidc_tzpil@0: no readyefined
[ 3.772933] qcom-tz-pil soc:vidc_tzpil@0: no handoQ defined
[ 3.778691] qcom-tz-pil soc:vidc_tzpil@0: no stop-Q defined
[ 3.786244] qcom-tz-pil soc:vidc_tzpil@0: Found reon area 8388608@0x000000008f800000
[ 3.792016] remoteproc1: soc:vidc_tzpil@0 is avai
[ 3.799140] remoteproc1: Note: remoteproc is stilr development and considered experimental.
[ 3.804241] remoteproc1: THE BINARY FORMAT IS NOTINALIZED, and backward compatibility isn’t yet guaran
[ 3.814566] remoteproc1: Direct firmware load for.mdt failed with error -2
[ 3.836960] remoteproc1: failed to load soc:vidc_0
[ 3.850869] a204000.wcnss supply vddcx not found, dummy regulator
[ 3.891925] remoteproc2: a204000.wcnss is availab
[ 3.892096] remoteproc2: Note: remoteproc is stilr development and considered experimental.
[ 3.896211] remoteproc2: THE BINARY FORMAT IS NOTINALIZED, and backward compatibility isn’t yet guaran
[ 3.906957] remoteproc2: Direct firmware load for.mdt failed with error -2
[ 3.934771] mmc0: new MMC card at address 0001
[ 3.963967] mmcblk0: mmc0:0001 16 8.00 KiB
[ 3.979973] l5: Restricting voltage, 2700000-18000
[ 4.076120] coresight-tmc 825000.etf: TMC initiali
[ 4.164277] coresight-tmc 826000.etr: TMC initiali
[ 4.201393] coresight-tpiu 820000.tpiu: TPIU initi
[ 4.244306] coresight-funnel 821000.funnel: FUNNELalized
[ 4.264469] coresight-funnel 841000.funnel: FUNNELalized
[ 4.298742] coresight-etm4x 85c000.etm: ETM 4.0 inzed
[ 4.322554] coresight-etm4x 85d000.etm: ETM 4.0 inzed
[ 4.347794] coresight-etm4x 85e000.etm: ETM 4.0 inzed
[ 4.368346] coresight-etm4x 85f000.etm: ETM 4.0 inzed
[ 4.394724] coresight-replicator-qcom 824000.repli REPLICATOR 1.0 initialized
[ 4.419767] usbcore: registered new interface driv-usb-audio
[ 4.484257] apq8016-lpass-cpu 7708000.lpass-cpu: aom_lpass_cpu_platform_probe() error getting mi2s-osr-2
[ 4.485398] apq8016-lpass-cpu 7708000.lpass-cpu: aom_lpass_cpu_platform_probe() error getting mi2s-osr-2
[ 4.494512] apq8016-lpass-cpu 7708000.lpass-cpu: aom_lpass_cpu_platform_probe() error getting mi2s-osr-2
[ 4.505499] apq8016-lpass-cpu 7708000.lpass-cpu: aom_lpass_cpu_platform_probe() error getting mi2s-osr-2
[ 4.549700] msm8916-wcd-codec 200f000.spmi:pm8916@c@f000: PMIC REV: 1 CODEC Version: 1
[ 4.564514] qcom-apq8016-sbc 7702000.sound: msm891i2s_rx1 <-> Primary MI2S mapping ok
[ 4.570412] qcom-apq8016-sbc 7702000.sound: msm891i2s_tx1 <-> Tertiary MI2S mapping ok
[ 4.674484] NET: Registered protocol family 10
[ 4.707303] sit: IPv6 over IPv4 tunneling driver
[ 4.723440] NET: Registered protocol family 17
[ 4.731846] Bluetooth: RFCOMM TTY layer initialize
[ 4.732323] Bluetooth: RFCOMM socket layer initial
[ 4.736263] Bluetooth: RFCOMM ver 1.11
[ 4.741268] Bluetooth: HIDP (Human Interface Emulaver 1.2
[ 4.744983] Bluetooth: HIDP socket layer initializ
[ 4.751717] 9pnet: Installing 9P2000 support
[ 4.758051] Key type dns_resolver registered
[ 4.761055] NET: Registered protocol family 42
[ 4.802928] registered taskstats version 1
[ 4.852297] msm_iommu 1f00000.qcom,iommu: invalid ce
[ 4.859535] msm_iommu 1f00000.qcom,iommu: iface: 100, core: 800000000
[ 4.860042] msm_iommu 1f00000.qcom,iommu: device gmu (model: 500) mapped at ffffff8000b20000, with 2 cts
[ 4.866242] msm_iommu 1f00000.qcom,iommu: iommu seable size: 94208
[ 4.901065] genirq: Setting trigger mode 1 for irqailed (gic_set_type+0x0/0x5c)
[ 4.903543] msm_iommu 1f00000.qcom,iommu: Request CFG IRQ 158 failed with ret=-22
[ 4.928750] msm_iommu_ctx 1f09000.qcom,iommu-ctx: t gfx3d_user using bank 1
[ 4.949641] msm_iommu_ctx 1f0a000.qcom,iommu-ctx: t gfx3d_priv using bank 2
[ 4.955183] msm_iommu 1e00000.qcom,iommu: iface: 100, core: 800000000
[ 4.957310] msm_iommu 1e00000.qcom,iommu: device ammu (model: 500) mapped at ffffff8006400000, with 20 nks
[ 4.973269] genirq: Setting trigger mode 1 for irqailed (gic_set_type+0x0/0x5c)
[ 4.979515] msm_iommu 1e00000.qcom,iommu: Request CFG IRQ 158 failed with ret=-22
[ 4.995782] msm_iommu_ctx 1e22000.qcom,iommu-ctx: t jpeg_enc0 using bank 2
[ 5.009078] msm_iommu_ctx 1e23000.qcom,iommu-ctx: t vfe using bank 3
[ 5.024707] msm_iommu_ctx 1e24000.qcom,iommu-ctx: t mdp_0 using bank 4
[ 5.039826] msm_iommu_ctx 1e25000.venus_ns: contexs_ns using bank 5
[ 5.049625] msm_iommu_ctx 1e26000.qcom,iommu-ctx: t cpp using bank 6
[ 5.056498] msm_iommu_ctx 1e27000.qcom,iommu-ctx: t mDSP using bank 7
[ 5.076930] msm_iommu_ctx 1e28000.qcom,iommu-ctx: t gss using bank 8
[ 5.094016] msm_iommu_ctx 1e29000.qcom,iommu-ctx: t a2 using bank 9
[ 5.111962] msm_iommu_ctx 1e32000.qcom,iommu-ctx: t mdp_1 using bank 18
[ 5.128504] msm_iommu_ctx 1e33000.venus_sec_pixel:xt venus_sec_pixel using bank 19
[ 5.143862] msm_iommu_ctx 1e34000.venus_sec_bitstrontext venus_sec_bitstream using bank 20
[ 5.159888] msm_iommu_ctx 1e35000.venus_sec_non_piontext venus_sec_non_pixel using bank 21
[ 5.177045] msm_iommu_ctx 1e36000.qcom,iommu-ctx: t venus_fw using bank 22
[ 5.184859] msm_iommu_ctx 1e37000.qcom,iommu-ctx: t periph_rpm using bank 23
[ 5.201900] msm_iommu_ctx 1e38000.qcom,iommu-ctx: t periph_CE using bank 24
[ 5.221674] msm_iommu_ctx 1e39000.qcom,iommu-ctx: t periph_BLSP using bank 25
[ 5.240722] msm_iommu_ctx 1e3a000.qcom,iommu-ctx: t periph_SDC1 using bank 26
[ 5.253710] msm_iommu_ctx 1e3b000.qcom,iommu-ctx: t periph_SDC2 using bank 27
[ 5.270615] msm_iommu_ctx 1e3c000.qcom,iommu-ctx: t periph_audio using bank 28
[ 5.284420] msm_iommu_ctx 1e3d000.qcom,iommu-ctx: t periph_USB_HS1 using bank 29
[ 5.303373] msm_otg 78d9000.phy: OTG regs = ffffffc000
[ 5.309831] msm_otg 78d9000.phy: no vddcx
[ 5.444079] msm_hsusb_host 78d9000.ehci: EHCI Hostoller
[ 5.444495] msm_hsusb_host 78d9000.ehci: new USB bistered, assigned bus number 1
[ 5.449327] msm_hsusb_host 78d9000.ehci: irq 148, 0x078d9000
[ 5.460675] input: gpio_keys as /devices/platform/eys/input/input1
[ 5.463494] rtc-pm8xxx 200f000.spmi:pm8916@0:rtc@6etting system clock to 1970-01-01 00:31:54 UTC (1914)
[ 5.469768] msm_hsusb_host 78d9000.ehci: USB 2.0 s, EHCI 1.00
[ 5.489178] ALSA device li辴 5.492172] Freeing kernel memory: 576K (ffffffc000c40000 - ffffffc000cd
[ 5.492248] Freeing alternatives memory: 64K (ffffcd0000 - ffffffc000ce0000)
[ 5.499334] usb usb1: New USB device found, idVendb, idProduct=0002
[ 5.506381] usb usb1: New USB device strings: Mfr=duct=2, SerialNumber=1
[ 5.513246] usb usb1: Product: EHCI Host Controlle
[ 5.520345] usb usb1: Manufacturer: Linux 4.4.23-llt-qcom ehci_hcd
[ 5.525145] usb usb1: SerialNumber: 78d9000.ehci
[ 5.533149] hub 1-0:1.0: USB hub found
[ 5.540209] hub 1-0:1.0: 1 port detected

starting version 230
[ 5.561744] random: systemd-udevd: uninitialized u read (16 bytes read, 17 bits of entropy available)
[ 5.562333] random: systemd-udevd: uninitialized u read (16 bytes read, 17 bits of entropy available)
[ 5.566957] random: udevadm: uninitialized urandom(16 bytes read, 17 bits of entropy available)
[ 5.567165] random: udevadm: uninitialized urandom(16 bytes read, 17 bits of entropy available)
[ 5.567235] random: udevadm: uninitialized urandom(16 bytes read, 17 bits of entropy available)
[ 5.567360] random: udevadm: uninitialized urandom(16 bytes read, 17 bits of entropy available)
[ 5.567780] random: udevadm: uninitialized urandom(16 bytes read, 17 bits of entropy available)
[ 5.567901] random: udevadm: uninitialized urandom(16 bytes read, 17 bits of entropy available)
[ 5.568299] random: udevadm: uninitialized urandom(16 bytes read, 17 bits of entropy available)
[ 5.568422] random: udevadm: uninitialized urandom(16 bytes read, 17 bits of entropy available)
[ 5.856578] usb 1-1: new high-speed USB device numusing msm_hsusb_host
[ 5.989028] usb 1-1: New USB device found, idVendo, idProduct=2514
[ 5.989054] usb 1-1: New USB device strings: Mfr=0uct=0, SerialNumber=0
[ 5.995570] hub 1-1:1.0: USB hub found
[ 6.002542] hub 1-1:1.0: 4 ports detected


#3

continue…

Full Log(Click here)

Begin: Loading essential drivers … done.
Begin: Running /scripts/init-premount … done.
Begin: Mounting root file system … Begin: Running /s/local-top … done.
Begin: Running /scripts/local-premount … done.
Begin: Waiting for root file system … Begin: Runninipts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
[ 11.546629] mmcblk0: error -110 sending stop commaiginal cmd response 0x2000900, card status 0x2400900
[ 11.546702] mmcblk0: error -110 transferring data,r 0, nr 8, cmd response 0x2000900, card status 0x0
[ 11.555707] mmcblk0: retrying using single block r
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
[ 19.117220] mmcblk0: error -110 transferring data,r 0, nr 8, cmd response 0x2000900, card status 0x0
[ 19.117295] blk_update_request: I/O error, dev mmcsector 0
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
[ 26.678044] mmcblk0: error -110 transferring data,r 1, nr 7, cmd response 0x2000900, card status 0x0
[ 26.678121] blk_update_request: I/O error, dev mmcsector 1
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
[ 34.238975] mmcblk0: error -110 transferring data,r 2, nr 6, cmd response 0x2000900, card status 0x0
[ 34.239049] blk_update_request: I/O error, dev mmcsector 2
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
Begin: Running /scripts/local-block … done.
done.
Gave up waiting for root device. Common problems:

  • Boot args (cat /proc/cmdline)
    • Check rootdelay= (did the system wait long enoug
    • Check root= (did the system wait for the right d)
  • Missing modules (cat /proc/modules; ls /dev)
    ALERT! /dev/disk/by-partlabel/rootfs does not exist.ping to a shell!
    (initramfs) [ 41.008833] random: nonblocking pool iialized
    [ 41.799794] mmcblk0: error -110 transferring data,r 3, nr 5, cmd response 0x2000900, card status 0x0
    [ 41.799864] blk_update_request: I/O error, dev mmcsector 3
    [ 49.360738] mmcblk0: error -110 transferring data,r 4, nr 4, cmd response 0x2000900, card status 0x0
    [ 49.360807] blk_update_request: I/O error, dev mmcsector 4
    [ 56.921616] mmcblk0: error -110 transferring data,r 5, nr 3, cmd response 0x2000900, card status 0x0
    [ 56.921683] blk_update_request: I/O error, dev mmcsector 5
    [ 64.482380] mmcblk0: error -110 transferring data,r 6, nr 2, cmd response 0x2000900, card status 0x0
    [ 64.482447] blk_update_request: I/O error, dev mmcsector 6
    [ 72.043164] mmcblk0: error -110 transferring data,r 7, nr 1, cmd response 0x2000900, card status 0x0
    [ 72.043232] blk_update_request: I/O error, dev mmcsector 7
    [ 72.051799] Buffer I/O error on dev mmcblk0, logicck 0, async page read
    [ 79.612640] mmcblk0: error -110 sending stop commaiginal cmd response 0x2000900, card status 0x2400900
    [ 79.612705] mmcblk0: error -110 transferring data,r 0, nr 8, cmd response 0x2000900, card status 0x0
    [ 79.621704] mmcblk0: retrying using single block r
    [ 87.183196] mmcblk0: error -110 transferring data,r 0, nr 8, cmd response 0x2000900, card status 0x0
    [ 87.183264] blk_update_request: I/O error, dev mmcsector 0
    [ 94.744064] mmcblk0: error -110 transferring data,r 1, nr 7, cmd response 0x2000900, card status 0x0
    [ 94.744132] blk_update_request: I/O error, dev mmcsector 1
    [ 102.304923] mmcblk0: error -110 transferring data,r 2, nr 6, cmd response 0x2000900, card status 0x0
    [ 102.304989] blk_update_request: I/O error, dev mmcsector 2
    [ 109.865711] mmcblk0: error -110 transferring data,r 3, nr 5, cmd response 0x2000900, card status 0x0
    [ 109.865779] blk_update_request: I/O error, dev mmcsector 3
    [ 117.426480] mmcblk0: error -110 transferring data,r 4, nr 4, cmd response 0x2000900, card status 0x0
    [ 117.426549] blk_update_request: I/O error, dev mmcsector 4
    [ 124.987351] mmcblk0: error -110 transferring data,r 5, nr 3, cmd response 0x2000900, card status 0x0
    [ 124.987418] blk_update_request: I/O error, dev mmcsector 5
    [ 132.548113] mmcblk0: error -110 transferring data,r 6, nr 2, cmd response 0x2000900, card status 0x0
    [ 132.548180] blk_update_request: I/O error, dev mmcsector 6
    [ 140.108990] mmcblk0: error -110 transferring data,r 7, nr 1, cmd response 0x2000900, card status 0x0
    [ 140.109058] blk_update_request: I/O error, dev mmcsector 7
    [ 140.117732] Buffer I/O error on dev mmcblk0, logicck 0, async page read
    [ 140.123997] mmcblk0: unable to read partition tab
    [ 140.186194] mmc1: new high speed SDHC card at addr01
    [ 140.188985] mmcblk1: mmc1:0001 SD 14.9 GiB
    [ 140.206192] GPT:Primary header thinks Alt. header at the end of the disk.
    [ 140.206254] GPT:15759359 != 31211519
    [ 140.212776] GPT:Alternate GPT header not at the enhe disk.
    [ 140.216236] GPT:15759359 != 31211519
    [ 140.222253] GPT: Use GNU Parted to correct GPT err
    [ 140.225949] mmcblk1: p1 p2 p3 p4 p5 p6 p7 p8
    [ 147.704455] mmcblk0: error -110 sending stop commaiginal cmd response 0x2000900, card status 0x2400900
    [ 147.704634] mmcblk0: error -110 transferring data,r 0, nr 8, cmd response 0x2000900, card status 0x0
    [ 147.713426] mmcblk0: retrying using single block r
    [ 155.275094] mmcblk0: error -110 transferring data,r 0, nr 8, cmd response 0x2000900, card status 0x0
    [ 155.275165] blk_update_request: I/O error, dev mmcsector 0
    [ 162.835994] mmcblk0: error -110 transferring data,r 1, nr 7, cmd response 0x2000900, card status 0x0
    [ 162.836062] blk_update_request: I/O error, dev mmcsector 1
    [ 170.396770] mmcblk0: error -110 transferring data,r 2, nr 6, cmd response 0x2000900, card status 0x0
    [ 170.396838] blk_update_request: I/O error, dev mmcsector 2
    [ 177.957617] mmcblk0: error -110 transferring data,r 3, nr 5, cmd response 0x2000900, card status 0x0
    [ 177.957684] blk_update_request: I/O error, dev mmcsector 3
    [ 185.518389] mmcblk0: error -110 transferring data,r 4, nr 4, cmd response 0x2000900, card status 0x0
    [ 185.518456] blk_update_request: I/O error, dev mmcsector 4
    [ 193.079249] mmcblk0: error -110 transferring data,r 5, nr 3, cmd response 0x2000900, card status 0x0
    [ 193.079317] blk_update_request: I/O error, dev mmcsector 5
    [ 200.640113] mmcblk0: error -110 transferring data,r 6, nr 2, cmd response 0x2000900, card status 0x0
    [ 200.640182] blk_update_request: I/O error, dev mmcsector 6
    [ 208.201005] mmcblk0: error -110 transferring data,r 7, nr 1, cmd response 0x2000900, card status 0x0
    [ 208.201073] blk_update_request: I/O error, dev mmcsector 7
    [ 215.764375] mmcblk0: error -110 sending stop commaiginal cmd response 0x2000900, card status 0x2400900
    [ 215.764443] mmcblk0: error -110 transferring data,r 0, nr 8, cmd response 0x2000900, card status 0x0
    [ 215.773443] mmcblk0: retrying using single block r
    [ 223.334929] mmcblk0: error -110 transferring data,r 0, nr 8, cmd response 0x2000900, card status 0x0
    [ 223.334996] blk_update_request: I/O error, dev mmcsector 0
    [ 230.895798] mmcblk0: error -110 transferring data,r 1, nr 7, cmd response 0x2000900, card status 0x0
    [ 230.895866] blk_update_request: I/O error, dev mmcsector 1
    [ 238.456667] mmcblk0: error -110 transferring data,r 2, nr 6, cmd response 0x2000900, card status 0x0
    [ 238.456735] blk_update_request: I/O error, dev mmcsector 2

#4

It really would be better to use a pastebin or github gist for this! Massive logs posted in line make scrolling through the topic a bit cumbersome!


#5

To be honest the could easily be a broken board although I also notice that you are installing a rather old release.

So…

Does this reproduce using the 18.01 release?
Do you observe this problem on just one board or across many?


#6

Hi @danielt,

Thanks for the remind and your quick response, I’ll use pastebin or github next time.
Let me hide full log to summery first…

Does this reproduce using the 18.01 release?

[Johnny] I’ll try to flash the 18.01 release to our board, and check whether it can recovery or not.

Do you observe this problem on just one board or across many?

[Johnny] It happened on 4/600 boards. Those 600 boards can flash the image (16.09) normally.
But there are 4 boards are failed when we tried to flash again with the new image (also based on 16.09).
So is it may caused the broken board or maybe we have something wrong?

Thanks.

Best regards,
Johnny


#7

I like the

details trick... I've not seen that before

and I hope to see it more in the future!