U-Boot SPL 2016.07-rc3-armbian (Jul 06 2016 - 12:17:48) DRAM: u MiB Trying to boot from MMC1 U-Boot 2016.07-rc3-armbian (Jul 06 2016 - 12:17:48 +0200) Allwinner Technology CPU: Allwinner H3 (SUN8I 1680) Model: Xunlong Orange Pi PC I2C: ready DRAM: 1 GiB MMC: SUNXI SD/MMC: 0 *** Warning - bad CRC, using default environment In: serial Out: serial Err: serial Net: No ethernet found. starting USB... USB0: USB EHCI 1.00 USB1: USB OHCI 1.0 USB2: USB EHCI 1.00 USB3: USB OHCI 1.0 USB4: USB EHCI 1.00 USB5: USB OHCI 1.0 scanning bus 0 for devices... 1 USB Device(s) found scanning bus 2 for devices... 1 USB Device(s) found scanning bus 4 for devices... 2 USB Device(s) found Hit any key to stop autoboot: 2  1  0 => printenv arch=arm baudrate=115200 board=sunxi board_name=sunxi boot_a_script=load ${devtype} ${devnum}:${distro_bootpart} ${scriptaddr} ${prefix}${script}; source ${scriptaddr} boot_efi_binary=load ${devtype} ${devnum}:${distro_bootpart} ${kernel_addr_r} efi/boot/bootarm.efi; if fdt addr ${fdt_addr_r}; then bootefi ${kernel_addr_r} ${fdt_addr_r};else bootefi ${kernel_addr_r} ${fdtcontroladdr};fi boot_extlinux=sysboot ${devtype} ${devnum}:${distro_bootpart} any ${scriptaddr} ${prefix}extlinux/extlinux.conf boot_net_usb_start=usb start boot_prefixes=/ /boot/ boot_script_dhcp=boot.scr.uimg boot_scripts=boot.scr.uimg boot.scr boot_targets=fel mmc0 usb0 pxe dhcp bootcmd=run distro_bootcmd bootcmd_dhcp=run boot_net_usb_start; if dhcp ${scriptaddr} ${boot_script_dhcp}; then source ${scriptaddr}; fi;setenv efi_fdtfile ${fdtfile}; if test -z "${fdtfile}" -a -n "${soc}"; then setenv efi_fdtfile ${soc}-${board}${boardver}.dtb; fi; setenv efi_old_vci ${bootp_vci};setenv efi_old_arch ${bootp_arch};setenv bootp_vci PXEClient:Arch:00010:UNDI:003000;setenv bootp_arch 0xa;if dhcp ${kernel_addr_r}; then tftpboot ${fdt_addr_r} dtb/${efi_fdtfile};if fdt addr ${fdt_addr_r}; then bootefi ${kernel_addr_r} ${fdt_addr_r}; else bootefi ${kernel_addr_r} ${fdtcontroladdr};fi;fi;setenv bootp_vci ${efi_old_vci};setenv bootp_arch ${efi_old_arch};setenv efi_fdtfile;setenv efi_old_arch;setenv efi_old_vci; bootcmd_fel=if test -n ${fel_booted} && test -n ${fel_scriptaddr}; then echo '(FEL boot)'; source ${fel_scriptaddr}; fi bootcmd_mmc0=setenv devnum 0; run mmc_boot bootcmd_pxe=run boot_net_usb_start; dhcp; if pxe get; then pxe boot; fi bootcmd_usb0=setenv devnum 0; run usb_boot bootdelay=2 bootm_size=0xa000000 console=ttyS0,115200 cpu=armv7 dfu_alt_info_ram=kernel ram 0x42000000 0x1000000;fdt ram 0x43000000 0x100000;ramdisk ram 0x43300000 0x4000000 distro_bootcmd=for target in ${boot_targets}; do run bootcmd_${target}; done efi_dtb_prefixes=/ /dtb/ /dtb/current/ ethaddr=02:20:24:00:00:00 fdt_addr_r=0x43000000 fdtcontroladdr=7bf3f3a8 fdtfile=sun8i-h3-orangepi-pc.dtb kernel_addr_r=0x42000000 load_efi_dtb=load ${devtype} ${devnum}:${distro_bootpart} ${fdt_addr_r} ${prefix}${efi_fdtfile} mmc_boot=if mmc dev ${devnum}; then setenv devtype mmc; run scan_dev_for_boot_part; fi preboot=usb start pxefile_addr_r=0x43200000 ramdisk_addr_r=0x43300000 scan_dev_for_boot=echo Scanning ${devtype} ${devnum}:${distro_bootpart}...; for prefix in ${boot_prefixes}; do run scan_dev_for_extlinux; run scan_dev_for_scripts; done;run scan_dev_for_efi; scan_dev_for_boot_part=part list ${devtype} ${devnum} -bootable devplist; env exists devplist || setenv devplist 1; for distro_bootpart in ${devplist}; do if fstype ${devtype} ${devnum}:${distro_bootpart} bootfstype; then run scan_dev_for_boot; fi; done scan_dev_for_efi=setenv efi_fdtfile ${fdtfile}; if test -z "${fdtfile}" -a -n "${soc}"; then setenv efi_fdtfile ${soc}-${board}${boardver}.dtb; fi; for prefix in ${efi_dtb_prefixes}; do if test -e ${devtype} ${devnum}:${distro_bootpart} ${prefix}${efi_fdtfile}; then run load_efi_dtb; fi;done;if test -e ${devtype} ${devnum}:${distro_bootpart} efi/boot/bootarm.efi; then echo Found EFI removable media binary efi/boot/bootarm.efi; run boot_efi_binary; echo EFI LOAD FAILED: continuing...; fi; setenv efi_fdtfile scan_dev_for_extlinux=if test -e ${devtype} ${devnum}:${distro_bootpart} ${prefix}extlinux/extlinux.conf; then echo Found ${prefix}extlinux/extlinux.conf; run boot_extlinux; echo SCRIPT FAILED: continuing...; fi scan_dev_for_scripts=for script in ${boot_scripts}; do if test -e ${devtype} ${devnum}:${distro_bootpart} ${prefix}${script}; then echo Found U-Boot script ${prefix}${script}; run boot_a_script; echo SCRIPT FAILED: continuing...; fi; done scriptaddr=0x43100000 serial#=0200462024000000 soc=sunxi stderr=serial stdin=serial,usbkbd stdout=serial usb_boot=usb start; if usb dev ${devnum}; then setenv devtype usb; run scan_dev_for_boot_part; fi Environment size: 4045/131068 bytes => boot switch to partitions #0, OK mmc0 is current device Scanning mmc 0:1... Found U-Boot script /boot/boot.scr 2599 bytes read in 183 ms (13.7 KiB/s) ## Executing script at 43100000 gpio: pin PL10 (gpio 298) value is 1 gpio: pin PG11 (gpio 203) value is 1 0 bytes read in 131 ms (0 Bytes/s) 0 bytes read in 231 ms (0 Bytes/s) 19694 bytes read in 281 ms (68.4 KiB/s) 4611781 bytes read in 4825 ms (932.6 KiB/s) 3404408 bytes read in 3522 ms (943.4 KiB/s) Kernel image @ 0x42000000 [ 0x000000 - 0x33f278 ] ## Loading init Ramdisk from Legacy Image at 43300000 ... Image Name: uInitrd Image Type: ARM Linux RAMDisk Image (gzip compressed) Data Size: 4611717 Bytes = 4.4 MiB Load Address: 00000000 Entry Point: 00000000 Verifying Checksum ... OK ## Flattened Device Tree blob at 43000000 Booting using the fdt blob at 0x43000000 Loading Ramdisk to 49b9a000, end 49fffe85 ... OK Loading Device Tree to 49b92000, end 49b99ced ... OK Using machid 0x1029 from environment Starting kernel ... [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.6.2-sun8i (root@S350E7C) (gcc version 6.1.1 20160519 (Ubuntu 6.1.1-4ubuntu11) ) #19 SMP PREEMPT Wed Jul 6 12:18:15 CEST 2016 [ 0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=70c5387d [ 0.000000] CPU: div instructions available: patching division code [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] Machine model: Xunlong Orange Pi PC [ 0.000000] Forcing write-allocate cache policy for SMP [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] percpu: Embedded 12 pages/cpu @ef6b6000 s19084 r8192 d21876 u49152 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 260416 [ 0.000000] Kernel command line: console=ttyS0,115200 console=tty1 root=/dev/mmcblk0p1 rootwait rootfstype=ext4 cgroup_enable=memory swapaccount=1 sunxi_ve_mem_reserve=0 sunxi_g2d_mem_reserve=0 sunxi_no_mali_mem_reserve sunxi_fb_mem_reserve=16 panic=10 consoleblank=0 enforcing=0 loglevel=7 [ 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] allocated 1048576 bytes of page_ext [ 0.000000] Memory: 1023964K/1048576K available (4272K kernel code, 266K rwdata, 1804K rodata, 2048K init, 279K bss, 24612K reserved, 0K cma-reserved, 262144K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) [ 0.000000] vmalloc : 0xf0800000 - 0xff800000 ( 240 MB) [ 0.000000] lowmem : 0xc0000000 - 0xf0000000 ( 768 MB) [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [ 0.000000] modules : 0xbf800000 - 0xbfe00000 ( 6 MB) [ 0.000000] .text : 0xc0008000 - 0xc07ef1ac (8093 kB) [ 0.000000] .init : 0xc0800000 - 0xc0a00000 (2048 kB) [ 0.000000] .data : 0xc0a00000 - 0xc0a42808 ( 267 kB) [ 0.000000] .bss : 0xc0a44000 - 0xc0a89dcc ( 280 kB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 32. [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] Architected cp15 timer(s) running at 24.00MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns [ 0.000005] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns [ 0.000016] Switching to timer-based delay loop, resolution 41ns [ 0.000161] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns [ 0.000329] Console: colour dummy device 80x30 [ 0.001025] console [tty1] enabled [ 0.001063] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=24000) [ 0.001109] pid_max: default: 32768 minimum: 301 [ 0.001232] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.001262] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.001914] CPU: Testing write buffer coherency: ok [ 0.002237] /cpus/cpu@0 missing clock-frequency property [ 0.002270] /cpus/cpu@1 missing clock-frequency property [ 0.002298] /cpus/cpu@2 missing clock-frequency property [ 0.002326] /cpus/cpu@3 missing clock-frequency property [ 0.002353] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.002674] Setting up static identity map for 0x40200000 - 0x4020004c [ 8787.863074] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 7267.055401] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 [ 5257.195258] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 [ 0.054909] Brought up 4 CPUs [ 0.055003] SMP: Total of 4 processors activated (192.00 BogoMIPS). [ 0.055028] CPU: All CPU(s) started in SVC mode. [ 8787.894264] devtmpfs: initialized [ 8787.899365] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 [ 8787.899690] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 8787.899857] xor: measuring software checksum speed [ 8787.909377] arm4regs : 1264.000 MB/sec [ 8787.919420] 8regs : 856.000 MB/sec [ 8787.929457] 32regs : 856.000 MB/sec [ 8787.939501] neon : 1284.000 MB/sec [ 8787.939524] xor: using function: neon (1284.000 MB/sec) [ 8787.939562] prandom: seed boundary self test passed [ 8787.942579] prandom: 100 self tests passed [ 8787.942610] pinctrl core: initialized pinctrl subsystem [ 8787.943567] NET: Registered protocol family 16 [ 8787.943958] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 8787.949986] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 8787.950031] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 8787.973864] raid6: int32x1 gen() 199 MB/s [ 8787.990802] raid6: int32x1 xor() 173 MB/s [ 8788.008080] raid6: int32x2 gen() 257 MB/s [ 8788.024977] raid6: int32x2 xor() 207 MB/s [ 8788.042202] raid6: int32x4 gen() 289 MB/s [ 8788.059197] raid6: int32x4 xor() 199 MB/s [ 8788.076434] raid6: int32x8 gen() 269 MB/s [ 8788.093428] raid6: int32x8 xor() 183 MB/s [ 8788.110460] raid6: neonx1 gen() 480 MB/s [ 8788.127556] raid6: neonx1 xor() 363 MB/s [ 8788.144657] raid6: neonx2 gen() 640 MB/s [ 8788.161734] raid6: neonx2 xor() 466 MB/s [ 8788.178866] raid6: neonx4 gen() 710 MB/s [ 8788.195937] raid6: neonx4 xor() 496 MB/s [ 8788.213026] raid6: neonx8 gen() 679 MB/s [ 8788.230118] raid6: neonx8 xor() 476 MB/s [ 8788.230140] raid6: using algorithm neonx4 gen() 710 MB/s [ 8788.230163] raid6: .... xor() 496 MB/s, rmw enabled [ 8788.230185] raid6: using intx1 recovery algorithm [ 8788.231408] SCSI subsystem initialized [ 8788.231872] usbcore: registered new interface driver usbfs [ 8788.231948] usbcore: registered new interface driver hub [ 8788.232059] usbcore: registered new device driver usb [ 8788.232193] pps_core: LinuxPPS API ver. 1 registered [ 8788.232217] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 8788.232277] PTP clock support registered [ 7267.410625] clocksource: Switched to clocksource arch_sys_counter [ 7267.410877] VFS: Disk quotas dquot_6.6.0 [ 7267.410950] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 7267.419463] NET: Registered protocol family 2 [ 7267.420146] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 7267.420250] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 7267.420383] TCP: Hash tables configured (established 8192 bind 8192) [ 7267.420463] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 7267.420536] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 7267.420762] NET: Registered protocol family 1 [ 7267.421004] RPC: Registered named UNIX socket transport module. [ 7267.421032] RPC: Registered udp transport module. [ 7267.421054] RPC: Registered tcp transport module. [ 7267.421076] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 7267.421293] Unpacking initramfs... [ 0.674233] Freeing initrd memory: 4504K (c9b9a000 - ca000000) [ 0.674495] kvm [1]: HYP mode not available [ 0.675775] futex hash table entries: 1024 (order: 4, 65536 bytes) [ 0.676217] Initialise system trusted keyring [ 0.676610] workingset: timestamp_bits=12 max_order=18 bucket_order=6 [ 0.687717] NFS: Registering the id_resolver key type [ 0.687778] Key type id_resolver registered [ 0.687802] Key type id_legacy registered [ 0.689728] bounce: pool size: 64 pages [ 0.690003] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 0.690051] io scheduler noop registered [ 0.690078] io scheduler deadline registered [ 0.690127] io scheduler cfq registered (default) [ 0.692360] crc32: CRC_LE_BITS = 64, CRC_BE BITS = 64 [ 0.692387] crc32: self tests passed, processed 225944 bytes in 1059125 nsec [ 0.693543] crc32c: CRC_LE_BITS = 64 [ 0.693565] crc32c: self tests passed, processed 225944 bytes in 558291 nsec [ 0.760359] crc32_combine: 8373 self tests passed [ 0.832172] crc32c_combine: 8373 self tests passed [ 0.832235] glob: 64 self-tests passed, 0 failed [ 0.833156] gpiod_to_irq: invalid GPIO [ 0.833182] gpiod_to_irq: invalid GPIO [ 0.835058] sun8i-h3-pinctrl 1c20800.pinctrl: initialized sunXi PIO driver [ 0.835778] sun8i-h3-r-pinctrl 1f02c00.pinctrl: initialized sunXi PIO driver [ 0.889189] Serial: 8250/16550 driver, 8 ports, IRQ sharing disabled [ 0.891539] console [ttyS0] disabled [ 0.911700] 1c28000.serial: ttyS0 at MMIO 0x1c28000 (irq = 34, base_baud = 1500000) is a U6_16550A [ 1.704594] console [ttyS0] enabled [ 1.730863] 1c28400.serial: ttyS1 at MMIO 0x1c28400 (irq = 35, base_baud = 1500000) is a U6_16550A [ 7268.778243] 1c28800.serial: ttyS2 at MMIO 0x1c28800 (irq = 36, base_baud = 1500000) is a U6_16550A [ 7268.809902] 1c28c00.serial: ttyS3 at MMIO 0x1c28c00 (irq = 37, base_baud = 1500000) is a U6_16550A [ 7268.820442] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 7268.826996] ehci-platform: EHCI generic platform driver [ 7268.832393] ehci-platform 1c1b000.usb: EHCI Host Controller [ 7268.837998] ehci-platform 1c1b000.usb: new USB bus registered, assigned bus number 1 [ 7268.845883] ehci-platform 1c1b000.usb: irq 23, io mem 0x01c1b000 [ 417.097066] ehci-platform 1c1b000.usb: USB 2.0 started, EHCI 1.00 [ 417.103922] hub 1-0:1.0: USB hub found [ 417.107750] hub 1-0:1.0: 1 port detected [ 417.112197] ehci-platform 1c1c000.usb: EHCI Host Controller [ 417.117823] ehci-platform 1c1c000.usb: new USB bus registered, assigned bus number 2 [ 417.125715] ehci-platform 1c1c000.usb: irq 25, io mem 0x01c1c000 [ 417.136991] ehci-platform 1c1c000.usb: USB 2.0 started, EHCI 1.00 [ 417.143760] hub 2-0:1.0: USB hub found [ 417.147565] hub 2-0:1.0: 1 port detected [ 417.151923] ehci-platform 1c1d000.usb: EHCI Host Controller [ 417.157540] ehci-platform 1c1d000.usb: new USB bus registered, assigned bus number 3 [ 417.165414] ehci-platform 1c1d000.usb: irq 27, io mem 0x01c1d000 [ 417.176990] ehci-platform 1c1d000.usb: USB 2.0 started, EHCI 1.00 [ 417.183731] hub 3-0:1.0: USB hub found [ 417.187536] hub 3-0:1.0: 1 port detected [ 417.191906] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 417.198128] ohci-platform: OHCI generic platform driver [ 417.203530] ohci-platform 1c1b400.usb: Generic Platform OHCI controller [ 417.210192] ohci-platform 1c1b400.usb: new USB bus registered, assigned bus number 4 [ 417.218057] ohci-platform 1c1b400.usb: irq 24, io mem 0x01c1b400 [ 417.279579] hub 4-0:1.0: USB hub found [ 417.283383] hub 4-0:1.0: 1 port detected [ 417.287738] ohci-platform 1c1c400.usb: Generic Platform OHCI controller [ 417.294403] ohci-platform 1c1c400.usb: new USB bus registered, assigned bus number 5 [ 417.302266] ohci-platform 1c1c400.usb: irq 26, io mem 0x01c1c400 [ 417.363573] hub 5-0:1.0: USB hub found [ 417.367373] hub 5-0:1.0: 1 port detected [ 417.371716] ohci-platform 1c1d400.usb: Generic Platform OHCI controller [ 417.378375] ohci-platform 1c1d400.usb: new USB bus registered, assigned bus number 6 [ 417.386231] ohci-platform 1c1d400.usb: irq 28, io mem 0x01c1d400 [ 417.447581] hub 6-0:1.0: USB hub found [ 417.451382] hub 6-0:1.0: 1 port detected [ 417.456428] sun6i-rtc 1f00000.rtc: rtc core: registered rtc-sun6i as rtc0 [ 417.463258] sun6i-rtc 1f00000.rtc: RTC enabled [ 417.467772] i2c /dev entries driver [ 7684.489250] sunxi-wdt 1c20ca0.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0) [ 7684.497861] sunxi-mmc 1c0f000.mmc: Got CD GPIO [ 7684.523649] sunxi-mmc 1c0f000.mmc: base:0xf08c4000 irq:22 [ 7684.530452] usbcore: registered new interface driver usbhid [ 7684.536062] usbhid: USB HID core driver [ 417.521995] usb 3-1: new high-speed USB device number 2 using ehci-platform [ 7684.548397] NET: Registered protocol family 17 [ 7684.552996] Key type dns_resolver registered [ 7684.557754] ThumbEE CPU extension supported. [ 7684.562075] Registering SWP/SWPB emulation handler [ 409.297939] Loading compiled-in X.509 certificates [ 409.305090] Btrfs loaded, integrity-checker=on [ 409.309620] BTRFS: selftest: Running btrfs free space cache tests [ 409.315751] BTRFS: selftest: Running extent only tests [ 409.320931] BTRFS: selftest: Running bitmap only tests [ 7684.592545] mmc0: host does not support reading read-only switch, assuming write-enable [ 7684.594923] mmc0: new high speed SDHC card at address b368 [ 7684.595344] mmcblk0: mmc0:b368 USD 3.76 GiB [ 7684.597039] mmcblk0: p1 [ 409.346660] BTRFS: selftest: Running bitmap and extent tests [ 409.352382] BTRFS: selftest: Running space stealing from bitmap to extent [ 409.360275] BTRFS: selftest: Free space cache tests finished [ 409.365952] BTRFS: selftest: Running extent buffer operation tests [ 409.371963] BTRFS: selftest: Running btrfs_split_item tests [ 409.377796] BTRFS: selftest: Running extent I/O tests [ 409.382866] BTRFS: selftest: Running find delalloc tests [ 409.698108] BTRFS: selftest: Running extent buffer bitmap tests [ 409.750501] BTRFS: selftest: Extent I/O tests finished [ 409.755662] BTRFS: selftest: Running btrfs_get_extent tests [ 409.761606] BTRFS: selftest: Running hole first btrfs_get_extent test [ 409.768119] BTRFS: selftest: Running outstanding_extents tests [ 409.774047] BTRFS: selftest: Running qgroup tests [ 409.778773] BTRFS: selftest: Qgroup basic add [ 409.783242] BTRFS: selftest: Qgroup multiple refs test [ 409.788544] BTRFS: selftest: Running free space tree tests [ 7685.189561] INFO: rcu_preempt self-detected stall on CPU[ 409.920543] INFO: rcu_preempt detected stalls on CPUs/tasks: [ 409.920554] 0-...: (1 GPs behind) idle=40d/1/0 softirq=312/313 fqs=1 [ 409.920561] 2-...: (1 GPs behind) idle=7c5/140000000000001/0 softirq=141/142 fqs=1 [ 409.920567] 3-...: (1 ticks this GP) idle=28b/1/0 softirq=266/266 fqs=1 [ 409.920568] (detected by 1, t=734440 jiffies, g=-171, c=-172, q=1) [ 409.920576] Task dump for CPU 0: [ 409.920579] swapper/0 R running 0 0 0 0x00000000 [ 409.920609] [] (__schedule) from [] (0xc0a01fa0) [ 409.920612] Task dump for CPU 2: [ 409.920613] swapper/0 R running 0 1 0 0x00000000 [ 409.920626] [] (__schedule) from [] (0xef04de20) [ 409.920628] Task dump for CPU 3: [ 409.920630] swapper/3 R running 0 0 1 0x00000000 [ 409.920642] [] (__schedule) from [] (0xef099fe8) [ 7685.275669] [ 7685.277369] 2-...: (1 GPs behind) idle=7c5/140000000000001/0 softirq=141/142 fqs=2 [ 7685.285126] (t=734440 jiffies g=-171 c=-172 q=1) [ 7685.289940] Task dump for CPU 0: [ 7685.293176] swapper/0 R running 0 0 0 0x00000002 [ 7685.299570] [] (__schedule) from [] (0xc0a01fa0) [ 7685.305931] Task dump for CPU 2: [ 7685.309167] swapper/0 R running 0 1 0 0x00000002 [ 7685.315568] [] (unwind_backtrace) from [] (show_stack+0xb/0xc) [ 7685.323165] [] (show_stack) from [] (rcu_dump_cpu_stacks+0x57/0x80) [ 7685.331195] [] (rcu_dump_cpu_stacks) from [] (rcu_check_callbacks+0x3c1/0x6b8) [ 7685.340179] [] (rcu_check_callbacks) from [] (update_process_times+0x2b/0x48) [ 7685.349078] [] (update_process_times) from [] (tick_sched_timer+0x4d/0x194) [ 7685.357800] [] (tick_sched_timer) from [] (__hrtimer_run_queues+0xbd/0x124) [ 7685.366521] [] (__hrtimer_run_queues) from [] (hrtimer_interrupt+0x73/0x160) [ 7685.375332] [] (hrtimer_interrupt) from [] (arch_timer_handler_virt+0x1f/0x24) [ 7685.384318] [] (arch_timer_handler_virt) from [] (handle_percpu_devid_irq+0x43/0x54) [ 7685.393821] [] (handle_percpu_devid_irq) from [] (generic_handle_irq+0x17/0x20) [ 7685.402890] [] (generic_handle_irq) from [] (__handle_domain_irq+0x3f/0x80) [ 7685.411612] [] (__handle_domain_irq) from [] (gic_handle_irq+0x2f/0x60) [ 7685.419987] [] (gic_handle_irq) from [] (__irq_svc+0x3b/0x80) [ 7685.427487] Exception stack(0xef04ddd0 to 0xef04de18) [ 7685.432549] ddc0: ef04de30 000cbc2a c0a698c8 c0a69800 [ 7685.440748] dde0: c0a69800 c0a10f7c ffffffea c0a69848 20000113 000000a5 00000000 00000000 [ 7685.448943] de00: 00000004 ef04de20 c02fc9f9 c02fca00 40000133 ffffffff [ 7685.455573] [] (__irq_svc) from [] (timerfd_clock_was_set+0x20/0xa8) [ 7685.463688] [] (timerfd_clock_was_set) from [] (do_settimeofday64+0xe3/0x170) [ 7685.472584] [] (do_settimeofday64) from [] (rtc_hctosys+0x71/0xbc) [ 7685.480524] [] (rtc_hctosys) from [] (do_one_initcall+0x8f/0x17c) [ 7685.488378] [] (do_one_initcall) from [] (kernel_init_freeable+0x179/0x1e0) [ 7685.497100] [] (kernel_init_freeable) from [] (kernel_init+0x7/0xd4) [ 7685.505216] [] (kernel_init) from [] (ret_from_fork+0x11/0x30) [ 7685.529058] sun6i-rtc 1f00000.rtc: setting system clock to 1970-01-01 00:07:06 UTC (426) [ 7685.540243] vcc3v0: disabling [ 7685.543255] vcc5v0: disabling [ 419.040724] INFO: rcu_preempt self-detected stall on CPU[ 410.786540] INFO: rcu_preempt detected stalls on CPUs/tasks: [ 410.786549] 0-...: (1 GPs behind) idle=40d/1/0 softirq=312/313 fqs=2 [ 410.786550] (detected by 1, t=635978150 jiffies, g=-171, c=-172, q=2) [ 410.786558] Task dump for CPU 0: [ 410.786560] swapper/0 R running 0 0 0 0x00000002 [ 410.786579] [] (__schedule) from [] (0xc0a01fa0) [ 410.786588] rcu_preempt kthread starved for 635243710 jiffies! g4294967125 c4294967124 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x1 [ 410.786591] rcu_preempt S c05f25bd 0 7 2 0x00000000 [ 410.786605] [] (__schedule) from [] (schedule+0x31/0x78) [ 410.786616] [] (schedule) from [] (schedule_timeout+0xbb/0x100) [ 410.786630] [] (schedule_timeout) from [] (rcu_gp_kthread+0x439/0x664) [ 410.786641] [] (rcu_gp_kthread) from [] (kthread+0x99/0xac) [ 410.786653] [] (kthread) from [] (ret_from_fork+0x11/0x30) [ 419.135584] [ 419.137283] 0-...: (1 GPs behind) idle=40d/1/0 softirq=312/313 fqs=2 [ 419.143817] (t=635978150 jiffies g=-171 c=-172 q=2) [ 419.148895] rcu_preempt kthread starved for 635243710 jiffies! g4294967125 c4294967124 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x0 [ 419.159951] rcu_preempt R running 0 7 2 0x00000000 [ 419.166350] [] (__schedule) from [] (schedule+0x31/0x78) [ 419.173414] [] (schedule) from [] (schedule_timeout+0xbb/0x100) [ 419.181095] [] (schedule_timeout) from [] (rcu_gp_kthread+0x439/0x664) [ 419.189383] [] (rcu_gp_kthread) from [] (kthread+0x99/0xac) [ 419.196715] [] (kthread) from [] (ret_from_fork+0x11/0x30) [ 419.203954] Task dump for CPU 0: [ 419.207189] swapper/0 R running 0 0 0 0x00000002 [ 419.213585] [] (unwind_backtrace) from [] (show_stack+0xb/0xc) [ 419.221180] [] (show_stack) from [] (rcu_dump_cpu_stacks+0x57/0x80) [ 419.229207] [] (rcu_dump_cpu_stacks) from [] (rcu_check_callbacks+0x3c1/0x6b8) [ 419.238191] [] (rcu_check_callbacks) from [] (update_process_times+0x2b/0x48) [ 419.247088] [] (update_process_times) from [] (tick_sched_timer+0x4d/0x194) [ 419.255811] [] (tick_sched_timer) from [] (__hrtimer_run_queues+0xbd/0x124) [ 419.264533] [] (__hrtimer_run_queues) from [] (hrtimer_interrupt+0x73/0x160) [ 419.273344] [] (hrtimer_interrupt) from [] (arch_timer_handler_virt+0x1f/0x24) [ 419.282329] [] (arch_timer_handler_virt) from [] (handle_percpu_devid_irq+0x43/0x54) [ 419.291833] [] (handle_percpu_devid_irq) from [] (generic_handle_irq+0x17/0x20) [ 419.300902] [] (generic_handle_irq) from [] (__handle_domain_irq+0x3f/0x80) [ 419.309622] [] (__handle_domain_irq) from [] (gic_handle_irq+0x2f/0x60) [ 419.317997] [] (gic_handle_irq) from [] (__irq_svc+0x3b/0x80) [ 419.325496] Exception stack(0xc0a01f50 to 0xc0a01f98) [ 419.330559] 1f40: ef6b63f0 00000000 0019740a c021f761 [ 419.338756] 1f60: c0a00000 c0a025bc c0a0256c c0a3b417 00000000 c0a024c0 c0a44000 c0828a28 [ 419.346952] 1f80: c0a0003c c0a01fa0 c0214a95 c0214a96 40000033 ffffffff [ 419.353580] [] (__irq_svc) from [] (arch_cpu_idle+0x22/0x24) [ 419.361001] [] (arch_cpu_idle) from [] (cpu_startup_entry+0xbb/0xec) [ 419.369118] [] (cpu_startup_entry) from [] (start_kernel+0x315/0x320) [ 419.377316] [] (start_kernel) from [<40008095>] (0x40008095) [ 7699.988971] Freeing unused kernel memory: 2048K (c0800000 - c0a00000) [ 424.762234] systemd-udevd[85]: starting version 215 [ 424.768880] random: systemd-udevd urandom read with 2 bits of entropy available [ 560.223692] random: nonblocking pool is initialized [ 639.082424] sy8106a 0-0065: SY8106A voltage at boot: 1200 mV [ 639.144457] usb-storage 3-1:1.0: USB Mass Storage device detected [ 630.900901] scsi host0: usb-storage 3-1:1.0 [ 630.908811] usbcore: registered new interface driver usb-storage [ 630.921584] usbcore: registered new interface driver uas [ 1073.739882] scsi 0:0:0:0: Direct-Access X-FLASH Flash Disk 2.00 PQ: 0 ANSI: 2 [ 1073.757822] sd 0:0:0:0: [sda] 993279 512-byte logical blocks: (509 MB/485 MiB) [ 1073.767395] sd 0:0:0:0: [sda] Write Protect is off [ 1073.774117] sd 0:0:0:0: [sda] No Caching mode page found [ 1073.779791] sd 0:0:0:0: [sda] Assuming drive cache: write through [ 1073.804457] sda: sda1 [ 1073.817027] sd 0:0:0:0: [sda] Attached SCSI removable disk