[FS#749] EdgeRouter Lite (octeon) f2fs WARNING in segment.c

LEDE Bugs lede-bugs at lists.infradead.org
Mon May 1 10:42:18 PDT 2017


A new Flyspray task has been opened.  Details are below. 

User who did this - netprince (netprince) 

Attached to Project - LEDE Project
Summary - EdgeRouter Lite (octeon) f2fs WARNING in segment.c
Task Type - Bug Report
Category - Base system
Status - Unconfirmed
Assigned To - 
Operating System - All
Severity - Medium
Priority - Very Low
Reported Version - lede-17.01
Due in Version - Undecided
Due Date - Undecided
Details - Using a firmware built on my own, as well as downloaded from 

https://downloads.lede-project.org/releases/17.01.1/targets/octeon/generic/

Freshly installed, there is a kernel WARNING (is that the right term?)


U-Boot 1.1.1 (UBNT Build ID: 4670715-gbd7e2d7) (Build time: May 27 2014 - 11:16:22)

BIST check passed.
UBNT_E100 r1:2, r2:18, f:4/71, serial #: 44D9E79F90C9
MPR 13-00318-18
Core clock: 500 MHz, DDR clock: 266 MHz (532 Mhz data rate)
DRAM:  512 MB
Clearing DRAM....... done
Flash:  4 MB
Net:   octeth0, octeth1, octeth2

USB:   (port 0) scanning bus for devices... 1 USB Devices found
       scanning bus for storage devices...
  Device 0: Vendor:          Prod.: USB DISK 2.0     Rev: PMAP
            Type: Removable Hard Disk
            Capacity: 3824.0 MB = 3.7 GB (7831552 x 512)                                                                                                       0
reading vmlinux.64
............................

5555424 bytes read
argv[2]: coremask=0x3
argv[3]: root=/dev/sda2
argv[4]: rootdelay=15
argv[5]: rw
argv[6]: rootsqimg=squashfs.img
argv[7]: rootsqwdir=w
argv[8]: mtdparts=phys_mapped_flash:512k(boot0),512k(boot1),64k at 1024k(eeprom)
ELF file is 64 bit
Allocating memory for ELF segment: addr: 0xffffffff81100000 (adjusted to: 0x1100000), size 0x16a3fa0
Allocated memory for ELF segment: addr: 0xffffffff81100000, size 0x16a3fa0
Processing PHDR 0
  Loading 54af00 bytes at ffffffff81100000
  Clearing 11590a0 bytes at ffffffff8164af00
## Loading Linux kernel with entry point: 0xffffffff811078a0 ...
Bootloader: Done loading app on coremask: 0x3
[    0.000000] Linux version 4.4.61 (buildbot at builds-02.infra.lede-project.org) (gcc version 5.4.0 (LEDE GCC 5.4.0 r3103-1b51a49) ) #0 SMP Sat Apr 15 16:13:45 2017
[    0.000000] CVMSEG size: 2 cache lines (256 bytes)
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 000d0601 (Cavium Octeon+)
[    0.000000] Checking for the multiply/shift bug... no.
[    0.000000] Checking for the daddiu bug... no.
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 0000000005800000 @ 0000000002800000 (usable)
[    0.000000]  memory: 0000000007c00000 @ 0000000008200000 (usable)
[    0.000000]  memory: 000000000fc00000 @ 0000000410000000 (usable)
[    0.000000]  memory: 00000000016a3fa0 @ 0000000001100000 (usable)
[    0.000000] Wasting 243712 bytes for tracking 4352 unused pages
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Using internal Device Tree.
[    0.000000] software IO TLB [mem 0x0378e000-0x0778e000] (64MB) mapped at [800000000378e000-800000000778dfff]
[    0.000000] Zone ranges:
[    0.000000]   DMA32    [mem 0x0000000001100000-0x00000000efffffff]
[    0.000000]   Normal   [mem 0x00000000f0000000-0x000000041fbfffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000001100000-0x00000000027a2fff]
[    0.000000]   node   0: [mem 0x0000000002800000-0x0000000007ffffff]
[    0.000000]   node   0: [mem 0x0000000008200000-0x000000000fdfffff]
[    0.000000]   node   0: [mem 0x0000000410000000-0x000000041fbfffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000001100000-0x000000041fbfffff]
[    0.000000] Primary instruction cache 32kB, virtually tagged, 4 way, 64 sets, linesize 128 bytes.
[    0.000000] Primary data cache 16kB, 64-way, 2 sets, linesize 128 bytes.
[    0.000000] PERCPU: Embedded 14 pages/cpu @8000000007849000 s20224 r8192 d28928 u57344
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 122875
[    0.000000] Kernel command line: mtdparts=phys_mapped_flash:512k(boot0)ro,512k(boot1)ro,64k(eeprom)ro root=/dev/sda2 rootfstype=squashfs,ext4 rootwait  console=ttyS0,115200
[    0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.000000] Memory: 400736K/498316K available (4199K kernel code, 304K rwdata, 652K rodata, 1284K init, 16719K bss, 97580K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=128, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  CONFIG_RCU_FANOUT set to non-default value of 32
[    0.000000]  RCU restricting CPUs from NR_CPUS=16 to nr_cpu_ids=2.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.000000] NR_IRQS:127
[    7.147901] clocksource: OCTEON_CVMCOUNT: mask: 0xffffffffffffffff max_cycles: 0xe6a171a037, max_idle_ns: 881590485102 ns
[    7.158745] Calibrating delay loop (skipped) preset value.. 1000.00 BogoMIPS (lpj=2000000)
[    7.166921] pid_max: default: 32768 minimum: 301
[    7.171667] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    7.178141] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    7.185929] Checking for the daddi bug... no.
[    7.192506] SMP: Booting CPU01 (CoreId  1)...
[    7.196888] CPU1 revision is: 000d0601 (Cavium Octeon+)
[    7.197086] Brought up 2 CPUs
[    7.210207] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    7.219889] futex hash table entries: 512 (order: 4, 65536 bytes)
[    7.226749] NET: Registered protocol family 16
[    7.231886] Can't analyze schedule() prologue at ffffffff81109a08
[    7.249961] Not in host mode, PCI Controller not initialized
[    7.273539] SCSI subsystem initialized
[    7.277735] usbcore: registered new interface driver usbfs
[    7.283246] usbcore: registered new interface driver hub
[    7.288576] usbcore: registered new device driver usb
[    7.295690] clocksource: Switched to clocksource OCTEON_CVMCOUNT
[    7.303021] NET: Registered protocol family 2
[    7.308164] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[    7.315205] TCP bind hash table entries: 4096 (order: 5, 131072 bytes)
[    7.321776] TCP: Hash tables configured (established 4096 bind 4096)
[    7.328094] UDP hash table entries: 256 (order: 2, 24576 bytes)
[    7.333965] UDP-Lite hash table entries: 256 (order: 2, 24576 bytes)
[    7.340528] NET: Registered protocol family 1
[    7.351823] Crashlog allocated RAM at address 0x3f00000
[    7.372836] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    7.379094] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    7.393829] io scheduler noop registered
[    7.397778] io scheduler deadline registered (default)
[    7.403150] octeon_gpio 1070000000800.gpio-controller: OCTEON GPIO driver probed.
[    7.410882] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    7.421840] console [ttyS0] disabled
[    7.425354] 1180000000800.serial: ttyS0 at MMIO 0x1180000000800 (irq = 41, base_baud = 31250000) is a OCTEON
[    7.435125] console [ttyS0] enabled
[    7.435125] console [ttyS0] enabled
[    7.442028] bootconsole [early0] disabled
[    7.442028] bootconsole [early0] disabled
[    7.450602] 1180000000c00.serial: ttyS1 at MMIO 0x1180000000c00 (irq = 42, base_baud = 31250000) is a OCTEON
[    7.461208] octeon_rng octeon_rng: Octeon Random Number Generator
[    7.476791] loop: module loaded
[    7.480859] of-flash 1f400000.nor: Can't get bank width from device tree
[    7.488130] libphy: mdio-octeon: probed
[    7.493863] mdio-octeon 1180000001800.mdio: Version 1.1
[    7.499514] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    7.506095] ehci-pci: EHCI PCI platform driver
[    7.510694] ehci-platform: EHCI generic platform driver
[    7.516232] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    7.522497] ohci-platform: OHCI generic platform driver
[    7.528113] usbcore: registered new interface driver usb-storage
[    7.534178] octeon_wdt: Initial granularity 5 Sec
[    7.542862] Interface 0 has 3 ports (RGMII)
[    7.551658] OcteonUSB 16f0010000000.usbc: Octeon Host Controller
[    7.557762] OcteonUSB 16f0010000000.usbc: new USB bus registered, assigned bus number 1
[    7.565863] OcteonUSB 16f0010000000.usbc: irq 124, io mem 0x00000000
[    7.573317] hub 1-0:1.0: USB hub found
[    7.577215] hub 1-0:1.0: 1 port detected
[    7.581735] OcteonUSB 16f0010000000.usbc: Registered HCD for port 0 on irq 124
[    7.590542] NET: Registered protocol family 10
[    7.597130] NET: Registered protocol family 17
[    7.601749] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[    7.614421] 8021q: 802.1Q VLAN Support v1.8
[    7.618987] Bootbus flash: Setting flash for 4MB flash at 0x1f800000
[    7.625502] phys_mapped_flash: Found 1 x16 devices at 0x0 in 8-bit bank. Manufacturer ID 0x000001 Chip ID 0x001a01
[    7.635895] Amd/Fujitsu Extended Query Table at 0x0040
[    7.641088]   Amd/Fujitsu Extended Query version 1.3.
[    7.646167] phys_mapped_flash: Swapping erase regions for top-boot CFI table.
[    7.653333] number of CFI chips: 1
[    7.656784] 3 cmdlinepart partitions found on MTD device phys_mapped_flash
[    7.663694] Creating 3 MTD partitions on "phys_mapped_flash":
[    7.669470] 0x000000000000-0x000000080000 : "boot0"
[    7.675929] 0x000000080000-0x000000100000 : "boot1"
[    7.682384] 0x000000100000-0x000000110000 : "eeprom"
[    7.690420] fdt: not creating '/sys/firmware/fdt': CRC check failed
[    7.697332] Waiting for root device /dev/sda2...
[    7.938987] usb 1-1: new high-speed USB device number 2 using OcteonUSB
[    8.379119] usb-storage 1-1:1.0: USB Mass Storage device detected
[    8.385761] scsi host0: usb-storage 1-1:1.0
[    9.388366] scsi 0:0:0:0: Direct-Access              USB DISK 2.0     PMAP PQ: 0 ANSI: 6
[    9.398568] sd 0:0:0:0: [sda] 7831552 512-byte logical blocks: (4.01 GB/3.73 GiB)
[    9.407156] sd 0:0:0:0: [sda] Write Protect is off
[    9.412976] sd 0:0:0:0: [sda] No Caching mode page found
[    9.418386] sd 0:0:0:0: [sda] Assuming drive cache: write through
[    9.429634]  sda: sda1 sda2
[    9.436532] sd 0:0:0:0: [sda] Attached SCSI removable disk
[    9.513812] VFS: Mounted root (squashfs filesystem) readonly on device 8:2.
[    9.522021] Freeing unused kernel memory: 1284K (ffffffff8160f000 - ffffffff81750000)
[   10.250157] init: Console is alive
[   10.253922] init: - watchdog -
[   10.571309] init: - preinit -
cat: can't open '/tmp/sysinfo/board_name': No such file or directory
[   10.943161] random: jshn: uninitialized urandom read (4 bytes read, 89 bits of entropy available)
[   10.991547] random: jshn: uninitialized urandom read (4 bytes read, 90 bits of entropy available)
[   11.029533] random: jshn: uninitialized urandom read (4 bytes read, 91 bits of entropy available)
[   11.065314] eth0: 1000 Mbps Full duplex, port 0, queue 0
Press the [f] key and hit [enter] to enter failsafe mode
Press the [1], [2], [3] or [4] key and hit [enter] to select the debug level
[   14.379000] random: nonblocking pool is initialized
[   14.436744] mount_root: rootdisk overlay filesystem has not been formatted yet

        F2FS-tools: mkfs.f2fs Ver: 1.8.0 (2017-02-03)

