Package: linux-image-4.9.0-1-armmp Severity: normal I recently switched to linux 4.9.0 (jessie-backport package on one, package from testing in the other) and latest u-boot on my two A20-OLinuXIno-LIME2 boards, which were running seriously outdated versions of linux until now (4.4 and 4.0).
Both systems have their rootfs on a microSD card, and have had the same issue after the upgrade: within hours of the boot process, the µSD fails to read/write with various errors from the mmc subsystem. In one of the systems (I don't have physical access to the second sytem right now), I have successfully reverted to linux 4.4, so this isn't a hardware issue or an issue caused by u-boot. Although it isn't about the same hardware nor the same version of the kernel, there is a similar bug report on Fedora's bugtracker, suggesting this is an upstream problem in the sunxi-mmc driver, but I haven't tried upstream's kernel without Debian patches yet, nor have I attempted to bisect the issue: https://bugzilla.redhat.com/show_bug.cgi?id=1419575 Here is a log from one of the two systems (the one that uses the kernel from testing): [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.9.0-1-armmp (debian-ker...@lists.debian.org) (gcc version 6.3.0 20170124 (Debian 6.3.0-5) ) #1 SMP Debian 4.9.6-3 (2017-01-28) [ 0.000000] CPU: ARMv7 Processor [410fc074] revision 4 (ARMv7), cr=10c5387d [ 0.000000] CPU: div instructions available: patching division code [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] OF: fdt:Machine model: Olimex A20-OLinuXino-LIME2 [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: UEFI not found. [ 0.000000] cma: Reserved 16 MiB at 0x7f000000 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] On node 0 totalpages: 262144 [ 0.000000] free_area_init_node: node 0, pgdat c0ce0d00, node_mem_map ef6f9000 [ 0.000000] Normal zone: 1728 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 196608 pages, LIFO batch:31 [ 0.000000] HighMem zone: 65536 pages, LIFO batch:15 [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: Using PSCI v0.1 Function IDs from DT [ 0.000000] percpu: Embedded 14 pages/cpu @ef6c1000 s27724 r8192 d21428 u57344 [ 0.000000] pcpu-alloc: s27724 r8192 d21428 u57344 alloc=14*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 260416 [ 0.000000] Kernel command line: root=/dev/mmcblk0p1 loglevel=7 ro rootwait [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes) [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Memory: 994060K/1048576K available (7168K kernel code, 957K rwdata, 2212K rodata, 1024K init, 396K bss, 38132K reserved, 16384K cma-reserved, 245760K highmem) [ 0.000000] Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xf0800000 - 0xff800000 ( 240 MB) lowmem : 0xc0000000 - 0xf0000000 ( 768 MB) pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) modules : 0xbf000000 - 0xbfe00000 ( 14 MB) .text : 0xc0008000 - 0xc0800000 (8160 kB) .init : 0xc0b00000 - 0xc0c00000 (1024 kB) .data : 0xc0c00000 - 0xc0cef47c ( 958 kB) .bss : 0xc0cf1000 - 0xc0d54104 ( 397 kB) [ 0.000000] Hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 32. [ 0.000000] RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=2. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=32, nr_cpu_ids=2 [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] arm_arch_timer: Architected cp15 timer(s) running at 24.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns [ 0.000008] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns [ 0.000024] Switching to timer-based delay loop, resolution 41ns [ 0.002773] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns [ 0.003660] clocksource: hstimer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 12741736309 ns [ 0.004405] Console: colour dummy device 80x30 [ 0.005264] console [tty0] enabled [ 0.005314] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=120000) [ 0.005366] pid_max: default: 32768 minimum: 301 [ 0.005697] Security Framework initialized [ 0.005732] Yama: disabled by default; enable with sysctl kernel.yama.* [ 0.005794] AppArmor: AppArmor disabled by boot time parameter [ 0.005915] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.005950] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.007161] CPU: Testing write buffer coherency: ok [ 0.007263] ftrace: allocating 26622 entries in 79 pages [ 0.068797] /cpus/cpu@0 missing clock-frequency property [ 0.068867] /cpus/cpu@1 missing clock-frequency property [ 0.068897] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.068980] Setting up static identity map for 0x40100000 - 0x40100098 [ 0.074775] EFI services will not be available. [ 0.086562] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.086792] Brought up 2 CPUs [ 0.086854] SMP: Total of 2 processors activated (96.00 BogoMIPS). [ 0.086878] CPU: All CPU(s) started in HYP mode. [ 0.086897] CPU: Virtualization extensions available. [ 0.088162] devtmpfs: initialized [ 0.100333] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 4 [ 0.100895] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 9556302231375000 ns [ 0.101916] pinctrl core: initialized pinctrl subsystem [ 0.104232] NET: Registered protocol family 16 [ 0.106247] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.109155] No ATAGs? [ 0.109214] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.109259] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.110306] Serial: AMBA PL011 UART driver [ 0.145617] reg-fixed-voltage ahci-5v: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/ahci_pwr_pin@1, deferring probe [ 0.145730] reg-fixed-voltage usb0-vbus: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/usb0_vbus_pin@0, deferring probe [ 0.145802] reg-fixed-voltage usb1-vbus: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/usb1_vbus_pin@0, deferring probe [ 0.145870] reg-fixed-voltage usb2-vbus: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/usb2_vbus_pin@0, deferring probe [ 0.149797] vgaarb: loaded [ 0.150941] media: Linux media interface: v0.10 [ 0.151038] Linux video capture interface: v2.00 [ 0.151152] pps_core: LinuxPPS API ver. 1 registered [ 0.151179] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giome...@linux.it> [ 0.151236] PTP clock support registered [ 0.153479] clocksource: Switched to clocksource arch_sys_counter [ 0.204610] VFS: Disk quotas dquot_6.6.0 [ 0.204770] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.220776] NET: Registered protocol family 2 [ 0.221816] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 0.221942] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 0.222087] TCP: Hash tables configured (established 8192 bind 8192) [ 0.222205] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 0.222290] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 0.222729] NET: Registered protocol family 1 [ 0.222805] PCI: CLS 0 bytes, default 64 [ 0.223668] Unpacking initramfs... [ 1.455337] Freeing initrd memory: 15204K (c9127000 - ca000000) [ 1.456168] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available [ 1.458264] futex hash table entries: 512 (order: 3, 32768 bytes) [ 1.458582] audit: initializing netlink subsys (disabled) [ 1.458759] audit: type=2000 audit(1.415:1): initialized [ 1.459693] Initialise system trusted keyrings [ 1.460344] workingset: timestamp_bits=14 max_order=18 bucket_order=4 [ 1.460570] zbud: loaded [ 1.822970] Key type asymmetric registered [ 1.823023] Asymmetric key parser 'x509' registered [ 1.823147] bounce: pool size: 64 pages [ 1.823318] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) [ 1.823673] io scheduler noop registered [ 1.823710] io scheduler deadline registered [ 1.823810] io scheduler cfq registered (default) [ 1.830149] sun7i-a20-pinctrl 1c20800.pinctrl: initialized sunXi PIO driver [ 1.846669] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 1.869767] 1c28000.serial: ttyS0 at MMIO 0x1c28000 (irq = 42, base_baud = 1500000) is a U6_16550A [ 2.549293] console [ttyS0] enabled [ 2.553780] Serial: AMBA driver [ 2.560698] libphy: Fixed MDIO Bus: probed [ 2.565907] mousedev: PS/2 mouse device common for all mice [ 2.574066] sunxi-rtc 1c20d00.rtc: rtc core: registered rtc-sunxi as rtc0 [ 2.580935] sunxi-rtc 1c20d00.rtc: RTC enabled [ 2.588122] ledtrig-cpu: registered to indicate activity on CPUs [ 2.595152] NET: Registered protocol family 10 [ 2.601045] mip6: Mobile IPv6 [ 2.604157] NET: Registered protocol family 17 [ 2.608660] mpls_gso: MPLS GSO support [ 2.612524] ThumbEE CPU extension supported. [ 2.616849] Registering SWP/SWPB emulation handler [ 2.622792] registered taskstats version 1 [ 2.626981] Loading compiled-in X.509 certificates [ 2.644424] alg: No test for pkcs1pad(rsa,sha256) (pkcs1pad(rsa-generic,sha256)) [ 2.654227] Loaded X.509 cert 'Debian Project: Ben Hutchings: 008a018dca80932630' [ 2.661919] zswap: loaded using pool lzo/zbud [ 2.677559] sunxi-rtc 1c20d00.rtc: setting system clock to 2017-02-23 09:25:06 UTC (1487841906) [ 2.686411] sr_init: No PMIC hook to init smartreflex [ 2.691701] sr_init: platform driver register failed for SR [ 2.697603] PM: Hibernation image not present or could not be loaded. [ 2.697793] vcc3v0: disabling [ 2.700834] vcc3v3: disabling [ 2.703842] vcc5v0: disabling [ 2.706825] ahci-5v: disabling [ 2.709925] usb0-vbus: disabling [ 2.713167] usb1-vbus: disabling [ 2.716425] usb2-vbus: disabling [ 2.721415] Freeing unused kernel memory: 1024K (c0b00000 - c0c00000) [ 2.816414] systemd-udevd[75]: starting version 215 [ 2.824759] random: systemd-udevd: uninitialized urandom read (16 bytes read) [ 2.970373] sunxi-mmc 1c0f000.mmc: Got CD GPIO [ 3.003628] SCSI subsystem initialized [ 3.025652] sunxi-mmc 1c0f000.mmc: base:0xf08cd000 irq:27 [ 3.032937] libata version 3.00 loaded. [ 3.104651] mmc0: host does not support reading read-only switch, assuming write-enable [ 3.109627] ahci-sunxi 1c18000.sata: controller can't do PMP, turning off CAP_PMP [ 3.109639] ahci-sunxi 1c18000.sata: forcing PORTS_IMPL to 0x1 [ 3.109702] ahci-sunxi 1c18000.sata: AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl platform mode [ 3.109714] ahci-sunxi 1c18000.sata: flags: ncq sntf pm led clo only pio slum part ccc [ 3.135438] scsi host0: ahci-sunxi [ 3.136039] ata1: SATA max UDMA/133 mmio [mem 0x01c18000-0x01c18fff] port 0x100 irq 32 [ 3.196263] sun7i-dwmac 1c50000.ethernet: no regulator found [ 3.210100] sun7i-dwmac 1c50000.ethernet: no reset control found [ 3.220840] Ring mode enabled [ 3.224057] DMA HW capability register supported [ 3.228708] Normal descriptors [ 3.238805] mmc0: new high speed SDHC card at address aaaa [ 3.251697] libphy: stmmac: probed [ 3.252867] mmcblk0: mmc0:aaaa SE16G 14.8 GiB [ 3.259724] eth%d: PHY ID 001cc912 at 1 IRQ POLL (stmmac-0:01) active [ 3.267903] mmcblk0: p1 [ 3.362470] random: fast init done [ 3.470136] ata1: SATA link down (SStatus 0 SControl 300) [ 3.949193] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null) [ 4.356857] systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) [ 4.370891] systemd[1]: Detected architecture 'arm'. [ 4.475419] systemd[1]: Inserted module 'autofs4' [ 4.482311] systemd[1]: Set hostname to <neiro>. [ 4.896485] systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. [ 4.914774] systemd[1]: Expecting device dev-ttyS0.device... [ 4.933726] systemd[1]: Starting Forward Password Requests to Wall Directory Watch. [ 4.941958] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 4.949800] systemd[1]: Starting Remote File Systems (Pre). [ 4.968645] systemd[1]: Reached target Remote File Systems (Pre). [ 5.888754] systemd-udevd[169]: starting version 215 [ 6.455932] sun4i-ss 1c15000.crypto-engine: no reset control found [ 6.464079] sun4i-ss 1c15000.crypto-engine: Die ID 0 [ 6.570509] usbcore: registered new interface driver usbfs [ 6.576263] usbcore: registered new interface driver hub [ 6.599075] usbcore: registered new device driver usb [ 6.652173] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 6.677512] sunxi-wdt 1c20c90.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0) [ 6.712943] ehci-platform: EHCI generic platform driver [ 6.756678] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 6.775757] axp20x-i2c 0-0034: AXP20x variant AXP209 found [ 6.812931] ohci-platform: OHCI generic platform driver [ 6.860570] axp20x-i2c 0-0034: AXP20X driver loaded [ 6.867371] ehci-platform 1c14000.usb: EHCI Host Controller [ 6.873230] ehci-platform 1c14000.usb: new USB bus registered, assigned bus number 1 [ 6.890057] ehci-platform 1c14000.usb: irq 29, io mem 0x01c14000 [ 6.908645] ehci-platform 1c14000.usb: USB 2.0 started, EHCI 1.00 [ 6.917109] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [ 6.924127] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.931518] usb usb1: Product: EHCI Host Controller [ 6.936517] usb usb1: Manufacturer: Linux 4.9.0-1-armmp ehci_hcd [ 6.942687] usb usb1: SerialNumber: 1c14000.usb [ 6.979537] hub 1-0:1.0: USB hub found [ 6.989308] hub 1-0:1.0: 1 port detected [ 7.012371] ohci-platform 1c14400.usb: Generic Platform OHCI controller [ 7.026868] ohci-platform 1c14400.usb: new USB bus registered, assigned bus number 2 [ 7.045483] ohci-platform 1c14400.usb: irq 30, io mem 0x01c14400 [ 7.114937] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001 [ 7.121933] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 7.129287] usb usb2: Product: Generic Platform OHCI controller [ 7.135308] usb usb2: Manufacturer: Linux 4.9.0-1-armmp ohci_hcd [ 7.141408] usb usb2: SerialNumber: 1c14400.usb [ 7.154310] hub 2-0:1.0: USB hub found [ 7.162303] hub 2-0:1.0: 1 port detected [ 7.179514] usb_phy_generic.0.auto supply vcc not found, using dummy regulator [ 7.196290] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver [ 7.221855] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 3 [ 7.236211] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002 [ 7.243156] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 7.250466] usb usb3: Product: MUSB HDRC host driver [ 7.255477] usb usb3: Manufacturer: Linux 4.9.0-1-armmp musb-hcd [ 7.261522] usb usb3: SerialNumber: musb-hdrc.1.auto [ 7.270365] hub 3-0:1.0: USB hub found [ 7.274384] hub 3-0:1.0: 1 port detected [ 7.301657] ehci-platform 1c1c000.usb: EHCI Host Controller [ 7.312030] ehci-platform 1c1c000.usb: new USB bus registered, assigned bus number 4 [ 7.324653] ehci-platform 1c1c000.usb: irq 33, io mem 0x01c1c000 [ 7.348661] ehci-platform 1c1c000.usb: USB 2.0 started, EHCI 1.00 [ 7.361874] usb usb4: New USB device found, idVendor=1d6b, idProduct=0002 [ 7.368879] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 7.376259] usb usb4: Product: EHCI Host Controller [ 7.381265] usb usb4: Manufacturer: Linux 4.9.0-1-armmp ehci_hcd [ 7.387455] usb usb4: SerialNumber: 1c1c000.usb [ 7.393569] hub 4-0:1.0: USB hub found [ 7.397532] hub 4-0:1.0: 1 port detected [ 7.407967] ohci-platform 1c1c400.usb: Generic Platform OHCI controller [ 7.416530] ohci-platform 1c1c400.usb: new USB bus registered, assigned bus number 5 [ 7.429601] at24 1-0050: 2048 byte 24c16 EEPROM, writable, 16 bytes/write [ 7.439481] ohci-platform 1c1c400.usb: irq 34, io mem 0x01c1c400 [ 7.450290] input: axp20x-pek as /devices/platform/soc@01c00000/1c2ac00.i2c/i2c-0/0-0034/axp20x-pek/input/input0 [ 7.461841] EXT4-fs (mmcblk0p1): re-mounted. Opts: errors=remount-ro [ 7.469233] ldo1: supplied by axp-ipsout [ 7.474501] ldo2: supplied by axp-ipsout [ 7.489013] ldo3: supplied by axp-ipsout [ 7.505612] ldo4: supplied by axp-ipsout [ 7.508095] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001 [ 7.508103] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 7.508109] usb usb5: Product: Generic Platform OHCI controller [ 7.508115] usb usb5: Manufacturer: Linux 4.9.0-1-armmp ohci_hcd [ 7.508120] usb usb5: SerialNumber: 1c1c400.usb [ 7.509268] hub 5-0:1.0: USB hub found [ 7.509331] hub 5-0:1.0: 1 port detected [ 7.550229] dcdc2: supplied by axp-ipsout [ 7.557026] dcdc3: supplied by axp-ipsout [ 7.768042] systemd-journald[167]: Received request to flush runtime journal from PID 1 [ 8.073646] usb 5-1: new full-speed USB device number 2 using ohci-platform [ 8.316755] usb 5-1: New USB device found, idVendor=2341, idProduct=0042 [ 8.323681] usb 5-1: New USB device strings: Mfr=1, Product=2, SerialNumber=220 [ 8.331208] usb 5-1: Manufacturer: Arduino (www.arduino.cc) [ 8.336946] usb 5-1: SerialNumber: 75436343430351209141 [ 8.361066] RX IPC Checksum Offload disabled [ 8.361075] No MAC Management Counters available [ 8.361098] sun7i-dwmac 1c50000.ethernet eth0: fail to init PTP. [ 8.363603] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 8.410225] cdc_acm 5-1:1.0: ttyACM0: USB ACM device [ 8.417569] usbcore: registered new interface driver cdc_acm [ 8.423424] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters [ 9.398852] sun7i-dwmac 1c50000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off [ 9.407348] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 10.926774] Link is Up - 100/Full [ 14.220587] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. [ 82.739900] random: crng init done [ 1833.448005] mmc0: Card stuck in programming state! mmc_do_erase [ 1834.222949] sunxi-mmc 1c0f000.mmc: fatal err update clk timeout [ 1834.997931] sunxi-mmc 1c0f000.mmc: fatal err update clk timeout [ 1835.752904] sunxi-mmc 1c0f000.mmc: fatal err update clk timeout [ 1835.759936] mmc0: tried to reset card, got error -5 [ 1835.764880] blk_update_request: I/O error, dev mmcblk0, sector 4994752 [ 1835.771836] mmcblk0: error -5 sending status command, retrying [ 1835.777754] mmcblk0: error -5 sending status command, retrying [ 1835.783652] mmcblk0: error -5 sending status command, aborting [ 1836.557888] sunxi-mmc 1c0f000.mmc: fatal err update clk timeout [ 1837.332870] sunxi-mmc 1c0f000.mmc: fatal err update clk timeout [ 1838.087847] sunxi-mmc 1c0f000.mmc: fatal err update clk timeout [ 1838.094935] mmc0: tried to reset card, got error -5 [ 1838.099945] blk_update_request: I/O error, dev mmcblk0, sector 4994680 [ 1838.106584] blk_update_request: I/O error, dev mmcblk0, sector 4994688 [ 1838.113180] blk_update_request: I/O error, dev mmcblk0, sector 4994696 [ 1838.119751] blk_update_request: I/O error, dev mmcblk0, sector 4994704