continuous user reset with linux 4.9.127

This forum is for users of Microchip MPUs and who are interested in using Linux OS.

Moderator: nferre

vchandapur
Posts: 5
Joined: Fri May 11, 2018 3:31 pm

continuous user reset with linux 4.9.127

Mon Feb 03, 2020 5:44 pm

Hi,

we are using custom board based on at91sam9g45,After flashing the images we are observing continuous user reset.
Could please help us debug further.

RomBOOT
reset: User reset
[2020-02-03 07:37:03] CPU: ARM926EJ-S [41069265] revision 5 (ARMv5TEJ), cr=0005317f
[2020-02-03 07:37:03] CPU: VIVT data cache, VIVT instruction cache
[2020-02-03 07:37:03] OF: fdt:Machine model: Atmel AT91SAM9G45-CU
[2020-02-03 07:37:03] Memory policy: Data cache writeback
[2020-02-03 07:37:03] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 65024
[2020-02-03 07:37:03] Kernel command line: ubi.mtd=4,512 root=ubi0:rootfs rootfstype=ubifs rootflags=bulk_read,chk_data_crc noalign vt.global_cursor_default=0 console=ttyS0,115200n8, mem=256M
[2020-02-03 07:37:03] PID hash table entries: 1024 (order: 0, 4096 bytes)
[2020-02-03 07:37:03] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[2020-02-03 07:37:03] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[2020-02-03 07:37:04] Memory: 253964K/262144K available (4190K kernel code, 174K rwdata, 988K rodata, 200K init, 174K bss, 8180K reserved, 0K cma-reserved)
[2020-02-03 07:37:04] Virtual kernel memory layout:
[2020-02-03 07:37:04] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[2020-02-03 07:37:04] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
[2020-02-03 07:37:04] vmalloc : 0xd0800000 - 0xff800000 ( 752 MB)
[2020-02-03 07:37:04] lowmem : 0xc0000000 - 0xd0000000 ( 256 MB)
[2020-02-03 07:37:04] modules : 0xbf000000 - 0xc0000000 ( 16 MB)
[2020-02-03 07:37:04] .text : 0xc0008000 - 0xc041fe10 (4192 kB)
[2020-02-03 07:37:04] .init : 0xc0518000 - 0xc054a000 ( 200 kB)
[2020-02-03 07:37:04] .data : 0xc054a000 - 0xc0575bc0 ( 175 kB)
[2020-02-03 07:37:04] .bss : 0xc0575bc0 - 0xc05a174c ( 175 kB)
[2020-02-03 07:37:04] NR_IRQS:16 nr_irqs:16 16
[2020-02-03 07:37:04] clocksource: pit: mask: 0x7ffffff max_cycles: 0x7ffffff, max_idle_ns: 7167226906 ns
[2020-02-03 07:37:04] sched_clock: 32 bits at 128 Hz, resolution 7812500ns, wraps every 16777215996093750ns
[2020-02-03 07:37:04] Console: colour dummy device 80x30
[2020-02-03 07:37:04] Calibrating delay loop... 198.76 BogoMIPS (lpj=775168)
[2020-02-03 07:37:04] pid_max: default: 32768 minimum: 301
[2020-02-03 07:37:04] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[2020-02-03 07:37:04] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[2020-02-03 07:37:04] CPU: Testing write buffer coherency: ok
[2020-02-03 07:37:04] Setting up static identity map for 0x20008400 - 0x20008460
[2020-02-03 07:37:04] devtmpfs: initialized
[2020-02-03 07:37:04] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 14931722236523437 ns
[2020-02-03 07:37:04] futex hash table entries: 256 (order: -1, 3072 bytes)
[2020-02-03 07:37:04] pinctrl core: initialized pinctrl subsystem
[2020-02-03 07:37:04] NET: Registered protocol family 16
[2020-02-03 07:37:04] DMA: preallocated 256 KiB pool for atomic coherent allocations
[2020-02-03 07:37:04] cpuidle: using governor ladder
[2020-02-03 07:37:04] gpio-at91 fffff200.gpio: at address d0851200
[2020-02-03 07:37:04] gpio-at91 fffff400.gpio: at address d0853400
[2020-02-03 07:37:04] gpio-at91 fffff600.gpio: at address d0855600
[2020-02-03 07:37:04] gpio-at91 fffff800.gpio: at address d0857800
[2020-02-03 07:37:04] gpio-at91 fffffa00.gpio: at address d0859a00
[2020-02-03 07:37:04] pinctrl-at91 ahb:apb:pinctrl@fffff200: initialized AT91 pinctrl driver
[2020-02-03 07:37:04] clocksource: tcb_clksrc: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 114675631333 ns
[2020-02-03 07:37:04] at_hdmac ffffec00.dma-controller: Atmel AHB DMA Controller ( cpy set slave sg-cpy ), 8 channels
[2020-02-03 07:37:04] AT91: Detected SoC family: at91sam9g45
[2020-02-03 07:37:04] AT91: Detected SoC: at91sam9g45, revision 2
[2020-02-03 07:37:04] SCSI subsystem initialized
[2020-02-03 07:37:04] usbcore: registered new interface driver usbfs
[2020-02-03 07:37:04] usbcore: registered new interface driver hub
[2020-02-03 07:37:04] usbcore: registered new device driver usb
[2020-02-03 07:37:04] at91_i2c fff84000.i2c: can't get DMA channel, continue without DMA support
[2020-02-03 07:37:04] at91_i2c fff84000.i2c: AT91 i2c bus driver (hw version: 0x302).
[2020-02-03 07:37:04] clocksource: Switched to clocksource tcb_clksrc
[2020-02-03 07:37:04] NET: Registered protocol family 2
[2020-02-03 07:37:04] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[2020-02-03 07:37:04] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
[2020-02-03 07:37:04] TCP: Hash tables configured (established 2048 bind 2048)
[2020-02-03 07:37:04] UDP hash table entries: 256 (order: 0, 4096 bytes)
[2020-02-03 07:37:04] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[2020-02-03 07:37:04] NET: Registered protocol family 1
[2020-02-03 07:37:04] workingset: timestamp_bits=30 max_order=16 bucket_order=0
[2020-02-03 07:37:04] NET: Registered protocol family 38
[2020-02-03 07:37:04] io scheduler noop registered (default)
[2020-02-03 07:37:04] brd: module loaded
[2020-02-03 07:37:04] loop: module loaded
[2020-02-03 07:37:04] atmel_usart_serial.0.auto: ttyS0 at MMIO 0xffffee00 (irq = 16, base_baud = 8333333) is a ATMEL_SERIAL
[2020-02-03 07:37:04] console [ttyS0] enabled
[2020-02-03 07:37:04] atmel_usart_serial.1.auto: ttyS2 at MMIO 0xfff90000 (irq = 23, base_baud = 8333333) is a ATMEL_SERIAL
[2020-02-03 07:37:04] SCSI Media Changer driver v0.25
[2020-02-03 07:37:04] atmel_nand 40000000.nand: Using dma0chan0 for DMA transfers.
[2020-02-03 07:37:04] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xaa
[2020-02-03 07:37:04] nand: Micron MT29F2G08ABBEAHC
[2020-02-03 07:37:04] nand: 256 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[2020-02-03 07:37:04] Scanning device for bad blocks
[2020-02-03 07:37:04] 10 ofpart partitions found on MTD device atmel_nand
[2020-02-03 07:37:04] Creating 10 MTD partitions on "atmel_nand":
[2020-02-03 07:37:04] 0x000000000000-0x000000020000 : "at91bootstrap"
[2020-02-03 07:37:04] 0x000000040000-0x0000000c0000 : "U-boot"
[2020-02-03 07:37:04] 0x000000180000-0x000000200000 : "U-Boot Env"
[2020-02-03 07:37:04] 0x000000200000-0x000000600000 : "kernel"
[2020-02-03 07:37:04] 0x000000800000-0x000005000000 : "rootfs"
[2020-02-03 07:37:04] 0x000005000000-0x000006e00000 : "application"
[2020-02-03 07:37:04] 0x000006e00000-0x000007200000 : "kernel_slot1"
[2020-02-03 07:37:04] 0x000007400000-0x00000bc00000 : "rootfs_slot1"
[2020-02-03 07:37:04] 0x00000bc00000-0x00000da00000 : "application_slot1"
[2020-02-03 07:37:04] 0x00000da00000-0x00000e200000 : "relfs"
[2020-02-03 07:37:04] atmel_spi fffa4000.spi: can not allocate dma coherent memory
[2020-02-03 07:37:04] ad_dpot spi32766.1: ad5160 256-Position Digital Potentiometer registered
[2020-02-03 07:37:04] atmel_spi fffa4000.spi: Atmel SPI Controller version 0x210 at 0xfffa4000 (irq 30)
[2020-02-03 07:37:04] libphy: Fixed MDIO Bus: probed
[2020-02-03 07:37:04] libphy: MACB_mii_bus: probed
[2020-02-03 07:37:04] Davicom DM9161A fffbc000.ethernet-ffffffff:00: attached PHY driver [Davicom DM9161A] (mii_bus:phy_addr=fffbc000.ethernet-ffffffff:00, irq=-1)
[2020-02-03 07:37:04] macb fffbc000.ethernet eth0: Cadence MACB rev 0x0001010c at 0xfffbc000 irq 24 (00:0b:d8:00:00:00)
[2020-02-03 07:37:04] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[2020-02-03 07:37:04] ohci-platform: OHCI generic platform driver
[2020-02-03 07:37:04] ohci-atmel: OHCI Atmel driver
[2020-02-03 07:37:04] at91_ohci 700000.ohci: failed to find sfr node
[2020-02-03 07:37:04] at91_ohci 700000.ohci: USB Host Controller
[2020-02-03 07:37:04] at91_ohci 700000.ohci: new USB bus registered, assigned bus number 1
[2020-02-03 07:37:04] at91_ohci 700000.ohci: irq 31, io mem 0x00700000
[2020-02-03 07:37:04] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
[2020-02-03 07:37:04] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[2020-02-03 07:37:04] usb usb1: Product: USB Host Controller
[2020-02-03 07:37:04] usb usb1: Manufacturer: Linux 4.9.127- ISC uImage V1.30 ohci_hcd
[2020-02-03 07:37:04] usb usb1: SerialNumber: at91
[2020-02-03 07:37:04] hub 1-0:1.0: USB hub found
[2020-02-03 07:37:04] hub 1-0:1.0: 2 ports detected
[2020-02-03 07:37:04] usbcore: registered new interface driver usblp
[2020-02-03 07:37:04] usbcore: registered new interface driver usb-storage
[2020-02-03 07:37:04] udc-core: couldn't find an available UDC - added [printer] to list of pending drivers
[2020-02-03 07:37:04] rtc-pcf8563 0-0051: rtc core: registered rtc-pcf8563 as rtc0
[2020-02-03 07:37:04] i2c /dev entries driver
[2020-02-03 07:37:04] DSX: client flags = 0, addr = 70, name = pca9547, adapter name = AT91
[2020-02-03 07:37:04] i2c i2c-0: Added multiplexed i2c bus 2
[2020-02-03 07:37:04] i2c i2c-0: Added multiplexed i2c bus 3
[2020-02-03 07:37:04] i2c i2c-0: Added multiplexed i2c bus 4
[2020-02-03 07:37:04] i2c i2c-0: Added multiplexed i2c bus 5
[2020-02-03 07:37:04] i2c i2c-0: Added multiplexed i2c bus 6
[2020-02-03 07:37:04] random: fast init done
[2020-02-03 07:37:04] i2c i2c-0: Added multiplexed i2c bus 7
[2020-02-03 07:37:04] i2c i2c-0: Added multiplexed i2c bus 8
[2020-02-03 07:37:04] i2c i2c-0: Added multiplexed i2c bus 9
[2020-02-03 07:37:04] pca954x 0-0070: registered 8 multiplexed busses for I2C mux pca9547
[2020-02-03 07:37:04] AT91: Starting after software reset
[2020-02-03 07:37:04] at91sam9_wdt: enabled (heartbeat=15 sec, nowayout=0)
[2020-02-03 07:37:05] softdog: initialized. soft_noboot=0 soft_margin=60 sec soft_panic=0 (nowayout=0)
[2020-02-03 07:37:05] device-mapper: ioctl: 4.35.0-ioctl (2016-06-23) initialised: dm-devel@redhat.com
[2020-02-03 07:37:05] atmel_mci fff80000.mmc: version: 0x410
[2020-02-03 07:37:05] atmel_mci fff80000.mmc: using dma0chan1 for DMA transfers
[2020-02-03 07:37:05] atmel_mci fff80000.mmc: Atmel MCI controller at 0xfff80000 irq 29, 1 slots
[2020-02-03 07:37:05] iio iio:device0: Resolution used: 10 bits
[2020-02-03 07:37:05] iio iio:device0: ADC Touch screen is disabled.
[2020-02-03 07:37:05] Netfilter messages via NETLINK v0.30.
[2020-02-03 07:37:05] nf_conntrack version 0.5.0 (4096 buckets, 16384 max)
[2020-02-03 07:37:05] ctnetlink v0.93: registering with nfnetlink.
[2020-02-03 07:37:05] nf_tables: (c) 2007-2009 Patrick McHardy <kaber@trash.net>
[2020-02-03 07:37:05] nf_tables_compat: (c) 2012 Pablo Neira Ayuso <pablo@netfilter.org>
[2020-02-03 07:37:05] ipip: IPv4 and MPLS over IPv4 tunneling driver
[2020-02-03 07:37:05] ip_tables: (C) 2000-2006 Netfilter Core Team
[2020-02-03 07:37:05] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully
[2020-02-03 07:37:05] arp_tables: arp_tables: (C) 2002 David S. Miller
[2020-02-03 07:37:05] NET: Registered protocol family 17
[2020-02-03 07:37:05] Key type encrypted registered
[2020-02-03 07:37:05] ubi0: default fastmap pool size: 25
[2020-02-03 07:37:05] ubi0: default fastmap WL pool size: 12
[2020-02-03 07:37:05] ubi0: attaching mtd4
[2020-02-03 07:37:05] mmc0: host does not support reading read-only switch, assuming write-enable
[2020-02-03 07:37:05] mmc0: new high speed SDHC card at address 1234
[2020-02-03 07:37:05] mmcblk0: mmc0:1234 SA04G 3.64 GiB
[2020-02-03 07:37:05] ubi0: scanning is finished
[2020-02-03 07:37:05] usb 1-2: new full-speed USB device number 2 using at91_ohci
[2020-02-03 07:37:05] ubi0: attached mtd4 (name "rootfs", size 72 MiB)
[2020-02-03 07:37:05] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 129024 bytes
[2020-02-03 07:37:05] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 512
[2020-02-03 07:37:05] ubi0: VID header offset: 512 (aligned 512), data offset: 2048
[2020-02-03 07:37:05] ubi0: good PEBs: 576, bad PEBs: 0, corrupted PEBs: 0
[2020-02-03 07:37:05] ubi0: user volume: 1, internal volumes: 1, max. volumes count: 128
[2020-02-03 07:37:05] ubi0: max/mean erase counter: 9/4, WL threshold: 4096, image sequence number: 398291135
[2020-02-03 07:37:05] ubi0: available PEBs: 7, total reserved PEBs: 569, PEBs reserved for bad PEB handling: 10
[2020-02-03 07:37:05] ubi0: background thread "ubi_bgt0d" started, PID 83
[2020-02-03 07:37:05] at91_i2c fff84000.i2c: overrun while reading
[2020-02-03 07:37:05] rtc-pcf8563 0-0051: pcf8563_read_block_data: read error
[2020-02-03 07:37:05] rtc-pcf8563 0-0051: hctosys: unable to read the hardware clock
[2020-02-03 07:37:05] UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0, name "rootfs", R/O mode
[2020-02-03 07:37:05] UBIFS (ubi0:0): LEB size: 129024 bytes (126 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[2020-02-03 07:37:05] UBIFS (ubi0:0): FS size: 69931008 bytes (66 MiB, 542 LEBs), journal size 9033728 bytes (8 MiB, 71 LEBs)
[2020-02-03 07:37:05] UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
[2020-02-03 07:37:05] UBIFS (ubi0:0): media format: w4/r0 (latest is w4/r0), UUID FD4D9CD6-92E5-4F56-B2CE-860CAAD88878, small LPT model
[2020-02-03 07:37:05] UBIFS (ubi0:0): full atime support is enabled.
[2020-02-03 07:37:05] VFS: Mounted root (ubifs filesystem) readonly on device 0:13.
[2020-02-03 07:37:05] devtmpfs: mounted
[2020-02-03 07:37:05] Freeing unused kernel memory: 200K
[2020-02-03 07:37:05] This architecture does not have kernel memory protection.
[2020-02-03 07:37:05] usb 1-2: New USB device found, idVendor=0930, idProduct=6545
[2020-02-03 07:37:05] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[2020-02-03 07:37:05] usb 1-2: Product: DataTraveler 2.0
[2020-02-03 07:37:05] usb 1-2: Manufacturer: Kingston
[2020-02-03 07:37:05] usb 1-2: SerialNumber: 408D5CBF5F0AB271F96807C5
[2020-02-03 07:37:05] usb-storage 1-2:1.0: USB Mass Storage device detected
[2020-02-03 07:37:05] scsi host0: usb-storage 1-2:1.0
[2020-02-03 07:37:05] INIT: version 2.88 booting
[2020-02-03 07:37:06] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, PID 108
[2020-02-03 07:37:06] scsi 0:0:0:0: Direct-Access Kingston DataTraveler 2.0 PMAP PQ: 0 ANSI: 6
[2020-02-03 07:37:06] sd 0:0:0:0: Attached scsi generic sg0 type 0
[2020-02-03 07:37:06] sd 0:0:0:0: [sda] 30240768 512-byte logical blocks: (15.5 GB/14.4 GiB)
[2020-02-03 07:37:06] sd 0:0:0:0: [sda] Write Protect is off
[2020-02-03 07:37:06] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[2020-02-03 07:37:06] sda: sda1
[2020-02-03 07:37:06] sd 0:0:0:0: [sda] Attached SCSI removable disk
[2020-02-03 07:37:08] random: dd: uninitialized urandom read (512 bytes read)
[2020-02-03 07:37:08] INIT: Entering runlevel: 3
[2020-02-03 07:37:08] Starting system message bus: random: dbus-daemon: uninitialized urandom read (12 bytes read)
[2020-02-03 07:37:08] random: dbus-daemon: uninitialized urandom read (4 bytes read)
[2020-02-03 07:37:08] dbus.
[2020-02-03 07:37:08] Starting Dropbear SSH server: dropbear.
[2020-02-03 07:37:08] Starting syslogd/klogd: done
[2020-02-03 07:37:21] RomBOOT
[2020-02-03 07:37:21] reset: User reset
blue_z
Location: USA
Posts: 2033
Joined: Thu Apr 19, 2007 10:15 pm

Re: continuous user reset with linux 4.9.127

Wed Feb 05, 2020 3:42 am

vchandapur wrote: we are using custom board based ... we are observing continuous user reset.
...
RomBOOT
reset: User reset
[2020-02-03 07:37:03] CPU: ARM926EJ-S [41069265] revision 5 (ARMv5TEJ), cr=0005317f
[2020-02-03 07:37:03] CPU: VIVT data cache, VIVT instruction cache
[2020-02-03 07:37:03] OF: fdt:Machine model: Atmel AT91SAM9G45-CU
...
[2020-02-03 07:37:21] RomBOOT
[2020-02-03 07:37:21] reset: User reset
The posting of the log is apparently to show that there is a loop.
However this log appears to be inaccurate, as the first two lines have no time stamps, and the kernel log is obviously incomplete.

More significant is the origin of the "reset: User reset" line.
What code is outputting this text?
What custom software are you using?

You are reporting that there's a "user reset".
However the kernel log clearly indicates that the reset driver is reporting a software reset:
vchandapur wrote: [2020-02-03 07:37:04] AT91: Starting after software reset

So what is the source of that "reset: User reset" text?
Which report is to be believed?

Regards
vchandapur
Posts: 5
Joined: Fri May 11, 2018 3:31 pm

Re: continuous user reset with linux 4.9.127

Wed Feb 05, 2020 3:58 pm

Hi
Thanks for your quick reply. Much appreciated.

More significant is the origin of the "reset: User reset" line.What code is outputting this text?
[ANS] we have added in AT91 boot strap code to know the reset reason by reading reset reg.

What custom software are you using?
[ANS] We are using a C# based application on this custom hardware, however we haven't reached yet to the point at which the application starts running. We see the board being reset when the linux boot process reaches run level 3 (and majority when systemd-udevd is about to complete)
vchandapur
Posts: 5
Joined: Fri May 11, 2018 3:31 pm

Re: continuous user reset with linux 4.9.127

Wed Feb 05, 2020 4:01 pm

Attaching full logs
\2020-02-05 00:37:40] ISC Bootstrap V1.28 (Tue Dec 31 09:24:47 UTC 2019)
[2020-02-05 00:37:40]
[2020-02-05 00:37:40] NAND: ONFI flash detected
[2020-02-05 00:37:40] NAND: Manufacturer ID: 0x2c Chip ID: 0xaa
[2020-02-05 00:37:40] NAND: Page Bytes: 2048, Spare Bytes: 64
[2020-02-05 00:37:40] NAND: ECC Correctability Bits: 4, ECC Sector Bytes: 512
[2020-02-05 00:37:40] NAND: Enable On-Die ECC
[2020-02-05 00:37:40] NAND: Image: Copy 0xa0000 bytes from 0x40000 to 0x23f00000
[2020-02-05 00:37:40] NAND: Done to load image
[2020-02-05 00:37:42] <debug_uart>
[2020-02-05 00:37:42]
[2020-02-05 00:37:42] ISC U-Boot V1.28 (Dec 31 2019 - 09:25:09)
[2020-02-05 00:37:42]
[2020-02-05 00:37:42] CPU: AT91SAM9G45
[2020-02-05 00:37:42] Crystal frequency: 12 MHz
[2020-02-05 00:37:42] CPU clock : 400 MHz
[2020-02-05 00:37:42] Master clock : 133.333 MHz
[2020-02-05 00:37:42] Watchdog enabled
[2020-02-05 00:37:42] DRAM: 256 MiB
[2020-02-05 00:37:42] Master Clock is 132096000 Hz
[2020-02-05 00:37:42] ---------------------------------------
[2020-02-05 00:37:42] --- Configuring Chip Select 3 ---
[2020-02-05 00:37:42] ---------------------------------------
[2020-02-05 00:37:42] --- Desired timings ---
[2020-02-05 00:37:42] ---------------------------------------
[2020-02-05 00:37:42] AT91_SMC_SETUP_NWE 0
[2020-02-05 00:37:42] AT91_SMC_SETUP_NCS_WR 0
[2020-02-05 00:37:42] AT91_SMC_SETUP_NRD 0
[2020-02-05 00:37:42] AT91_SMC_SETUP_NCS_RD 0
[2020-02-05 00:37:42] AT91_SMC_PULSE_NWE 28
[2020-02-05 00:37:42] AT91_SMC_PULSE_NCS_WR 60
[2020-02-05 00:37:42] AT91_SMC_PULSE_NRD 28
[2020-02-05 00:37:42] AT91_SMC_PULSE_NCS_RD 60
[2020-02-05 00:37:42] AT91_SMC_CYCLE_NRD 56
[2020-02-05 00:37:42] AT91_SMC_CYCLE_NWE 56
[2020-02-05 00:37:42] Clock period (ns) 8
[2020-02-05 00:37:42] ---------------------------------------
[2020-02-05 00:37:42] --- Real timings ---
[2020-02-05 00:37:42] ---------------------------------------
[2020-02-05 00:37:42] AT91_SMC_SETUP_NWE 0
[2020-02-05 00:37:42] AT91_SMC_SETUP_NCS_WR 0
[2020-02-05 00:37:42] AT91_SMC_SETUP_NRD 0
[2020-02-05 00:37:42] AT91_SMC_SETUP_NCS_RD 0
[2020-02-05 00:37:42] AT91_SMC_PULSE_NWE 24
[2020-02-05 00:37:42] AT91_SMC_PULSE_NCS_WR 56
[2020-02-05 00:37:42] AT91_SMC_PULSE_NRD 24
[2020-02-05 00:37:42] AT91_SMC_PULSE_NCS_RD 56
[2020-02-05 00:37:42] AT91_SMC_CYCLE_NRD 56
[2020-02-05 00:37:42] AT91_SMC_CYCLE_NWE 56
[2020-02-05 00:37:42] Master Clock is 132096000 Hz
[2020-02-05 00:37:42] ---------------------------------------
[2020-02-05 00:37:42] --- Configuring Chip Select 3 ---
[2020-02-05 00:37:42] ---------------------------------------
[2020-02-05 00:37:42] --- Desired timings ---
[2020-02-05 00:37:42] ---------------------------------------
[2020-02-05 00:37:42] AT91_SMC_SETUP_NWE 10
[2020-02-05 00:37:42] AT91_SMC_SETUP_NCS_WR 0
[2020-02-05 00:37:42] AT91_SMC_SETUP_NRD 10
[2020-02-05 00:37:42] AT91_SMC_SETUP_NCS_RD 0
[2020-02-05 00:37:42] AT91_SMC_PULSE_NWE 10
[2020-02-05 00:37:42] AT91_SMC_PULSE_NCS_WR 30
[2020-02-05 00:37:42] AT91_SMC_PULSE_NRD 30
[2020-02-05 00:37:42] AT91_SMC_PULSE_NCS_RD 30
[2020-02-05 00:37:42] AT91_SMC_CYCLE_NRD 50
[2020-02-05 00:37:42] AT91_SMC_CYCLE_NWE 0
[2020-02-05 00:37:42] Clock period (ns) 8
[2020-02-05 00:37:42] ---------------------------------------
[2020-02-05 00:37:42] --- Real timings ---
[2020-02-05 00:37:42] ---------------------------------------
[2020-02-05 00:37:42] AT91_SMC_SETUP_NWE 8
[2020-02-05 00:37:42] AT91_SMC_SETUP_NCS_WR 0
[2020-02-05 00:37:42] AT91_SMC_SETUP_NRD 0
[2020-02-05 00:37:42] AT91_SMC_SETUP_NCS_RD 0
[2020-02-05 00:37:42] AT91_SMC_PULSE_NWE 8
[2020-02-05 00:37:42] AT91_SMC_PULSE_NCS_WR 24
[2020-02-05 00:37:42] AT91_SMC_PULSE_NRD 24
[2020-02-05 00:37:42] AT91_SMC_PULSE_NCS_RD 24
[2020-02-05 00:37:42] AT91_SMC_CYCLE_NRD 48
[2020-02-05 00:37:42] AT91_SMC_CYCLE_NWE 0
[2020-02-05 00:37:42] Phoenix LCD registered
[2020-02-05 00:37:42] NAND: 256 MiB
[2020-02-05 00:37:43] In: serial
[2020-02-05 00:37:43] Out: serial
[2020-02-05 00:37:43] Err: serial
[2020-02-05 00:37:43] Erasing NAND...
[2020-02-05 00:37:43] Erasing at 0x180000 -- 25% complete.Erasing at 0x1a0000 -- 50% complete.Erasing at 0x1c0000 -- 75% complete.Erasing at 0x1e0000 -- 100% complete.
[2020-02-05 00:37:43] Writing to NAND... OK
[2020-02-05 00:37:43] Hit any key to stop autoboot: 1 0
[2020-02-05 00:37:44]
[2020-02-05 00:37:44] NAND read: device 0 offset 0x6e00000, size 0x800000
[2020-02-05 00:37:46] 8388608 bytes read: OK
[2020-02-05 00:37:46] ## Booting kernel from Legacy Image at 20000000 ...
[2020-02-05 00:37:46] Image Name:
[2020-02-05 00:37:46] Image Type: ARM Linux Kernel Image (uncompressed)
[2020-02-05 00:37:46] Data Size: 2853547 Bytes = 2.7 MiB
[2020-02-05 00:37:46] Load Address: 20008000
[2020-02-05 00:37:46] Entry Point: 20008000
[2020-02-05 00:37:46] Verifying Checksum ... OK
[2020-02-05 00:37:46] Loading Kernel Image ... OK
[2020-02-05 00:37:47]
[2020-02-05 00:37:47] Starting kernel ...
[2020-02-05 00:37:47]
[2020-02-05 00:37:48] Booting Linux on physical CPU 0x0
[2020-02-05 00:37:48] Linux version 4.9.127 uImage V1.30 (gcc version 7.3.0 (GCC) ) #8 Mon Feb 3 17:18:57 IST 2020
[2020-02-05 00:37:48] CPU: ARM926EJ-S [41069265] revision 5 (ARMv5TEJ), cr=0005317f
[2020-02-05 00:37:48] CPU: VIVT data cache, VIVT instruction cache
[2020-02-05 00:37:48] OF: fdt:Machine model: Atmel AT91SAM9G45-CU
[2020-02-05 00:37:48] Memory policy: Data cache writeback
[2020-02-05 00:37:48] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 32512
[2020-02-05 00:37:48] Kernel command line: ubi.mtd=7,512 root=ubi0:rootfs rootfstype=ubifs rootflags=bulk_read,chk_data_crc noalign vt.global_cursor_default=0 console=ttyS0,115200n8, mem=128M
[2020-02-05 00:37:48] PID hash table entries: 512 (order: -1, 2048 bytes)
[2020-02-05 00:37:48] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[2020-02-05 00:37:48] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[2020-02-05 00:37:48] Memory: 124016K/131072K available (4190K kernel code, 174K rwdata, 988K rodata, 200K init, 174K bss, 7056K reserved, 0K cma-reserved)
[2020-02-05 00:37:48] Virtual kernel memory layout:
[2020-02-05 00:37:48] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[2020-02-05 00:37:48] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
[2020-02-05 00:37:48] vmalloc : 0xc8800000 - 0xff800000 ( 880 MB)
[2020-02-05 00:37:48] lowmem : 0xc0000000 - 0xc8000000 ( 128 MB)
[2020-02-05 00:37:48] modules : 0xbf000000 - 0xc0000000 ( 16 MB)
[2020-02-05 00:37:48] .text : 0xc0008000 - 0xc041fe10 (4192 kB)
[2020-02-05 00:37:48] .init : 0xc0518000 - 0xc054a000 ( 200 kB)
[2020-02-05 00:37:48] .data : 0xc054a000 - 0xc0575bc0 ( 175 kB)
[2020-02-05 00:37:48] .bss : 0xc0575bc0 - 0xc05a174c ( 175 kB)
[2020-02-05 00:37:48] NR_IRQS:16 nr_irqs:16 16
[2020-02-05 00:37:48] clocksource: pit: mask: 0x7ffffff max_cycles: 0x7ffffff, max_idle_ns: 7167226906 ns
[2020-02-05 00:37:48] sched_clock: 32 bits at 128 Hz, resolution 7812500ns, wraps every 16777215996093750ns
[2020-02-05 00:37:48] Console: colour dummy device 80x30
[2020-02-05 00:37:48] Calibrating delay loop... 198.76 BogoMIPS (lpj=775168)
[2020-02-05 00:37:48] pid_max: default: 32768 minimum: 301
[2020-02-05 00:37:48] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[2020-02-05 00:37:48] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[2020-02-05 00:37:48] CPU: Testing write buffer coherency: ok
[2020-02-05 00:37:48] Setting up static identity map for 0x20008400 - 0x20008460
[2020-02-05 00:37:48] devtmpfs: initialized
[2020-02-05 00:37:48] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 14931722236523437 ns
[2020-02-05 00:37:48] futex hash table entries: 256 (order: -1, 3072 bytes)
[2020-02-05 00:37:48] pinctrl core: initialized pinctrl subsystem
[2020-02-05 00:37:48] NET: Registered protocol family 16
[2020-02-05 00:37:48] DMA: preallocated 256 KiB pool for atomic coherent allocations
[2020-02-05 00:37:48] cpuidle: using governor ladder
[2020-02-05 00:37:48] gpio-at91 fffff200.gpio: at address c8851200
[2020-02-05 00:37:48] gpio-at91 fffff400.gpio: at address c8853400
[2020-02-05 00:37:48] gpio-at91 fffff600.gpio: at address c8855600
[2020-02-05 00:37:48] gpio-at91 fffff800.gpio: at address c8857800
[2020-02-05 00:37:48] gpio-at91 fffffa00.gpio: at address c8859a00
[2020-02-05 00:37:48] pinctrl-at91 ahb:apb:pinctrl@fffff200: initialized AT91 pinctrl driver
[2020-02-05 00:37:48] clocksource: tcb_clksrc: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 114675631333 ns
[2020-02-05 00:37:48] at_hdmac ffffec00.dma-controller: Atmel AHB DMA Controller ( cpy set slave sg-cpy ), 8 channels
[2020-02-05 00:37:48] AT91: Detected SoC family: at91sam9g45
[2020-02-05 00:37:48] AT91: Detected SoC: at91sam9g45, revision 2
[2020-02-05 00:37:48] SCSI subsystem initialized
[2020-02-05 00:37:48] usbcore: registered new interface driver usbfs
[2020-02-05 00:37:48] usbcore: registered new interface driver hub
[2020-02-05 00:37:48] usbcore: registered new device driver usb
[2020-02-05 00:37:48] at91_i2c fff84000.i2c: can't get DMA channel, continue without DMA support
[2020-02-05 00:37:48] at91_i2c fff84000.i2c: AT91 i2c bus driver (hw version: 0x302).
[2020-02-05 00:37:48] clocksource: Switched to clocksource tcb_clksrc
[2020-02-05 00:37:48] NET: Registered protocol family 2
[2020-02-05 00:37:48] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[2020-02-05 00:37:48] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[2020-02-05 00:37:48] TCP: Hash tables configured (established 1024 bind 1024)
[2020-02-05 00:37:48] UDP hash table entries: 256 (order: 0, 4096 bytes)
[2020-02-05 00:37:48] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[2020-02-05 00:37:48] NET: Registered protocol family 1
[2020-02-05 00:37:48] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[2020-02-05 00:37:48] NET: Registered protocol family 38
[2020-02-05 00:37:48] io scheduler noop registered (default)
[2020-02-05 00:37:48] brd: module loaded
[2020-02-05 00:37:48] loop: module loaded
[2020-02-05 00:37:48] atmel_usart_serial.0.auto: ttyS0 at MMIO 0xffffee00 (irq = 16, base_baud = 8333333) is a ATMEL_SERIAL
[2020-02-05 00:37:48] console [ttyS0] enabled
[2020-02-05 00:37:48] atmel_usart_serial.1.auto: ttyS2 at MMIO 0xfff90000 (irq = 23, base_baud = 8333333) is a ATMEL_SERIAL
[2020-02-05 00:37:48] SCSI Media Changer driver v0.25
[2020-02-05 00:37:48] atmel_nand 40000000.nand: Using dma0chan0 for DMA transfers.
[2020-02-05 00:37:48] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xaa
[2020-02-05 00:37:48] nand: Micron MT29F2G08ABBEAHC
[2020-02-05 00:37:48] nand: 256 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[2020-02-05 00:37:48] Scanning device for bad blocks
[2020-02-05 00:37:49] 10 ofpart partitions found on MTD device atmel_nand
[2020-02-05 00:37:49] Creating 10 MTD partitions on "atmel_nand":
[2020-02-05 00:37:49] 0x000000000000-0x000000020000 : "at91bootstrap"
[2020-02-05 00:37:49] 0x000000040000-0x0000000c0000 : "U-boot"
[2020-02-05 00:37:49] 0x000000180000-0x000000200000 : "U-Boot Env"
[2020-02-05 00:37:49] 0x000000200000-0x000000600000 : "kernel"
[2020-02-05 00:37:49] 0x000000800000-0x000005000000 : "rootfs"
[2020-02-05 00:37:49] 0x000005000000-0x000006e00000 : "application"
[2020-02-05 00:37:49] 0x000006e00000-0x000007200000 : "kernel_slot1"
[2020-02-05 00:37:49] 0x000007400000-0x00000bc00000 : "rootfs_slot1"
[2020-02-05 00:37:49] 0x00000bc00000-0x00000da00000 : "application_slot1"
[2020-02-05 00:37:49] 0x00000da00000-0x00000e200000 : "relfs"
[2020-02-05 00:37:49] atmel_spi fffa4000.spi: can not allocate dma coherent memory
[2020-02-05 00:37:49] ad_dpot spi32766.1: ad5160 256-Position Digital Potentiometer registered
[2020-02-05 00:37:49] atmel_spi fffa4000.spi: Atmel SPI Controller version 0x210 at 0xfffa4000 (irq 30)
[2020-02-05 00:37:49] libphy: Fixed MDIO Bus: probed
[2020-02-05 00:37:49] libphy: MACB_mii_bus: probed
[2020-02-05 00:37:49] Davicom DM9161A fffbc000.ethernet-ffffffff:00: attached PHY driver [Davicom DM9161A] (mii_bus:phy_addr=fffbc000.ethernet-ffffffff:00, irq=-1)
[2020-02-05 00:37:49] macb fffbc000.ethernet eth0: Cadence MACB rev 0x0001010c at 0xfffbc000 irq 24 (00:0b:d8:00:00:00)
[2020-02-05 00:37:49] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[2020-02-05 00:37:49] ohci-platform: OHCI generic platform driver
[2020-02-05 00:37:49] ohci-atmel: OHCI Atmel driver
[2020-02-05 00:37:49] at91_ohci 700000.ohci: failed to find sfr node
[2020-02-05 00:37:49] at91_ohci 700000.ohci: USB Host Controller
[2020-02-05 00:37:49] at91_ohci 700000.ohci: new USB bus registered, assigned bus number 1
[2020-02-05 00:37:49] at91_ohci 700000.ohci: irq 31, io mem 0x00700000
[2020-02-05 00:37:49] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
[2020-02-05 00:37:49] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[2020-02-05 00:37:49] usb usb1: Product: USB Host Controller
[2020-02-05 00:37:49] usb usb1: Manufacturer: Linux 4.9.127- ISC uImage V1.30 ohci_hcd
[2020-02-05 00:37:49] usb usb1: SerialNumber: at91
[2020-02-05 00:37:49] hub 1-0:1.0: USB hub found
[2020-02-05 00:37:49] hub 1-0:1.0: 2 ports detected
[2020-02-05 00:37:49] usbcore: registered new interface driver usblp
[2020-02-05 00:37:49] usbcore: registered new interface driver usb-storage
[2020-02-05 00:37:49] udc-core: couldn't find an available UDC - added [printer] to list of pending drivers
[2020-02-05 00:37:49] rtc-pcf8563 0-0051: rtc core: registered rtc-pcf8563 as rtc0
[2020-02-05 00:37:49] i2c /dev entries driver
[2020-02-05 00:37:49] DSX: client flags = 0, addr = 70, name = pca9547, adapter name = AT91
[2020-02-05 00:37:49] i2c i2c-0: Added multiplexed i2c bus 2
[2020-02-05 00:37:49] i2c i2c-0: Added multiplexed i2c bus 3
[2020-02-05 00:37:49] i2c i2c-0: Added multiplexed i2c bus 4
[2020-02-05 00:37:49] i2c i2c-0: Added multiplexed i2c bus 5
[2020-02-05 00:37:49] i2c i2c-0: Added multiplexed i2c bus 6
[2020-02-05 00:37:49] i2c i2c-0: Added multiplexed i2c bus 7
[2020-02-05 00:37:49] random: fast init done
[2020-02-05 00:37:49] i2c i2c-0: Added multiplexed i2c bus 8
[2020-02-05 00:37:49] i2c i2c-0: Added multiplexed i2c bus 9
[2020-02-05 00:37:49] pca954x 0-0070: registered 8 multiplexed busses for I2C mux pca9547
[2020-02-05 00:37:49] AT91: Starting after user reset
[2020-02-05 00:37:49] at91sam9_wdt: enabled (heartbeat=15 sec, nowayout=0)
[2020-02-05 00:37:49] softdog: initialized. soft_noboot=0 soft_margin=60 sec soft_panic=0 (nowayout=0)
[2020-02-05 00:37:49] device-mapper: ioctl: 4.35.0-ioctl (2016-06-23) initialised
[2020-02-05 00:37:49] atmel_mci fff80000.mmc: version: 0x410
[2020-02-05 00:37:49] atmel_mci fff80000.mmc: using dma0chan1 for DMA transfers
[2020-02-05 00:37:49] atmel_mci fff80000.mmc: Atmel MCI controller at 0xfff80000 irq 29, 1 slots
[2020-02-05 00:37:49] iio iio:device0: Resolution used: 10 bits
[2020-02-05 00:37:49] iio iio:device0: ADC Touch screen is disabled.
[2020-02-05 00:37:49] Netfilter messages via NETLINK v0.30.
[2020-02-05 00:37:49] nf_conntrack version 0.5.0 (2048 buckets, 8192 max)
[2020-02-05 00:37:49] ctnetlink v0.93: registering with nfnetlink.
[2020-02-05 00:37:49] nf_tables: (c) 2007-2009 Patrick McHardy
[2020-02-05 00:37:49] nf_tables_compat: (c) 2012 Pablo Neira Ayuso
[2020-02-05 00:37:49] ipip: IPv4 and MPLS over IPv4 tunneling driver
[2020-02-05 00:37:49] ip_tables: (C) 2000-2006 Netfilter Core Team
[2020-02-05 00:37:49] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully
[2020-02-05 00:37:49] arp_tables: arp_tables: (C) 2002 David S. Miller
[2020-02-05 00:37:49] NET: Registered protocol family 17
[2020-02-05 00:37:49] Key type encrypted registered
[2020-02-05 00:37:49] ubi0: default fastmap pool size: 25
[2020-02-05 00:37:49] ubi0: default fastmap WL pool size: 12
[2020-02-05 00:37:49] ubi0: attaching mtd7
[2020-02-05 00:37:49] mmc0: host does not support reading read-only switch, assuming write-enable
[2020-02-05 00:37:49] mmc0: new high speed SDHC card at address 1234
[2020-02-05 00:37:49] mmcblk0: mmc0:1234 SA04G 3.64 GiB
[2020-02-05 00:37:49] ubi0: scanning is finished
[2020-02-05 00:37:49] usb 1-2: new full-speed USB device number 2 using at91_ohci
[2020-02-05 00:37:49] ubi0: attached mtd7 (name "rootfs_slot1", size 72 MiB)
[2020-02-05 00:37:49] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 129024 bytes
[2020-02-05 00:37:49] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 512
[2020-02-05 00:37:49] ubi0: VID header offset: 512 (aligned 512), data offset: 2048
[2020-02-05 00:37:49] ubi0: good PEBs: 576, bad PEBs: 0, corrupted PEBs: 0
[2020-02-05 00:37:49] ubi0: user volume: 1, internal volumes: 1, max. volumes count: 128
[2020-02-05 00:37:49] ubi0: max/mean erase counter: 24/7, WL threshold: 4096, image sequence number: 1897240242
[2020-02-05 00:37:49] ubi0: available PEBs: 7, total reserved PEBs: 569, PEBs reserved for bad PEB handling: 10
[2020-02-05 00:37:49] ubi0: background thread "ubi_bgt0d" started, PID 83
[2020-02-05 00:37:49] at91_i2c fff84000.i2c: overrun while reading
[2020-02-05 00:37:49] rtc-pcf8563 0-0051: pcf8563_read_block_data: read error
[2020-02-05 00:37:49] rtc-pcf8563 0-0051: hctosys: unable to read the hardware clock
[2020-02-05 00:37:50] UBIFS (ubi0:0): recovery needed
[2020-02-05 00:37:50] UBIFS (ubi0:0): recovery deferred
[2020-02-05 00:37:50] UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0, name "rootfs", R/O mode
[2020-02-05 00:37:50] UBIFS (ubi0:0): LEB size: 129024 bytes (126 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[2020-02-05 00:37:50] UBIFS (ubi0:0): FS size: 69931008 bytes (66 MiB, 542 LEBs), journal size 9033728 bytes (8 MiB, 71 LEBs)
[2020-02-05 00:37:50] UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
[2020-02-05 00:37:50] UBIFS (ubi0:0): media format: w4/r0 (latest is w4/r0), UUID 577D54EB-7944-48CF-B0E3-4AE0E39D99DB, small LPT model
[2020-02-05 00:37:50] UBIFS (ubi0:0): full atime support is enabled.
[2020-02-05 00:37:50] VFS: Mounted root (ubifs filesystem) readonly on device 0:13.
[2020-02-05 00:37:50] devtmpfs: mounted
[2020-02-05 00:37:50] Freeing unused kernel memory: 200K
[2020-02-05 00:37:50] This architecture does not have kernel memory protection.
[2020-02-05 00:37:50] usb 1-2: New USB device found, idVendor=0930, idProduct=6545
[2020-02-05 00:37:50] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[2020-02-05 00:37:50] usb 1-2: Product: DataTraveler 2.0
[2020-02-05 00:37:50] usb 1-2: Manufacturer: Kingston
[2020-02-05 00:37:50] usb 1-2: SerialNumber: 408D5CBF5F0AB271F96807C5
[2020-02-05 00:37:50] usb-storage 1-2:1.0: USB Mass Storage device detected
[2020-02-05 00:37:50] scsi host0: usb-storage 1-2:1.0
[2020-02-05 00:37:50] INIT: version 2.88 booting
[2020-02-05 00:37:51] Starting udev
[2020-02-05 00:37:51] scsi 0:0:0:0: Direct-Access Kingston DataTraveler 2.0 PMAP PQ: 0 ANSI: 6
[2020-02-05 00:37:51] sd 0:0:0:0: Attached scsi generic sg0 type 0
[2020-02-05 00:37:51] sd 0:0:0:0: [sda] 30240768 512-byte logical blocks: (15.5 GB/14.4 GiB)
[2020-02-05 00:37:51] sd 0:0:0:0: [sda] Write Protect is off
[2020-02-05 00:37:51] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[2020-02-05 00:37:51] starting version 234
[2020-02-05 00:37:51] sda: sda1
[2020-02-05 00:37:51] sd 0:0:0:0: [sda] Attached SCSI removable disk
[2020-02-05 00:37:57] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[2020-02-05 00:38:18] RomBOOT
[2020-02-05 00:38:18] reset: User reset
blue_z
Location: USA
Posts: 2033
Joined: Thu Apr 19, 2007 10:15 pm

Re: continuous user reset with linux 4.9.127

Thu Feb 06, 2020 2:56 am

vchandapur wrote: we have added in AT91 boot strap code to know the reset reason by reading reset reg.
So you do have the source code that generates both reports from the same register that do not agree!
You have yet to explain this discrepancy of different reports in your initial post.
vchandapur wrote: RomBOOT
reset: User reset
...
[2020-02-03 07:37:04] AT91: Starting after software reset
I'm inclined to disbelieve that the reset status register reports a user reset and changes its state to then indicate a software reset.
Is the code that you "added" interpreting the status register correctly?
Or is that a bogus log that has been doctored to show a fake cycle?

Your second boot log adds a new wrinkle (besides resetting at a different point).
This log does report that
[2020-02-05 00:37:49] AT91: Starting after user reset
whereas the first log reported a software reset.

If you're really (only) seeing spontaneous user resets, then I would suspect that there's a hardware issue.
If you are seeing spontaneous software resets, then I would be inclined to look for a software issue (but that seems implausible).
IMO you need to determine exactly which type of reset is occurring in order to efficiently "debug further".

vchandapur wrote: We are using a C# based application on this custom hardware, ...
The possibility of userspace triggering a reset should be nil with a secure OS such as Linux.
Are there modifications and/or custom drivers in your kernel?

vchandapur wrote: We see the board being reset when the linux boot process reaches run level 3 (and majority when systemd-udevd is about to complete.)
That is not what I see in the second log that you posted; that log contradicts that entire description.
You do not seem to have an accurate description of symptoms.
In my experience inconsistent board behavior tends to be caused by hardware malfunctions.

Regards
vchandapur
Posts: 5
Joined: Fri May 11, 2018 3:31 pm

Re: continuous user reset with linux 4.9.127

Thu Feb 06, 2020 1:40 pm

blue_z wrote:
Thu Feb 06, 2020 2:56 am
So you do have the source code that generates both reports from the same register that do not agree!
You have yet to explain this discrepancy of different reports in your initial post.
vchandapur wrote: RomBOOT
reset: User reset
...
[2020-02-03 07:37:04] AT91: Starting after software reset
Apologies for the confusion created because of the two reset types quoted. In the first log, there was indeed a software reset. There aren't any discrepancies wrt the different resets that can happen, we have added the debug statements within at91bootstrap code to print the reason for the reset based on reset register value.
I'm inclined to disbelieve that the reset status register reports a user reset and changes its state to then indicate a software reset.
Is the code that you "added" interpreting the status register correctly?
Or is that a bogus log that has been doctored to show a fake cycle?
Again, sorry for the confusion created from the first post. Here is the code snippet that we have added in bootstrap.

Code: Select all

    
static int getResetCause(void)
{
    unsigned int reg;

    reg = readl(AT91C_BASE_RSTC + RSTC_RSR);
    return (reg &= AT91C_RSTC_RSTTYP);
}
<< snip>>

    switch(getResetCause())
    {
        case AT91C_RSTC_RSTTYP_GENERAL:
            usart_puts("reset: General reset (PowerOn)\n");
            break;
        case AT91C_RSTC_RSTTYP_WAKEUP:
            usart_puts("reset: Wakeup reset \n");
            break;
        case AT91C_RSTC_RSTTYP_WATCHDOG:
            usart_puts("reset: Watchdog reset \n");
            break;
        case AT91C_RSTC_RSTTYP_SOFTWARE:
            usart_puts("reset: Software reset \n");
            break;
        case AT91C_RSTC_RSTTYP_USER:
            usart_puts("reset: User reset \n");
            break;
        default:
            usart_puts("reset: Unknown reset\n");
            break;
    }
Your second boot log adds a new wrinkle (besides resetting at a different point).
This log does report that
[2020-02-05 00:37:49] AT91: Starting after user reset
whereas the first log reported a software reset.
<< snip >>
If you're really (only) seeing spontaneous user resets, then I would suspect that there's a hardware issue.
If you are seeing spontaneous software resets, then I would be inclined to look for a software issue (but that seems implausible).
IMO you need to determine exactly which type of reset is occurring in order to efficiently "debug further".
Yes, we do see only spontaneous user resets on the board and happens randomly at random places. You are correct in your understanding that there were no spontaneous software resets, we didn't reach to the point where our application really did start on this board.
vchandapur wrote: We are using a C# based application on this custom hardware, ...
The possibility of userspace triggering a reset should be nil with a secure OS such as Linux.
Are there modifications and/or custom drivers in your kernel?
Yes, we have added 4- bit ECC support in the nand driver code, besides that we have modified the fbtft frame buffer driver code to suite our display device requirements. However the fbtft driver is compiled as module and gets inserted just before the application starts. Other than these two, rest all peripherals are as per AT91SAM9M10G45 reference board.
vchandapur wrote: We see the board being reset when the linux boot process reaches run level 3 (and majority when systemd-udevd is about to complete.)
That is not what I see in the second log that you posted; that log contradicts that entire description.
You do not seem to have an accurate description of symptoms.
Again, apologies for not having the correct log file which has all the user reset cases captured. We do see the board really gets a user reset randomly.
In my experience inconsistent board behavior tends to be caused by hardware malfunctions.
We do believe so, however we right now do not have an exact reason as to which of these peripherals is actually causing the reset.
Best regards,
Vishwanath.
blue_z
Location: USA
Posts: 2033
Joined: Thu Apr 19, 2007 10:15 pm

Re: continuous user reset with linux 4.9.127

Fri Feb 07, 2020 11:16 pm

"User reset" is caused by the NRST input.
I would proceed with the hypothesis that some other signal is shorted with NRST.
Perform test(s) to prove or disprove the hypothesis.

One quick test to try is modifying the RSTC_MR register in U-Boot before the kernel is loaded/run.
Clear the URSTEN bit to zero and set the URSTIEN bit to one, so that any NRST input will cause an (unhandled) interrupt instead of a reset.
You'll also get a stack trace, which might indicate what code was executing.

Regards

Return to “LINUX”

Who is online

Users browsing this forum: Google [Bot] and 9 guests