Info: Debug level = 0
Info: Label = rootfs_data
Info: Trim is enabled
Info: Segments per section = 1
Info: Sections per zone = 1
Info: sector size = 512
Info: total sectors = 3414144 (1667 MB)
Info: zone aligned segment0 blkaddr: 512
Info: format version with
  "Linux version 4.4.61 (buildbot at builds-02.infra.lede-project.org) (gcc version 5.4.0 (LEDE GCC 5.4.0 r3103-1b51a49) ) #0 SMP Sat Apr 15 16:13:45 2017"
Info: [/dev/loop0] Discarding device
Info: This device doesn't support BLKSECDISCARD
Info: This device doesn't support BLKDISCARD
Info: Overprovision ratio = 4.960%
Info: Overprovision segments = 86 (GC reserved = 48)
Info: format successful
[   16.695198] mount_root: overlay filesystem has not been fully initialized yet
[   16.703760] mount_root: switching to f2fs overlay
[   16.753210] urandom-seed: Seed file not found (/etc/urandom.seed)
[   16.905217] eth0: Link down
[   16.917557] procd: - early -
[   16.920639] procd: - watchdog -
[   17.543389] procd: - ubus -
[   17.678625] procd: - init -
Please press Enter to activate this console.
[   17.928815] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   17.943136] ip_tables: (C) 2000-2006 Netfilter Core Team
[   17.954403] nf_conntrack version 0.5.0 (3140 buckets, 12560 max)
[   18.023028] xt_time: kernel timezone is -0000
[   18.036364] PPP generic driver version 2.4.2
[   18.041948] NET: Registered protocol family 24
[   19.332518] ------------[ cut here ]------------
[   19.337193] WARNING: CPU: 1 PID: 626 at fs/f2fs/segment.c:718 0xffffffff812cfd90()
[   19.344780] Modules linked in: pppoe ppp_async iptable_nat pppox ppp_generic nf_nat_ipv4 nf_conntrack_ipv6 nf_conntrack_ipv4 ipt_REJECT ipt_MASQUERADE xt_time xt_tcpudp xt_state xt_nat xt_multiport xt_mark xt_mac xt_limit xt_conntrack xt_comment xt_TCPMSS xt_REDIRECT xt_LOG xt_CT slhc nf_reject_ipv4 nf_nat_redirect nf_nat_masquerade_ipv4 nf_nat nf_log_ipv4 nf_defrag_ipv6 nf_defrag_ipv4 nf_conntrack_rtcache nf_conntrack iptable_mangle iptable_filter ip_tables crc_ccitt ip6t_REJECT nf_reject_ipv6 nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables x_tables
[   19.396207] CPU: 1 PID: 626 Comm: uci Not tainted 4.4.61 #0
[   19.401780] Stack : 0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 800000041e107220 0000000000000000 ffffffff81125fc0
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 ffffffff81125fc0 0000000000000000 0000000000000000
          ...
