12621 – busybox syslog missing a lot of kernel messages when kernel bootup（compare with serial port outputs or dmesg outputs）

Bug 12621 - busybox syslog missing a lot of kernel messages when kernel bootup（compare with serial port outputs or dmesg outputs）

Summary: busybox syslog missing a lot of kernel messages when kernel bootup（compare wi...

Status:	NEW

Alias:	None

Product:	Busybox
Classification:	Unclassified
Component:	Other (show other bugs)
Version:	1.22.x
Hardware:	Other Other

Importance:	P5 normal
Target Milestone:	---
Assignee:	unassigned

URL:
Keywords:

Depends on:
Blocks:

Reported:	2020-03-09 06:47 UTC by jack back
Modified:	2020-03-09 06:50 UTC (History)
CC List:	1 user (show)

See Also:
Host:
Target:
Build:

Attachments
openwrt config file （rar type file, need to unpack first then can see .config） (22.40 KB, application/octet-stream) 2020-03-09 06:50 UTC, jack back	Details
Add an attachment (proposed patch, testcase, etc.)

Note You need to log in before you can comment on or make changes to this bug.

Description jack back 2020-03-09 06:47:20 UTC

I'm using busybox-1.22.1 on an embedded system running Linux kernel 3.10.34. The system is openwrt and the cpu architecture is MIPS 1004Kc V2.15（Soc：MTK 7621A）.

I check the system.log file and find it has less logs then serial port or dmesg outputs, below I'll list the output：

1. logs in system.log
2020.03.03-16:29:16  info  syslogd started: BusyBox v1.22.1                                                                     
2020.03.03-16:29:16  notice  kernel: [    0.000000] Linux version 3.10.34 (yanai.wang@localhost.localdomain) (gcc version 4.8.3 (OpenWrt/Linaro GCC 4.8-2014.04 r288) ) #1 SMP Mon Mar 2 12:28
2020.03.03-16:29:16  info  kernel: [    0.000000] SoC Type: Mediatek MT7621 ver:1 eco:3                                     
2020.03.03-16:29:16  info  kernel: [    0.000000] bootconsole [early0] enabled                                                       
2020.03.03-16:29:16  info  kernel: [    0.000000] CPU revision is: 0001992f (MIPS 1004Kc)                                     
2020.03.03-16:29:16  info  kernel: [    0.000000] MIPS: machine is WiTown board                                                 
2020.03.03-16:29:16  info  kernel: [    0.000000] Determined physical RAM map:                                                   
2020.03.03-16:29:16  info  kernel: [    0.000000]  memory: 10000000 @ 00000000 (usable)                                   
2020.03.03-16:29:16  info  kernel: [    0.000000] Initrd not found or empty - disabling initrd                                   
2020.03.03-16:29:16  warn  kernel: [    0.000000] Zone ranges:                                                               
2020.03.03-16:29:16  warn  kernel: [    0.000000]   Normal   [mem 0x00000000-0x0fffffff] 
2020.03.04-10:17:19  warn  kernel: [    0.000000] Movable zone start for each node       
2020.03.04-10:17:19  info  kernel: [    1.970000] pci 0000:00:01.0: BAR 9: assigned [mem 0x60200000-0x602fffff pref]
2020.03.04-10:17:19  warn  kernel: [    2.710000] FM_OUT value: u4FmOut = 0(0x00000000)                             
2020.03.04-10:17:19  info  kernel: [    9.470000] xt_time: kernel timezone is -0000                                 
2020.03.04-10:17:19  emerg  syslog: ls: /etc/log/crashlog.*: No such file or directory       
2020.03.04-10:17:21  notice  netifd: netifd start ...                                         
2020.03.04-10:17:21  warn  kernel: [   14.320000] raeth: HW IP/TCP/UDP checksum RX/TX offload enabled       
2020.03.04-10:17:21  warn  kernel: [   14.330000] raeth: HW VLAN RX offload enabled                 
2020.03.04-10:17:21  warn  kernel: [   14.340000] raeth: HW Scatter/Gather TX offload enabled                       
2020.03.04-10:17:21  warn  kernel: [   14.350000] raeth: HW TCP segmentation offload (TSO) enabled                 
2020.03.04-10:17:21  warn  kernel: [   14.360000] mtk_esw - hw bridge: W|LLLL                                       
2020.03.04-10:17:21  info  kernel: [   14.380000] device eth0.1 entered promiscuous mode         
2020.03.04-10:17:21  info  kernel: [   14.390000] device eth0 entered promiscuous mode               
2020.03.04-10:17:21  info  kernel: [   14.400000] br-lan: port 1(eth0.1) entered forwarding state   
2020.03.04-10:17:21  info  kernel: [   14.410000] br-lan: port 1(eth0.1) entered forwarding state   