[   19.467419] Call Trace:[] 0xffffffff81125fc0
[   19.473278] [] 0xffffffff81125fc0
[   19.478161] [] 0xffffffff813103dc
[   19.483043] [] 0xffffffff8113baac
[   19.487925] [] 0xffffffff812cfd90
[   19.492807] [] 0xffffffff812d0690
[   19.497688] [] 0xffffffff812d2114
[   19.502571] [] 0xffffffff812d24d0
[   19.507453] [] 0xffffffff812d261c
[   19.512335] [] 0xffffffff812c844c
[   19.517217] [] 0xffffffff812c88d4
[   19.522100] [] 0xffffffff812c5358
[   19.526981] [] 0xffffffff812c56a0
[   19.531866] [] 0xffffffff8110c740
[   19.536747] [] 0xffffffff811adcb4
[   19.541630] [] 0xffffffff812c59a0
[   19.546512] [] 0xffffffff811a26b0
[   19.551394] [] 0xffffffff812c25f8
[   19.556276] [] 0xffffffff812c2738
[   19.561157] [] 0xffffffff811ad424
[   19.566039] [] 0xffffffff8116fa64
[   19.570923] [] 0xffffffff812bcc14
[   19.575805] [] 0xffffffff8110c740
[   19.580686] [] 0xffffffff8116f9d8
[   19.585567] [] 0xffffffff812b6ed0
[   19.590450] [] 0xffffffff812b3188
[   19.595338] [] 0xffffffff812b329c
[   19.600222] [] 0xffffffff812b0248
[   19.605104] [] 0xffffffff811dd1b4
[   19.609985] [] 0xffffffff811eb76c
[   19.614868] [] 0xffffffff811ec300
[   19.619751] [] 0xffffffff811ecbf4
[   19.624635] [] 0xffffffff8110c7c0
[   19.629516] [] 0xffffffff811f9cac
[   19.634398] [] 0xffffffff811dd5cc
[   19.639281] [] 0xffffffff811038d0
[   19.644164] [] 0xffffffff81107668
[   19.649045] [] 0xffffffff811e2d8c
[   19.653928]
[   19.655705] ---[ end trace 790531a03d0d7329 ]---
[   19.660688] ------------[ cut here ]------------
[   19.665362] WARNING: CPU: 1 PID: 626 at fs/f2fs/segment.c:718 0xffffffff812cfd90()
[   19.672952] Modules linked in: pppoe ppp_async iptable_nat pppox ppp_generic nf_nat_ipv4 nf_conntrack_ipv6 nf_conntrack_ipv4 ipt_REJECT ipt_MASQUERADE xt_time xt_tcpudp xt_state xt_nat xt_multiport xt_mark xt_mac xt_limit xt_conntrack xt_comment xt_TCPMSS xt_REDIRECT xt_LOG xt_CT slhc nf_reject_ipv4 nf_nat_redirect nf_nat_masquerade_ipv4 nf_nat nf_log_ipv4 nf_defrag_ipv6 nf_defrag_ipv4 nf_conntrack_rtcache nf_conntrack iptable_mangle iptable_filter ip_tables crc_ccitt ip6t_REJECT nf_reject_ipv6 nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables x_tables
[   19.724383] CPU: 1 PID: 626 Comm: uci Tainted: G        W       4.4.61 #0
[   19.731172] Stack : 0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 800000041e107380 0000000000000000 ffffffff81125fc0
          0000000000000000 0000000000000000 0000000000000000 0000000000000000
          0000000000000000 ffffffff81125fc0 0000000000000000 0000000000000000
          ...
[   19.796810] Call Trace:[] 0xffffffff81125fc0
[   19.802671] [] 0xffffffff81125fc0
[   19.807554] [] 0xffffffff813103dc
[   19.812435] [] 0xffffffff8113baac
[   19.817317] [] 0xffffffff812cfd90
[   19.822200] [] 0xffffffff812d0690
[   19.827081] [] 0xffffffff812d2114
[   19.831964] [] 0xffffffff812d24d0
[   19.836846] [] 0xffffffff812d2580
[   19.841727] [] 0xffffffff812ca1fc
[   19.846610] [] 0xffffffff812cc2a0
[   19.851492] [] 0xffffffff812c24d0
[   19.856375] [] 0xffffffff8110c740
[   19.861256] [] 0xffffffff8116fa64
[   19.866138] [] 0xffffffff812c2788
[   19.871020] [] 0xffffffff811ad424
[   19.875902] [] 0xffffffff8116fa64
[   19.880786] [] 0xffffffff812bcc14
[   19.885668] [] 0xffffffff8110c740
[   19.890548] [] 0xffffffff8116f9d8
[   19.895430] [] 0xffffffff812b6ed0
[   19.900313] [] 0xffffffff812b3188
[   19.905200] [] 0xffffffff812b329c
[   19.910085] [] 0xffffffff812b0248
[   19.914966] [] 0xffffffff811dd1b4
[   19.919848] [] 0xffffffff811eb76c
[   19.924730] [] 0xffffffff811ec300
[   19.929613] [] 0xffffffff811ecbf4
[   19.934497] [] 0xffffffff8110c7c0
[   19.939378] [] 0xffffffff811f9cac
[   19.944260] [] 0xffffffff811dd5cc
[   19.949143] [] 0xffffffff811038d0
[   19.954026] [] 0xffffffff81107668
[   19.958907] [] 0xffffffff811e2d8c
[   19.963790]
[   19.965526] ---[ end trace 790531a03d0d732a ]---
[   24.600671] device eth0 entered promiscuous mode
[   24.620295] br-lan: port 1(eth0) entered forwarding state
[   24.625817] br-lan: port 1(eth0) entered forwarding state
[   24.765769] eth1: 1000 Mbps Full duplex, port 1, queue 1
[   25.579165] br-lan: port 1(eth0) entered disabled state
[   27.591127] eth0: 1000 Mbps Full duplex, port 0, queue 0
[   27.596582] br-lan: port 1(eth0) entered forwarding state
[   27.602133] br-lan: port 1(eth0) entered forwarding state
[   29.602953] br-lan: port 1(eth0) entered forwarding state



Another capture from my build:
https://pastebin.com/usVfACu0

More information can be found at the following URL:
https://bugs.lede-project.org/index.php?do=details&task_id=749



More information about the lede-bugs mailing list