2. logs on serial port （too long and I just pick some of them）
[    0.000000] Linux version 3.10.34 (yanai.wang@localhost.localdomain) (gcc version 4.8.3 (OpenWrt/Linaro GCC 4.8-2014.04 r288) ) #1 SMP Mon Mar 2 12:28:24 CST 2020
[    0.000000] SoC Type: Mediatek MT7621 ver:1 eco:3
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU revision is: 0001992f (MIPS 1004Kc)
[    0.000000] MIPS: machine is WiTown board
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 10000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x00000000-0x0fffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x00000000-0x0fffffff]   #these logs below can't be seen in system.log reboot everytime 
[    0.000000] Detected 3 available secondary CPU(s)
[    0.000000] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
[    0.000000] MIPS secondary cache 256kB, 8-way, linesize 32 bytes.
[    0.000000] PERCPU: Embedded 7 pages/cpu @81207000 s6656 r8192 d13824 u32768
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 65024
[    0.000000] Kernel command line:  boot_firmware=1 console=ttyS0,57600 rootfstype=squashfs,jffs2
[    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Writing ErrCtl register=00031010
[    0.000000] Readback ErrCtl register=00031010
[    0.000000] Memory: 255796k/262144k available (2825k kernel code, 6348k reserved, 675k data, 204k init, 0k highmem)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS:256
[    0.000000] gic: revision 3.0
[    0.000000] CPU Clock: 880MHz
[    0.000000] Calibrating delay loop... 577.53 BogoMIPS (lpj=2887680)
[    0.050000] pid_max: default: 32768 minimum: 301
[    0.060000] Mount-cache hash table entries: 512
[    0.070000] CPU revision is: 0001992f (MIPS 1004Kc)
[    0.070000] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.070000] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
[    0.070000] MIPS secondary cache 256kB, 8-way, linesize 32 bytes.
[    0.140000] Synchronize counters for CPU 1: done.
[    0.150000] CPU revision is: 0001992f (MIPS 1004Kc)
[    0.150000] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.150000] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
[    0.150000] MIPS secondary cache 256kB, 8-way, linesize 32 bytes.
[    0.220000] Synchronize counters for CPU 2: done.
[    0.230000] CPU revision is: 0001992f (MIPS 1004Kc)
[    0.230000] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.230000] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
[    0.230000] MIPS secondary cache 256kB, 8-way, linesize 32 bytes.
[    0.300000] Synchronize counters for CPU 3: done.
[    0.310000] Brought up 4 CPUs
[    0.310000] pinctrl core: initialized pinctrl subsystem
[    0.320000] NET: Registered protocol family 16
[    0.320000] witown_netlink_init init witown netlink type WI_NETLINK_WITOWN !
[    0.330000] release PCIe RST: RALINK_RSTCTRL = 7000000
[    0.340000] ***** Xtal 40MHz *****
[    0.340000] pull PCIe RST: RALINK_RSTCTRL = 6000000
[    0.640000] release PCIe RST: RALINK_RSTCTRL = 7000000
[    0.650000] Port 0 N_FTS = 1b105000
[    0.650000] Port 1 N_FTS = 1b105000
[    0.660000] Port 2 N_FTS = 1b105000
[    1.790000] PCIE2 no card, disable it(RST&CLK)
[    1.800000]  -> 21007f2
[    1.800000] PCIE0 enabled
[    1.810000] PCIE1 enabled
[    1.820000] bio: create slab <bio-0> at 0
[    1.830000] mt7621_gpio 1e000600.gpio: registering 32 gpios
[    1.840000] mt7621_gpio 1e000600.gpio: registering 32 gpios
[    1.850000] mt7621_gpio 1e000600.gpio: registering 32 gpios
[    1.860000] usbcore: registered new interface driver usbfs
[    1.870000] usbcore: registered new interface driver hub
[    1.880000] usbcore: registered new device driver usb
[    1.900000] PCI host bridge to bus 0000:00
[    1.900000] pci_bus 0000:00: root bus resource [mem 0x60000000-0x6fffffff]
[    1.920000] pci_bus 0000:00: root bus resource [io  0x1e160000-0x1e16ffff]
[    1.930000] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    1.950000] pci 0000:00:00.0: BAR 0: can't assign mem (size 0x80000000)
[    1.960000] pci 0000:00:01.0: BAR 0: can't assign mem (size 0x80000000)
[    1.970000] pci 0000:00:00.0: BAR 8: assigned [mem 0x60000000-0x600fffff]
[    1.990000] pci 0000:00:01.0: BAR 8: assigned [mem 0x60100000-0x601fffff]
[    2.000000] pci 0000:00:01.0: BAR 9: assigned [mem 0x60200000-0x602fffff pref]
[    2.020000] pci 0000:00:00.0: BAR 1: assigned [mem 0x60300000-0x6030ffff]
[    2.030000] pci 0000:00:01.0: BAR 1: assigned [mem 0x60310000-0x6031ffff]
[    2.040000] pci 0000:01:00.0: BAR 0: assigned [mem 0x60000000-0x600fffff]
[    2.060000] pci 0000:00:00.0: PCI bridge to [bus 01]
[    2.070000] pci 0000:00:00.0:   bridge window [mem 0x60000000-0x600fffff]
[    2.080000] pci 0000:02:00.0: BAR 0: assigned [mem 0x60100000-0x601fffff 64bit]
[    2.090000] pci 0000:02:00.0: BAR 6: assigned [mem 0x60200000-0x6020ffff pref]
[    2.110000] pci 0000:00:01.0: PCI bridge to [bus 02]
[    2.120000] pci 0000:00:01.0:   bridge window [mem 0x60100000-0x601fffff]
[    2.130000] pci 0000:00:01.0:   bridge window [mem 0x60200000-0x602fffff pref]
[    2.150000] BAR0 at slot 0 = 0
[    2.150000] bus=0x0, slot = 0x0
[    2.160000] BAR0 at slot 1 = 0
[    2.160000] bus=0x0, slot = 0x1
[    2.170000] bus=0x1, slot = 0x0, irq=0xff
[    2.180000] bus=0x2, slot = 0x1, irq=0xff
[    2.190000] Switching to clocksource MIPS
[    2.200000] NET: Registered protocol family 2
[    2.220000] TCP established hash table entries: 2048 (order: 2, 16384 bytes)
[    2.230000] TCP bind hash table entries: 2048 (order: 2, 16384 bytes)
[    2.240000] TCP: Hash tables configured (established 2048 bind 2048)
[    2.260000] TCP: reno registered
[    2.260000] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    2.270000] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    2.290000] NET: Registered protocol family 1
[    2.310000] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    2.320000] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    2.340000] msgmni has been set to 499
[    2.350000] io scheduler noop registered
[    2.350000] io scheduler deadline registered (default)
[    2.370000] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[    2.380000] 1e000c00.uartlite: ttyS0 at MMIO 0x1e000c00 (irq = 34) is a 16550A
[    2.390000] console [ttyS0] enabled, bootconsole disabled
[    2.390000] console [ttyS0] enabled, bootconsole disabled
[    2.420000] flash manufacture id: c2, device id 20 19
[    2.430000] MX25L25635E(c2 2019c220) (32768 Kbytes)
[    2.440000] mtd .name = raspi, .size = 0x02000000 (32M) .erasesize = 0x00010000 (64K) .numeraseregions = 0
[    2.460000] Creating 9 MTD partitions on "raspi":
[    2.470000] 0x000000000000-0x000000030000 : "u-boot"
[    2.480000] 0x000000030000-0x000000040000 : "u-boot-env"
[    2.490000] 0x000000040000-0x000000050000 : "factory"
[    2.500000] 0x000000050000-0x000000b50000 : "firmware1"
[    2.510000] 0x000000180222-0x000000b50000 : "rootfs"
[    2.520000] mtd: partition "rootfs" must either start or end on erase block boundary or be smaller than an erase block -- forcing read-only
[    2.550000] mtd: device 4 (rootfs) set to be root filesystem
[    2.560000] 0x000000b50000-0x000001650000 : "firmware2"
[    2.570000] 0x000001650000-0x000001ad0000 : "rootfs_data"
[    2.580000] 0x000001ad0000-0x000001fd0000 : "cloud"
[    2.590000] 0x000001fd0000-0x000001ff0000 : "Reserve"
[    2.600000] 0x000001ff0000-0x000002000000 : "Buginfo"
[    2.620000] Ralink APSoC Ethernet Driver v3.2.4 (raeth)
[    2.630000] raeth: PDMA RX ring 512, PDMA TX ring 256. Max packet size 1536

3. my busybox syslog setting
see attachment

Comment 1 jack back 2020-03-09 06:50:59 UTC

Created attachment 8391 [details]
openwrt config file （rar type file, need to unpack first then can see .config）