"Cannot allocate memory" - Only 250MB of RAM...?!

david.hromas · January 21, 2019, 8:26am

After migration of my Turris 1.1 to SD card per Turris Documentation everything seemed to work fine (except for the current Foris error AttributeError("'ForisState' object has no attribute 'password_set'",) - SW bugs discussion - Turris forum), but then noticed some processes fail with “out of memory” or “cannot allocate memory”.

Discovered Turris reports just 256MB of RAM instead of 2GB.

Tried removing the RAM module multiple times, no change.
Tried booting from flash (without SD) card and again just 256MB of RAM and the system was very slow.

Tried booting without the RAM module (to check whether the 256MB is some onboard memory), but that did not work at all.

Any chance this could be a SW issue? Or should I rather go and buy a new RAM module?

Update: Tried replacing the RAM module (2 different modules) and still getting only 252868KB of RAM. HW issue of Turris? Software configuration? Any thoughts?

Thanks.

root@turris:~# free
             total       used       free     shared    buffers     cached
Mem:        252868     242236      10632       3084        124      85392
-/+ buffers/cache:     156720      96148
Swap:            0          0          0

Excerpt from /var/log/messages:

2019-01-21 08:24:32 notice syslog-ng[7917]: syslog-ng starting up; version='3.19.1'
2019-01-21 08:24:32 emerg syslog[]: remove dhcp script
2019-01-21 08:24:32 info kernel[]: [    0.000000] Using P2020 RDB machine description
2019-01-21 08:24:32 info kernel[]: [    0.000000] Memory CAM mapping: 256 Mb, residual: 0Mb
2019-01-21 08:24:32 info kernel[]: [    0.000000] Initializing cgroup subsys cpuset
2019-01-21 08:24:32 info kernel[]: [    0.000000] Initializing cgroup subsys cpu
2019-01-21 08:24:32 info kernel[]: [    0.000000] Initializing cgroup subsys cpuacct
2019-01-21 08:24:32 notice kernel[]: [    0.000000] Linux version 4.4.169-49a62e79231da4ac50e66e6eb4cd7945-2 (beast@build) (gcc version 4.8.3 (OpenWrt/Linaro GCC 4.8-2014.04 r47055) ) #1 SMP Tue Jan 15 09:50
:48 CET 2019
2019-01-21 08:24:32 debug kernel[]: [    0.000000] Found legacy serial port 0 for /soc@ffe00000/serial@4500
2019-01-21 08:24:32 debug kernel[]: [    0.000000]   mem=ffe04500, taddr=ffe04500, irq=0, clk=600000000, speed=0
2019-01-21 08:24:32 debug kernel[]: [    0.000000] Found legacy serial port 1 for /soc@ffe00000/serial@4600
2019-01-21 08:24:32 debug kernel[]: [    0.000000]   mem=ffe04600, taddr=ffe04600, irq=0, clk=600000000, speed=0
2019-01-21 08:24:32 info kernel[]: [    0.000000] CPU maps initialized for 1 thread per core
2019-01-21 08:24:32 debug kernel[]: [    0.000000]  (thread shift is 0)
2019-01-21 08:24:32 info kernel[]: [    0.000000] bootconsole [udbg0] enabled
2019-01-21 08:24:32 err kernel[]: [    0.000000] mpc85xx_qe_init: Could not find Quicc Engine node
2019-01-21 08:24:32 info kernel[]: [    0.000000] MPC85xx RDB board from Freescale Semiconductor
2019-01-21 08:24:32 debug kernel[]: [    0.000000] Top of RAM: 0x10000000, Total RAM: 0x10000000
2019-01-21 08:24:32 debug kernel[]: [    0.000000] Memory hole size: 0MB
2019-01-21 08:24:32 info kernel[]: [    0.000000] Zone ranges:
2019-01-21 08:24:32 info kernel[]: [    0.000000]   DMA      [mem 0x0000000000000000-0x000000000fffffff]
2019-01-21 08:24:32 info kernel[]: [    0.000000]   Normal   empty
2019-01-21 08:24:32 info kernel[]: [    0.000000]   HighMem  empty
2019-01-21 08:24:32 info kernel[]: [    0.000000] Movable zone start for each node
2019-01-21 08:24:32 info kernel[]: [    0.000000] Early memory node ranges
2019-01-21 08:24:32 info kernel[]: [    0.000000]   node   0: [mem 0x0000000000000000-0x000000000fffffff]
2019-01-21 08:24:32 info kernel[]: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000000fffffff]
2019-01-21 08:24:32 debug kernel[]: [    0.000000] On node 0 totalpages: 65536
2019-01-21 08:24:32 debug kernel[]: [    0.000000] free_area_init_node: node 0, pgdat c064a700, node_mem_map cfda0000
2019-01-21 08:24:32 debug kernel[]: [    0.000000]   DMA zone: 576 pages used for memmap
2019-01-21 08:24:32 debug kernel[]: [    0.000000]   DMA zone: 0 pages reserved
2019-01-21 08:24:32 debug kernel[]: [    0.000000]   DMA zone: 65536 pages, LIFO batch:15
2019-01-21 08:24:32 info kernel[]: [    0.000000] MMU: Allocated 1088 bytes of context maps for 255 contexts
2019-01-21 08:24:32 info kernel[]: [    0.000000] PERCPU: Embedded 10 pages/cpu @cfd89000 s9420 r8192 d23348 u40960
2019-01-21 08:24:32 debug kernel[]: [    0.000000] pcpu-alloc: s9420 r8192 d23348 u40960 alloc=10*4096
2019-01-21 08:24:32 debug kernel[]: [    0.000000] pcpu-alloc: [0] 0 [0] 1
2019-01-21 08:24:32 info kernel[]: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 64960
2019-01-21 08:24:32 notice kernel[]: [    0.000000] Kernel command line: root=/dev/mmcblk0p2 rootwait rw rootfstype=btrfs rootflags=subvol=@,commit=5 console=ttyS0,115200
2019-01-21 08:24:32 info kernel[]: [    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
2019-01-21 08:24:32 info kernel[]: [    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
2019-01-21 08:24:32 info kernel[]: [    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
2019-01-21 08:24:32 notice kernel[]: [    0.000000] Sorting __ex_table...
2019-01-21 08:24:32 info kernel[]: [    0.000000] Memory: 186720K/262144K available (5008K kernel code, 176K rwdata, 1092K rodata, 196K init, 259K bss, 75424K reserved, 0K cma-reserved, 0K highmem)
2019-01-21 08:24:32 info kernel[]: [    0.000000] Kernel virtual memory layout:
2019-01-21 08:24:32 info kernel[]: [    0.000000]   * 0xfffbf000..0xfffff000  : fixmap
2019-01-21 08:24:32 info kernel[]: [    0.000000]   * 0xffc00000..0xffe00000  : highmem PTEs
2019-01-21 08:24:32 info kernel[]: [    0.000000]   * 0xffbfd000..0xffc00000  : early ioremap
2019-01-21 08:24:32 info kernel[]: [    0.000000]   * 0xd1000000..0xffbfd000  : vmalloc & ioremap

linker · January 28, 2019, 6:58pm

I have same problem. After factory reset and btrfs migration, I have only 256MB of memory available.

david.hromas · January 28, 2019, 9:01pm

“Glad to” hear that!
I already opened a ticket and was asked for serial debug logs, then for the boot environment listing. Haven’t heard back since then…

Serial debug log per https://doc.turris.cz/doc/cs/troubleshooting/serial_link :

root@turris:/# Stopping router Turris.
Jan 22 21:04:11 crelay[8220]: Exit crelay daemon
Jan 22 21:04:11 ucollect[12807]: Removing plugin Spoof
Jan 22 21:04:11 ucollect[12807]: Unloading plugin library

[92386.767251] reboot: Restarting system

U-Boot 2015.04-04654-gbcfb33e-dirty (Jun 26 2015 - 12:50:49)

CPU0:  P2020E, Version: 2.1, (0x80ea0021)
Core:  e500, Version: 5.1, (0x80211051)
Clock Configuration:
       CPU0:1200 MHz, CPU1:1200 MHz, 
       CCB:600  MHz,
       DDR:400  MHz (800 MT/s data rate) (Asynchronous), LBC:37.500 MHz
L1:    D-cache 32 KiB enabled
       I-cache 32 KiB enabled
wdt status 00000005 reset
       Watchdog enabled
Board: TURRIS CPLD: V4.1 PCBA: V4.0
Error, wrong i2c adapter 2 max 2 possible
in: 56
out: 0
io_config: 255
rom_loc: nor upper bank
SD/MMC : 4-bit Mode
eSPI : Enabled
I2C:   Error, wrong i2c adapter 2 max 2 possible
ready
SPI:   ready
DRAM:  Error, wrong i2c adapter 2 max 2 possible
Detected UDIMM 9905594-009.A00LF 
WARNING: Calling __hwconfig without a buffer and before environment is ready
WARNING: Calling __hwconfig without a buffer and before environment is ready
2 GiB (DDR3, 64-bit, CL=6, ECC off)
Flash: 16 MiB
L2:    512 KiB enabled
NAND:  256 MiB
MMC:   FSL_SDHC: 0
PCIe1: Root Complex of mini PCIe SLOT, no link, regs @ 0xffe0a000
PCIe1: Bus 00 - 00
PCIe2: Root Complex of PCIe SLOT, x1 gen1, regs @ 0xffe09000
  02:00.0     - 168c:0030 - Network controller
PCIe2: Bus 01 - 02
PCIe3: Root Complex of PCIe SLOT, x1 gen1, regs @ 0xffe08000
  04:00.0     - 104c:8241 - Serial bus controller
PCIe3: Bus 03 - 04
Error, wrong i2c adapter 2 max 2 possible
In:    serial
Out:   serial
Err:   serial
Net:   Phy 5 not found
PHY reset timed out
eTSEC1, eTSEC2, eTSEC3 [PRIME]
Error: eTSEC3 address not set.

Hit any key to stop autoboot:  1  0 
BOOT NAND
reading zImage
3299361 bytes read in 154 ms (20.4 MiB/s)
wdt status 00000003
reading fdt
12945 bytes read in 14 ms (902.3 KiB/s)
## Booting kernel from Legacy Image at 02100000 ...
   Image Name:   Linux-4.4.169-49a62e79231da4ac50
   Created:      2019-01-15   8:50:53 UTC
   Image Type:   PowerPC Linux Kernel Image (gzip compressed)
   Data Size:    3299297 Bytes = 3.1 MiB
   Load Address: 00000000
   Entry Point:  00000000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 02000000
   Booting using the fdt blob at 0x2000000
   Uncompressing Kernel Image ... OK
   Loading Device Tree to 03ff9000, end 03fff290 ... OK
WARNING: could not find compatible node fsl-usb2-dr
[    0.000000] Using P2020 RDB machine description
[    0.000000] Memory CAM mapping: 256 Mb, residual: 0Mb
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.169-49a62e79231da4ac50e66e6eb4cd7945-2 (beast@build) (gcc version 4.8.3 (OpenWrt/Linaro GCC 4.8-2014.04 r47055) ) #1 SMP Tue Jan 15 09:50:48 CET 2019
[    0.000000] CPU maps initialized for 1 thread per core
[    0.000000] bootconsole [udbg0] enabled
setup_arch: initmem
mpc85xx_rdb_setup_arch()
[    0.000000] mpc85xx_qe_init: Could not find Quicc Engine node
[    0.000000] MPC85xx RDB board from Freescale Semiconductor
arch: exit
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000]   Normal   empty
[    0.000000]   HighMem  empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000] MMU: Allocated 1088 bytes of context maps for 255 contexts
[    0.000000] PERCPU: Embedded 10 pages/cpu @cfd89000 s9420 r8192 d23348 u40960
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 64960
[    0.000000] Kernel command line: root=/dev/mmcblk0p2 rootwait rw rootfstype=btrfs rootflags=subvol=@,commit=5 console=ttyS0,115200
[    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] Sorting __ex_table...
[    0.000000] Memory: 186720K/262144K available (5008K kernel code, 176K rwdata, 1092K rodata, 196K init, 259K bss, 75424K reserved, 0K cma-reserved, 0K highmem)
[    0.000000] Kernel virtual memory layout:
[    0.000000]   * 0xfffbf000..0xfffff000  : fixmap
[    0.000000]   * 0xffc00000..0xffe00000  : highmem PTEs
[    0.000000]   * 0xffbfd000..0xffc00000  : early ioremap
[    0.000000]   * 0xd1000000..0xffbfd000  : vmalloc & ioremap
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS:512 nr_irqs:512 16
[    0.000000] mpic: Setting up MPIC " OpenPIC  " version 1.2 at ffe40000, max 2 CPUs
[    0.000000] mpic: ISU size: 256, shift: 8, mask: ff
[    0.000000] mpic: Initializing for 256 sources
[    0.000000] mpc85xx_rdb_pic_init: Could not find qe-ic node
[    0.000008] clocksource: timebase: mask: 0xffffffffffffffff max_cycles: 0x114c1bade8, max_idle_ns: 440795203839 ns
[    0.010275] clocksource: timebase mult[d555555] shift[24] registered
[    0.016656] pid_max: default: 32768 minimum: 301
[    0.021301] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.027842] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.035251] Initializing cgroup subsys io
[    0.039189] Initializing cgroup subsys memory
[    0.043542] Initializing cgroup subsys devices
[    0.047963] Initializing cgroup subsys freezer
[    0.052395] Initializing cgroup subsys net_cls
[    0.056828] Initializing cgroup subsys pids
[    0.061147] mpic: requesting IPIs...
[    0.065643] Brought up 2 CPUs
[    0.070647] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.080324] futex hash table entries: 512 (order: 2, 16384 bytes)
[    0.086505] xor: measuring software checksum speed
[    0.101263]    8regs     :  1472.000 MB/sec
[    0.115400]    8regs_prefetch:  1312.000 MB/sec
[    0.129884]    32regs    :  1472.000 MB/sec
[    0.144018]    32regs_prefetch:  1360.000 MB/sec
[    0.148550] xor: using function: 32regs (1472.000 MB/sec)
[    0.154263] NET: Registered protocol family 16
[    0.163678] cpuidle: using governor ladder

[    0.173927] Found FSL PCI host bridge at 0x00000000ffe08000. Firmware bus number: 0->1
[    0.181775] PCI host bridge /pcie@ffe08000 (primary) ranges:
[    0.187424]  MEM 0x00000000c0000000..0x00000000dfffffff -> 0x00000000c0000000 
[    0.194637]   IO 0x00000000ffc20000..0x00000000ffc2ffff -> 0x0000000000000000
[    0.201775] /pcie@ffe08000: PCICSRBAR @ 0xfff00000
[    0.206545] setup_pci_atmu: end of DRAM 10000000
[    0.211428] Found FSL PCI host bridge at 0x00000000ffe09000. Firmware bus number: 0->1
[    0.219270] PCI host bridge /pcie@ffe09000  ranges:
[    0.224137]  MEM 0x00000000a0000000..0x00000000bfffffff -> 0x00000000a0000000 
[    0.231351]   IO 0x00000000ffc10000..0x00000000ffc1ffff -> 0x0000000000000000
[    0.238487] /pcie@ffe09000: PCICSRBAR @ 0xfff00000
[    0.243260] setup_pci_atmu: end of DRAM 10000000
[    0.248117] Found FSL PCI host bridge at 0x00000000ffe0a000. Firmware bus number: 0->0
[    0.255957] PCI host bridge /pcie@ffe0a000  ranges:
[    0.260825]  MEM 0x0000000080000000..0x000000009fffffff -> 0x0000000080000000 
[    0.268038]   IO 0x00000000ffc00000..0x00000000ffc0ffff -> 0x0000000000000000
[    0.275175] /pcie@ffe0a000: PCICSRBAR @ 0xfff00000
[    0.279947] setup_pci_atmu: end of DRAM 10000000
[    0.293543] PCI: Probing PCI hardware
[    0.297275] fsl-pci ffe08000.pcie: PCI host bridge to bus 0000:00
[    0.303296] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
[    0.309461] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xdfffffff]
[    0.316330] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.323836] pci 0000:00:00.0: PCI bridge to [bus 01-ff]
[    0.329205] fsl-pci ffe09000.pcie: PCI host bridge to bus 0001:02
[    0.335226] pci_bus 0001:02: root bus resource [io  0x20000-0x2ffff] (bus address [0x0000-0xffff])
[    0.344173] pci_bus 0001:02: root bus resource [mem 0xa0000000-0xbfffffff]
[    0.351041] pci_bus 0001:02: root bus resource [bus 02-ff]
[    0.356767] pci 0001:02:00.0: Primary bus is hard wired to 0
[    0.362349] pci 0001:02:00.0: bridge configuration invalid ([bus 01-01]), reconfiguring
[    0.373371] pci 0001:02:00.0: PCI bridge to [bus 03-ff]
[    0.378728] fsl-pci ffe0a000.pcie: PCI host bridge to bus 0002:04
[    0.384749] pci_bus 0002:04: root bus resource [io  0x40000-0x4ffff] (bus address [0x0000-0xffff])
[    0.393696] pci_bus 0002:04: root bus resource [mem 0x80000000-0x9fffffff]
[    0.400564] pci_bus 0002:04: root bus resource [bus 04-ff]
[    0.406279] pci 0002:04:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    0.414298] pci 0002:04:00.0: PCI bridge to [bus 05-ff]
[    0.419532] PCI: Cannot allocate resource region 0 of PCI bridge 1, will remap
[    0.426686] PCI: Cannot allocate resource region 0 of device 0000:00:00.0, will remap
[    0.434502] PCI: Cannot allocate resource region 0 of device 0001:02:00.0, will remap
[    0.442326] PCI: Cannot allocate resource region 0 of device 0002:04:00.0, will remap
[    0.450183] pci 0000:00:00.0: BAR 0: no space for [mem size 0x00100000]
[    0.456755] pci 0000:00:00.0: BAR 0: failed to assign [mem size 0x00100000]
[    0.463710] pci 0000:00:00.0: PCI bridge to [bus 01]
[    0.468665] pci 0000:00:00.0:   bridge window [mem 0xc0000000-0xdfffffff]
[    0.475445] pci_bus 0000:00: Some PCI device resources are unassigned, try booting with pci=realloc
[    0.484516] pci 0001:02:00.0: BAR 0: no space for [mem size 0x00100000]
[    0.491093] pci 0001:02:00.0: BAR 0: failed to assign [mem size 0x00100000]
[    0.498051] pci 0001:03:00.0: BAR 6: assigned [mem 0xa0020000-0xa002ffff pref]
[    0.505267] pci 0001:02:00.0: PCI bridge to [bus 03]
[    0.510218] pci 0001:02:00.0:   bridge window [io  0x20000-0x2ffff]
[    0.516477] pci 0001:02:00.0:   bridge window [mem 0xa0000000-0xbfffffff]
[    0.523258] pci_bus 0001:02: Some PCI device resources are unassigned, try booting with pci=realloc
[    0.532336] pci 0002:04:00.0: BAR 0: no space for [mem size 0x00100000]
[    0.538906] pci 0002:04:00.0: BAR 0: failed to assign [mem size 0x00100000]
[    0.545860] pci 0002:04:00.0: PCI bridge to [bus 05]
[    0.550816] pci 0002:04:00.0:   bridge window [io  0x40000-0x4ffff]
[    0.557075] pci 0002:04:00.0:   bridge window [mem 0x80000000-0x9fffffff]
[    0.563856] pci_bus 0002:04: Some PCI device resources are unassigned, try booting with pci=realloc
[    0.573188] mpic-msgr ffe41400.message: Found 0 message registers
[    0.579204] mpic-msgr ffe41400.message: Of-device full name /soc@ffe00000/message@41400
[    0.587208] mpic-msgr ffe41400.message: Failed to find message register block alias
[    0.618608] raid6: int32x1  gen()   171 MB/s
[    0.639920] raid6: int32x1  xor()   218 MB/s
[    0.661281] raid6: int32x2  gen()   300 MB/s
[    0.682598] raid6: int32x2  xor()   263 MB/s
[    0.703940] raid6: int32x4  gen()   398 MB/s
[    0.725257] raid6: int32x4  xor()   289 MB/s
[    0.746617] raid6: int32x8  gen()   378 MB/s
[    0.767926] raid6: int32x8  xor()   253 MB/s
[    0.772110] raid6: using algorithm int32x4 gen() 398 MB/s
[    0.777499] raid6: .... xor() 289 MB/s, rmw enabled
[    0.782367] raid6: using intx1 recovery algorithm
[    0.788341] clocksource: Switched to clocksource timebase
[    0.794462] NET: Registered protocol family 2
[    0.799120] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[    0.806029] TCP bind hash table entries: 2048 (order: 2, 16384 bytes)
[    0.812460] TCP: Hash tables configured (established 2048 bind 2048)
[    0.818825] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.824626] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.830988] NET: Registered protocol family 1
[    0.841454] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.847377] jffs2: version 2.2 (NAND) (SUMMARY) (ZLIB) (LZO) (LZMA) (RTIME) (RUBIN) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.860063] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.867396] io scheduler noop registered
[    0.871297] io scheduler deadline registered (default)
[    0.876713] pcieport 0001:02:00.0: enabling device (0106 -> 0107)
[    0.882888] pcieport 0002:04:00.0: enabling device (0106 -> 0107)
[    0.889221] pcieport 0000:00:00.0: Signaling PME through PCIe PME interrupt
[    0.896113] pci 0000:01:00.0: Signaling PME through PCIe PME interrupt
[    0.902661] pcieport 0001:02:00.0: Signaling PME through PCIe PME interrupt
[    0.909581] pci 0001:03:00.0: Signaling PME through PCIe PME interrupt
[    0.916126] pcieport 0002:04:00.0: Signaling PME through PCIe PME interrupt
[    0.949094] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.957597] console [ttyS0] disabled
[    0.961140] serial8250.0: ttyS0 at MMIO 0xffe04500 (irq = 42, base_baud = 37500000) is a 16550A
[    0.969813] console [ttyS0] enabled
[    0.969813] console [ttyS0] enabled
[    0.976748] bootconsole [udbg0] disabled
[    0.976748] bootconsole [udbg0] disabled
[    0.985000] serial8250.0: ttyS1 at MMIO 0xffe04600 (irq = 42, base_baud = 37500000) is a 16550A
[    0.994293] console [ttyS0] disabled
[    0.997910] console [ttyS0] enabled
[    1.001805] ffe04600.serial: ttyS1 at MMIO 0xffe04600 (irq = 42, base_baud = 37500000) is a 16550
[    1.017633] brd: module loaded
[    1.021410] ef000000.nor: Found 1 x16 devices at 0x0 in 16-bit bank. Manufacturer ID 0x000001 Chip ID 0x002101
[    1.031432] Amd/Fujitsu Extended Query Table at 0x0040
[    1.036600]   Amd/Fujitsu Extended Query version 1.3.
[    1.041656] number of CFI chips: 1
[    1.045280] 6 ofpart partitions found on MTD device ef000000.nor
[    1.051341] Creating 6 MTD partitions on "ef000000.nor":
[    1.056664] 0x000000000000-0x000000020000 : "NOR (RO) DTB Image"
[    1.063285] 0x000000020000-0x0000001c0000 : "NOR (RO) Linux Kernel Image"
[    1.070621] 0x0000001c0000-0x000000340000 : "NOR (RO) JFFS2 Root File System"
[    1.078328] 0x000000340000-0x000000e40000 : "NOR (RO) NAND FW backup"
[    1.085339] 0x000000e40000-0x000000f00000 : "NOR (RW) Cert backup"
[    1.092086] 0x000000f00000-0x000001000000 : "NOR (RO) U-Boot Image"
[    1.099465] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xda
[    1.105853] nand: Micron MT29F2G08ABAEAWP
[    1.109867] nand: 256 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    1.118778] Bad block table found at page 131008, version 0x01
[    1.125380] Bad block table found at page 130944, version 0x01
[    1.131810] 4 ofpart partitions found on MTD device ff800000.flash
[    1.138005] Creating 4 MTD partitions on "ff800000.flash":
[    1.143497] 0x000000000000-0x000000200000 : "NAND (RW) DTB Image"
[    1.150187] 0x000000200000-0x000000700000 : "NAND (RW) Linux Kernel Image"
[    1.157604] 0x000000700000-0x000010000000 : "NAND (RW) JFFS2 Root File System"
[    1.165587] 0x000000000000-0x000010000000 : "rootfs-ubifs"
[    1.171823] eLBC NAND device at 0xff800000, bank 1
[    1.177090] fsl_espi ffe07000.spi: cs=0, init_csmode=0x100008
[    1.182857] fsl_espi ffe07000.spi: cs=1, init_csmode=0x100008
[    1.188619] fsl_espi ffe07000.spi: cs=2, init_csmode=0x100008
[    1.194372] fsl_espi ffe07000.spi: cs=3, init_csmode=0x100008
[    1.201187] fsl_espi ffe07000.spi: at 0xd11d2000 (irq = 59)
[    1.207222] libphy: Fixed MDIO Bus: probed
[    1.211526] libphy: Freescale PowerQUICC MII Bus: probed
[    1.219363] switch0: Atheros AR8337 rev. 2 switch registered on mdio@ffe24520
[    1.271038] fsl-gianfar ffe24000.ethernet: enabled errata workarounds, flags: 0x4
[    1.288959] fsl-gianfar ffe24000.ethernet eth0: mac: 00:00:11:22:33:44
[    1.295500] fsl-gianfar ffe24000.ethernet eth0: Running with NAPI enabled
[    1.302297] fsl-gianfar ffe24000.ethernet eth0: RX BD ring size for Q[0]: 256
[    1.309440] fsl-gianfar ffe24000.ethernet eth0: TX BD ring size for Q[0]: 256
[    1.316872] fsl-gianfar ffe25000.ethernet: enabled errata workarounds, flags: 0x4
[    1.334740] fsl-gianfar ffe25000.ethernet eth1: mac: 00:00:11:22:33:45
[    1.341282] fsl-gianfar ffe25000.ethernet eth1: Running with NAPI enabled
[    1.348078] fsl-gianfar ffe25000.ethernet eth1: RX BD ring size for Q[0]: 256
[    1.355221] fsl-gianfar ffe25000.ethernet eth1: TX BD ring size for Q[0]: 256
[    1.362441] fsl-gianfar ffe26000.ethernet: enabled errata workarounds, flags: 0x4
[    1.380303] fsl-gianfar ffe26000.ethernet eth2: mac: 00:00:00:00:00:00
[    1.386847] fsl-gianfar ffe26000.ethernet eth2: Running with NAPI enabled
[    1.393643] fsl-gianfar ffe26000.ethernet eth2: RX BD ring size for Q[0]: 256
[    1.400786] fsl-gianfar ffe26000.ethernet eth2: TX BD ring size for Q[0]: 256
[    1.408033] ucc_geth_driver: QE UCC Gigabit Ethernet Controller
[    1.414163] i2c /dev entries driver
[    1.417795] mpc-i2c ffe03000.i2c: timeout 1000000 us
[    1.425271] rtc-ds1307 0-006f: rtc core: registered mcp7940x as rtc0
[    1.431650] rtc-ds1307 0-006f: 64 bytes nvram
[    1.436197] mpc-i2c ffe03100.i2c: timeout 1000000 us
[    1.441445] booke_wdt: powerpc book-e watchdog driver loaded
[    1.447296] sdhci: Secure Digital Host Controller Interface driver
[    1.453486] sdhci: Copyright(c) Pierre Ossman
[    1.457851] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.463657] /soc@ffe00000/sdhc@2e000: voltage-ranges unspecified
[    1.469683] sdhci-esdhc ffe2e000.sdhc: No vmmc regulator found
[    1.475522] sdhci-esdhc ffe2e000.sdhc: No vqmmc regulator found
[    1.503856] mmc0: SDHCI controller on ffe2e000.sdhc [ffe2e000.sdhc] using DMA
[    1.511719] NET: Registered protocol family 10
[    1.516791] NET: Registered protocol family 17
[    1.521297] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[    1.533952] Bridge firewalling registered
[    1.537980] 8021q: 802.1Q VLAN Support v1.8
[    1.543004] BTRFS: using crc32c-generic for crc32c
[    1.549140] Btrfs loaded, integrity-checker=on
[    1.555103] rtc-ds1307 0-006f: setting system clock to 2019-01-22 20:04:20 UTC (1548187460)
[    1.564038] Waiting for root device /dev/mmcblk0p2...
[    1.578972] mmc0: new high speed SDHC card at address 0007
[    1.584824] mmcblk0: mmc0:0007 SD16G 14.5 GiB 
[    1.590451]  mmcblk0: p1 p2
[    1.671653] BTRFS: device fsid 3e7862ed-c9b6-4fc6-a9b7-b830c06cf399 devid 1 transid 4434 /dev/root
[    1.683964] BTRFS info (device mmcblk0p2): disk space caching is enabled
[    1.690686] BTRFS: has skinny extents
[    1.713452] BTRFS: detected SSD devices, enabling SSD mode
[    1.733881] VFS: Mounted root (btrfs filesystem) on device 0:13.
[    1.740137] Freeing unused kernel memory: 196K
[    1.876060] init: Console is alive
[    1.879583] init: - watchdog -
[    2.888602] usbcore: registered new interface driver usbfs
[    2.894154] usbcore: registered new interface driver hub
[    2.899526] usbcore: registered new device driver usb
[    2.906333] Button Hotplug driver version 0.4.1
[    2.912928] exFAT: Version 1.2.9
[    2.942918] JFS: nTxBlock = 1975, nTxLock = 15804
[    2.971248] SGI XFS with security attributes, no debug enabled
[    2.982790] SCSI subsystem initialized
[    2.988370] usbcore: registered new interface driver usb-storage
[    2.995128] usbcore: registered new interface driver uas
[    3.003600] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    3.010563] ehci-platform: EHCI generic platform driver
[    3.016358] ehci-fsl: Freescale EHCI Host controller driver
[    3.022005] fsl-ehci fsl-ehci.0: Freescale On-Chip EHCI Host Controller
[    3.028648] fsl-ehci fsl-ehci.0: new USB bus registered, assigned bus number 1
[    3.035946] fsl-ehci fsl-ehci.0: irq 28, io mem 0xffe22000
[    3.046860] fsl-ehci fsl-ehci.0: USB 2.0 started, EHCI 1.00
[    3.052894] hub 1-0:1.0: USB hub found
[    3.056678] hub 1-0:1.0: 1 port detected
[    3.069995] uhci_hcd: USB Universal Host Controller Interface driver
[    3.079721] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    3.084987] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    3.092558] xhci_hcd 0000:01:00.0: hcc params 0x0270f06d hci version 0x96 quirks 0x00000000
[    3.101491] hub 2-0:1.0: USB hub found
[    3.105290] hub 2-0:1.0: 4 ports detected
[    3.109696] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    3.114946] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 3
[    3.122429] usb usb3: We don't know the algorithms for LPM for this host, disabling LPM.
[    3.130927] hub 3-0:1.0: USB hub found
[    3.134715] hub 3-0:1.0: 4 ports detected
[    3.361857] usb 1-1: new high-speed USB device number 2 using fsl-ehci
[    3.482547] hub 1-1:1.0: USB hub found
[    3.486441] hub 1-1:1.0: 2 ports detected
[    3.883150] init: - preinit -
ifconfig: SIOCSIFFLAGS: Cannot assign requested address
sendto(): Network is unre[    4.559263] random: mktemp: uninitialized urandom read (6 bytes read, 79 bits of entropy available)
achable
[    4.570323] random: mktemp: uninitialized urandom read (6 bytes read, 79 bits of entropy available)
[    4.581223] random: mktemp: uninitialized urandom read (6 bytes read, 79 bits of entropy available)
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
[    5.492967] usb 3-1: new SuperSpeed USB device number 2 using xhci_hcd
[    5.514247] usb-storage 3-1:1.0: USB Mass Storage device detected
[    5.520899] random: procd: uninitialized urandom read (4 bytes read, 82 bits of entropy available)
[    5.522951] scsi host0: usb-storage 3-1:1.0
[    6.528649] scsi 0:0:0:0: Direct-Access     ST380011 A                     PQ: 0 ANSI: 7
[    6.537797] sd 0:0:0:0: [sda] 156301488 512-byte logical blocks: (80.0 GB/74.5 GiB)
[    6.546943] sd 0:0:0:0: [sda] Write Protect is off
[    6.553185] sd 0:0:0:0: [sda] No Caching mode page found
[    6.558511] sd 0:0:0:0: [sda] Assuming drive cache: write through
[    6.587686]  sda: sda1 sda2 sda3 sda4
[    6.595318] sd 0:0:0:0: [sda] Attached SCSI disk
[    8.279393] mount_root: mounting /dev/root
[   12.130489] BTRFS info (device mmcblk0p2): disk space caching is enabled
[   12.137400] mount_root: loading kmods from internal overlay
[   12.561504] random: nonblocking pool is initialized
[   12.616631] block: attempting to load /etc/config/fstab
[   12.625739] block: extroot: not configured
ifconfig: SIOCSIFFLAGS: Cannot ass[   12.637181] procd: - early -
ign r[   12.640473] procd: - watchdog -
equested address
Failed to connect to ubus
Failed to connect to ubus
[   13.287299] procd: - ubus -
[   14.293289] procd: - init -
Please press Enter to activate this console.
[   14.410113] NET: Registered protocol family 38
[   14.425330] Netfilter messages via NETLINK v0.30.
[   14.438789] md: linear personality registered for level -1
[   14.444977] md: raid0 personality registered for level 0
[   14.451566] md: raid1 personality registered for level 1
[   14.458349] md: raid10 personality registered for level 10
[   14.468552] md: raid6 personality registered for level 6
[   14.473964] md: raid5 personality registered for level 5
[   14.479295] md: raid4 personality registered for level 4
[   14.488448] FS-Cache: Loaded
[   14.492212] md: multipath personality registered for level -4
[   14.500200] NET: Registered protocol family 8
[   14.504583] NET: Registered protocol family 20
[   14.514647] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com
[   14.529231] Key type dns_resolver registered
[   14.538311] NET: Registered protocol family 33
[   14.542785] Key type rxrpc registered
[   14.546450] Key type rxrpc_s registered
[   14.554702] kAFS: Red Hat AFS client v0.1 registering.
[   14.559926] FS-Cache: Netfs 'afs' registered for caching
[   14.601110] RPC: Registered named UNIX socket transport module.
[   14.607075] RPC: Registered udp transport module.
[   14.611814] RPC: Registered tcp transport module.
[   14.616595] RPC: Registered tcp NFSv4.1 backchannel transport module.
[   14.646440] NFS: Registering the id_resolver key type
[   14.651540] Key type id_resolver registered
[   14.655749] Key type id_legacy registered
[   14.660776] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[   14.671265] ntfs: driver 2.1.32 [Flags: R/O MODULE].
[   14.680944] RxRPC: Registered security type 2 'rxkad'
[   14.705260] tun: Universal TUN/TAP device driver, 1.6
[   14.710363] tun: (C) 1999-2004 Max Krasnyansky 
[   14.722387] sit: IPv6 over IPv4 tunneling driver
[   14.729957] gre: GRE over IPv4 demultiplexor driver
[   14.735641] ip_gre: GRE over IPv4 tunneling driver
[   14.752592] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[   14.762230] PPP generic driver version 2.4.2
[   14.768938] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   14.785396] nf_conntrack version 0.5.0 (3951 buckets, 15804 max)
[   14.910593] ip_set: protocol 6
[   15.423886] cryptodev: driver 1.8 loaded.
[   15.465334] Loading modules backported from Linux version wt-2017-01-31-0-ge882dff19e7f
[   15.473372] Backport generated by backports.git backports-20160324-13-g24da7d3c
[   15.639070] sd 0:0:0:0: Attached scsi generic sg0 type 0
[   15.853477] u32 classifier
[   15.856202]     input device check on
[   15.859876]     Actions configured
[   15.923416] Mirror/redirect action on
[   16.047383] GACT probability on
[   16.539439] netem: version 1.3
[   16.741256] fuse init (API version 7.23)
[   16.796661] usbcore: registered new interface driver cdc_acm
[   16.802338] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[   16.812259] usbcore: registered new interface driver cdc_wdm
[   16.823427] Ebtables v2.0 registered
[   16.832085] ip_tables: (C) 2000-2006 Netfilter Core Team
[   16.858241] ctnetlink v0.93: registering with nfnetlink.
[   16.906355] nfnl_acct: registering with nfnetlink.
[   16.913340] PPP MPPE Compression module registered
[   16.919475] NET: Registered protocol family 24
[   16.927014] usbcore: registered new interface driver ums-alauda
[   16.935028] usbcore: registered new interface driver ums-cypress
[   16.943470] usbcore: registered new interface driver ums-datafab
[   16.951423] usbcore: registered new interface driver ums-freecom
[   16.959762] usbcore: registered new interface driver ums-isd200
[   16.967980] usbcore: registered new interface driver ums-jumpshot
[   16.976281] usbcore: registered new interface driver ums-karma
[   16.984937] usbcore: registered new interface driver ums-sddr09
[   16.993291] usbcore: registered new interface driver ums-sddr55
[   17.001627] usbcore: registered new interface driver ums-usbat
[   17.020195] usbcore: registered new interface driver usbserial
[   17.026156] usbcore: registered new interface driver usbserial_generic
[   17.032760] usbserial: USB Serial support registered for generic
[   17.061590] xt_time: kernel timezone is -0000
[   17.071959] usbcore: registered new interface driver cdc_ether
[   17.081102] usbcore: registered new interface driver ftdi_sio
[   17.086949] usbserial: USB Serial support registered for FTDI USB Serial Device
[   17.111026] usbcore: registered new interface driver qmi_wwan
[   17.119390] usbcore: registered new interface driver rndis_host
[   17.264978] ieee80211 phy0: Atheros AR9300 Rev:3 mem=0xd4000000, irq=37
[   17.275060] usbcore: registered new interface driver option
[   17.280758] usbserial: USB Serial support registered for GSM modem (1-port)
Jan 22 21:04:41 haveged: haveged starting up
Jan 22 21:04:41 haveged: haveged: ver: 1.9.4; arch: ppc; vend: ; build: (gcc 4.8.3 V); collect: 128K
Jan 22 21:04:41 haveged: haveged: cpu: (); data: 32K (P); inst: 32K (P); idx: 15/40; sz: 30904/80288
Jan 22 21:04:41 haveged: haveged: fills: 0, generated: 0 
Jan 22 21:04:57 ucollect[7363]: Initializing emergency signal handlers
Jan 22 21:04:57 ucollect[7363]: Creating a main loop
Jan 22 21:04:57 ucollect[7363]: Creating uplink
Jan 22 21:04:57 ucollect[7363]: Initializing PCAP (in) on lo
Jan 22 21:04:57 ucollect[7363]: Initializing PCAP (out) on lo
Jan 22 21:04:57 ucollect[7363]: Loading plugin library libplugin_ucollect_badconf_25.so
Jan 22 21:04:57 ucollect[7363]: Installing plugin Badconf with api version 0
Jan 22 21:04:57 ucollect[7363]: Loading plugin library libplugin_ucollect_bandwidth_22.so
Jan 22 21:04:57 ucollect[7363]: Installing plugin Bandwidth with api version 0
Jan 22 21:04:57 ucollect[7363]: Loading plugin library libplugin_ucollect_count_28.so
Jan 22 21:04:57 ucollect[7363]: Installing plugin Count with api version 0
Jan 22 21:04:57 ucollect[7363]: Loading plugin library libplugin_ucollect_fake_23.so
Jan 22 21:04:57 ucollect[7363]: Installing plugin Fake with api version 0
Jan 22 21:04:57 ucollect[7363]: Loading plugin library libplugin_ucollect_flow_33.so
Jan 22 21:04:57 ucollect[7363]: Installing plugin Flow with api version 2
Jan 22 21:04:57 ucollect[7363]: Loading plugin library libpluglib_ucollect_diffstore_1.2.so
Jan 22 21:04:57 ucollect[7363]: Loading plugin library libplugin_ucollect_fwup_12.so
Jan 22 21:04:57 ucollect[7363]: Installing plugin Fwup with api version 2
Jan 22 21:04:57 ucollect[7363]: Loading plugin library libpluglib_ucollect_diffstore_1.2.so
Jan 22 21:04:57 ucollect[7363]: Loading plugin library libplugin_ucollect_refused_13.so
Jan 22 21:04:57 ucollect[7363]: Installing plugin Refused with api version 0
Jan 22 21:04:57 ucollect[7363]: Loading plugin library libplugin_ucollect_sniff_19.so
Jan 22 21:04:57 ucollect[7363]: Installing plugin Sniff with api version 0
Jan 22 21:04:57 ucollect[7363]: Loading plugin library libplugin_ucollect_spoof_8.so
Jan 22 21:04:57 ucollect[7363]: Installing plugin Spoof with api version 0
Jan 22 21:04:57 ucollect[7363]: Changing remote uplink address to api.turris.cz:5679
Jan 22 21:04:57 ucollect[7363]: Failed to resolve uplink api.turris.cz:5679: Name or service not known
Jan 22 21:04:57 ucollect[7363]: Didn't get any V4 address in resolution of api.turris.cz
Jan 22 21:04:57 ucollect[7363]: Didn't get any V6 address in resolution of api.turris.cz
Jan 22 21:04:57 ucollect[7363]: Sending login credentials disabled
Jan 22 21:04:57 ucollect[7363]: Sending login credentials disabled
Jan 22 21:04:57 ucollect[7363]: Sending login credentials disabled
Jan 22 21:04:57 ucollect[7363]: Sending login credentials disabled
Jan 22 21:04:57 ucollect[7363]: Sending login credentials disabled
Jan 22 21:04:57 ucollect[7363]: Sending login credentials disabled
Jan 22 21:04:57 ucollect[7363]: Unloading plugin library
Jan 22 21:04:57 ucollect[7363]: Running the main loop
Jan 22 21:04:57 ucollect[7363]: Reconnecting to api.turris.cz:5679 now
Jan 22 21:04:57 ucollect[7363]: Socat started
Jan 22 21:04:57 ucollect[7363]: Failed to resolve uplink api.turris.cz:5679: Name or service not known
Jan 22 21:04:57 ucollect[7363]: Didn't get any V4 address in resolution of api.turris.cz
Jan 22 21:04:57 ucollect[7363]: Didn't get any V6 address in resolution of api.turris.cz
Jan 22 21:04:57 ucollect[7363]: Error from socat: 2019/01/22 21:04:57 socat[7399] E getaddrinfo("api.turris.cz", "NULL", {1,10,1,6}, {}): Name or service not known
Jan 22 21:04:57 ucollect[7363]: Remote closed the uplink api.turris.cz:5679, reconnecting
Jan 22 21:04:57 ucollect[7363]: epoll_wait on 3 interrupted, retry
Jan 22 21:04:57 ucollect[7363]: Reconnecting to api.turris.cz:5679 now
Jan 22 21:04:57 ucollect[7363]: Reconnecting too often, waiting a little while
Jan 22 21:04:57 ucollect[7363]: Going to reconnect to api.turris.cz:5679 after 2 seconds
Jan 22 21:04:59 ucollect[7363]: Reconnecting to api.turris.cz:5679 now
Jan 22 21:04:59 ucollect[7363]: Socat started
Jan 22 21:04:59 ucollect[7363]: Failed to resolve uplink api.turris.cz:5679: Name or service not known
Jan 22 21:04:59 ucollect[7363]: Didn't get any V4 address in resolution of api.turris.cz
Jan 22 21:04:59 ucollect[7363]: Didn't get any V6 address in resolution of api.turris.cz
Jan 22 21:04:59 ucollect[7363]: Error from socat: 2019/01/22 21:04:59 socat[7811] E getaddrinfo("api.turris.cz", "NULL", {1,2,1,6}, {}): Name or service not known
Jan 22 21:04:59 ucollect[7363]: Remote closed the uplink api.turris.cz:5679, reconnecting
Jan 22 21:04:59 ucollect[7363]: epoll_wait on 3 interrupted, retry
Jan 22 21:04:59 ucollect[7363]: Reconnecting to api.turris.cz:5679 now
Jan 22 21:04:59 ucollect[7363]: Reconnecting too often, waiting a little while
Jan 22 21:04:59 ucollect[7363]: Going to reconnect to api.turris.cz:5679 after 4 seconds
Jan 22 21:05:03 ucollect[7363]: Reconnecting to api.turris.cz:5679 now
Jan 22 21:05:03 ucollect[7363]: Socat started
Jan 22 21:05:03 ucollect[7363]: Failed to resolve uplink api.turris.cz:5679: Name or service not known
Jan 22 21:05:03 ucollect[7363]: Didn't get any V4 address in resolution of api.turris.cz
Jan 22 21:05:03 ucollect[7363]: Didn't get any V6 address in resolution of api.turris.cz
Jan 22 21:05:03 ucollect[7363]: Error from socat: 2019/01/22 21:05:03 socat[7950] E getaddrinfo("api.turris.cz", "NULL", {1,10,1,6}, {}): Name or service not known
Jan 22 21:05:03 ucollect[7363]: Remote closed the uplink api.turris.cz:5679, reconnecting
Jan 22 21:05:03 ucollect[7363]: epoll_wait on 3 interrupted, retry
Jan 22 21:05:03 ucollect[7363]: Reconnecting to api.turris.cz:5679 now
Jan 22 21:05:03 ucollect[7363]: Reconnecting too often, waiting a little while
Jan 22 21:05:03 ucollect[7363]: Going to reconnect to api.turris.cz:5679 after 8 seconds

Router Turris successfully started.

Boot environment variable - I had to stop booting via serial debug line and enter command printenv:

=> printenv
arch=sandbox
backbootcmd=setexpr.b reflash *0xFFA0001F;if test $reflash -ge $reflash_timeout; then echo BOOT NOR; run norboot; else echo BOOT NAND; run ubiboot; fi
baudrate=115200
board=sandbox
board_name=sandbox
boot_a_script=load ${devtype} ${devnum}:${distro_bootpart} ${scriptaddr} ${prefix}${script}; source ${scriptaddr}
boot_extlinux=sysboot ${devtype} ${devnum}:${distro_bootpart} any ${scriptaddr} ${prefix}extlinux/extlinux.conf
boot_net_pci_enum=pci enum
boot_net_usb_start=usb start
boot_prefixes=/ /boot/
boot_script_dhcp=boot.scr.uimg
boot_scripts=boot.scr.uimg boot.scr
boot_targets=host1 host0
bootargsmmc=root=/dev/mmcblk0p2 rootwait rw rootfstype=btrfs rootflags=subvol=@,commit=5 console=ttyS0,115200
bootargsnor=root=/dev/mtdblock2 rw rootfstype=jffs2 console=ttyS0,115200
bootargsubi=root=ubi0:rootfs rootfstype=ubifs ubi.mtd=9,2048 rootflags=chk_data_crc rw console=ttyS0,115200
bootcmd=setexpr.b reflash *0xFFA0001F;if test $reflash -ge $reflash_timeout; then echo BOOT NOR; run norboot; else echo BOOT NAND; mmc rescan; if fatload mmc 0:1 $nandbootaddr zImage; then run mmcboot; else run ubiboot; fi; fi
bootcmd_host0=setenv devnum 0; run host_boot
bootcmd_host1=setenv devnum 1; run host_boot
bootdelay=1
bootfile=uImage
bootm_size=0x10000000
consoledev=ttyS0
cpu=sandbox
distro_bootcmd=setenv scsi_need_init; for target in ${boot_targets}; do run bootcmd_${target}; done
eth1addr=00:00:11:22:33:45
eth3addr=00:00:11:22:33:46
eth5addr=00:00:11:22:33:47
ethact=eTSEC3
ethaddr=00:00:11:22:33:44
ethprime=eTSEC3
fdt_addr_r=0xc00000
fdtaddr=c00000
host_boot=if host dev ${devnum}; then setenv devtype host; run scan_dev_for_boot_part; fi
ide_boot=if ide dev ${devnum}; then setenv devtype ide; run scan_dev_for_boot_part; fi
ipaddr=1.2.3.4
jffs2nand=mtdblock9
jffs2nor=mtdblock3
kernel_addr_r=0x1000000
loadaddr=1000000
map_lowernorbank=i2c dev 1; i2c mw 18 1 02 1; i2c mw 18 3 fd 1
map_uppernorbank=i2c dev 1; i2c mw 18 1 00 1; i2c mw 18 3 fd 1
mmcboot=max6370_wdt_off; fatload mmc 0:1 $nandfdtaddr fdt; setenv bootargs $bootargsmmc; bootm $nandbootaddr - $nandfdtaddr
mtdids=nor0=ef000000.nor,nand0=nand
mtdparts=mtdparts=ef000000.nor:128k(dtb-image),1664k(kernel),1536k(root),11264k(backup),128k(cert),1024k(u-boot);nand:-(rootfs)
nandbootaddr=2100000
nandfdtaddr=2000000
netdev=eth0
nfsboot=setenv bootargs root=/dev/nfs rw nfsroot=$serverip:$rootpath ip=$ipaddr:$serverip:$gatewayip:$netmask:$hostname:$netdev:off console=$consoledev,$baudrate $othbootargs;tftp $loadaddr $bootfile;tftp $fdtaddr $fdtfile;bootm $loadaddr - $fdtaddr
norboot=max6370_wdt_off; env default -a; saveenv; setenv bootargs $bootargsnor; bootm 0xef020000 - 0xef000000
norbootaddr=ef080000
norfdtaddr=ef040000
pxefile_addr_r=0x2000
ramboot=setenv bootargs root=/dev/ram rw console=$consoledev,$baudrate $othbootargs ramdisk_size=$ramdisk_size;tftp $ramdiskaddr $ramdiskfile;tftp $loadaddr $bootfile;tftp $fdtaddr $fdtfile;bootm $loadaddr $ramdiskaddr $fdtaddr
ramdisk_addr_r=0x2000000
ramdisk_size=120000
ramdiskaddr=2000000
ramdiskfile=rootfs.ext2.gz.uboot
reflash_timeout=40
rootpath=/opt/nfsroot
sata_boot=if sata dev ${devnum}; then setenv devtype sata; run scan_dev_for_boot_part; fi
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;
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_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=0x1000
scsi_boot=run scsi_init; if scsi dev ${devnum}; then setenv devtype scsi; run scan_dev_for_boot_part; fi
scsi_init=if ${scsi_need_init}; then setenv scsi_need_init false; scsi scan; fi
stderr=serial
stdin=serial
stdout=serial
tftpflash=tftpboot $loadaddr $uboot; protect off 0xeff40000 +$filesize; erase 0xeff40000 +$filesize; cp.b $loadaddr 0xeff40000 $filesize; protect on 0xeff40000 +$filesize; cmp.b $loadaddr 0xeff40000 $filesize
ubiboot=max6370_wdt_off; setenv bootargs $bootargsubi; ubi part rootfs; ubifsmount ubi0:rootfs; ubifsload $nandfdtaddr /boot/fdt; ubifsload $nandbootaddr /boot/zImage; bootm $nandbootaddr - $nandfdtaddr
uboot=u-boot.bin
usb_boot=usb start; if usb dev ${devnum}; then setenv devtype usb; run scan_dev_for_boot_part; fi

Environment size: 4986/8188 bytes

RomanHK · January 28, 2019, 10:20pm

david.hromas · January 29, 2019, 11:30am

@RomanHK Mám v tomto návodu hledat něco poučného? Dokazuje jen to, že v srpnu asi problémy ještě nebyly. Jinak NOR mám poupdatovanou automatickou aktualizací (někdy v říjnu, nebo kdy). Factory resetu se zatím zdráhám - není jisté, že pomůže, router zatím routuje, a věřím, že se najde oprava, která nebude Factory reset vyžadovat

Mimochodem, v threadu Chybové hlášky ve Forisu je minimálně jeden další postižený, možná dva.

RomanHK · January 29, 2019, 11:49am

Domnívám se, že to bude pouze SW chyba a čekal jsem, že po úplném flash NOR se to opraví - ale jak si čtu další vlákna, opak je pravdou a právě po migraci a resetu se to rozhasí - ale pořád si myslím, že to nebude HW chyba.

Jinak jsem vlastníkem Turris Omnia, tudíž nemohu plně sloužit a proto jsem poskytl aspoň odkaz - tak pardón.

david.hromas · January 29, 2019, 12:13pm

OK, díky za vysvětlení. Nevěděl jsem, co přesně z návodu odkazujete/navrhujete, a pořád jsem hledal, co mi uniklo. Každopádně díky za snahu.
Také už věřím na čistě SW problém.

Nemáte, prosím, někdo z Turrisu 1.1 uloženy ty bootovací proměnné? Tedy výstup příkazu printenv uBootu při přerušení bootu prostřednictvím sériové konzole připojené pomocí USB kabelu…?

david.hromas · January 28, 2019, 9:07pm

Pánové, můžete zkontrolovat, kolik RAM hlásí vaše Turrisy? Viz můj problém Only 250MB of RAM...?

Nones · January 29, 2019, 8:34am

Můj Turris 1.x vidí 2GB … tak to má být, myslím

david.hromas · January 29, 2019, 9:58am

Ano, 2GB jsou správně. Ale můj Turris 1.1 po přesunu na SD kartu vidí 2GB pouze při bootu, pak už použije jen 256MB.
Proto mi teď mnoho procesů končí právě hláškou “Cannot allocate memory”. V důsledku toho nefunguje např.

update
cloud backup

A zbytek jsem ořezal na minimum, aby aspoň routování fungovalo, než se problém s pamětí vyřeší.

No a proto by mne zajímalo, kolik dostupné RAM ukazuje při běhu Turris pánům @Gorole, a @heger.m, kteří také hlásí problémy s alokací paměti…?

heger.m · January 29, 2019, 10:35am

To bude asi můj problém. Také jsem resetoval a dělal čistou instalaci. Nefunguje mi ještě pakon a síť mi celkem vypadává. Při loginu mi to vyhazuje chybové hlášení. Jak zjišťujete v ruzných stavech velikost pamětí?

commar · January 29, 2019, 10:58am

Možná hloupý dotaz, zkusil jste rozebrat router a vyndal a zase zacvaknout paměť?
Pokud jste třeba vkládal SD kartu, je potřeba paměť vyndat, třeba jde o špatný kontakt, nebo pokud máte tu možnost, zkuste paměť jinde vyzkoušet.

EDIT: kromě LuCI můžete vidět využití paměti příkazem free.

root@turris:~# free
total used free shared buffers cached
Mem: 2071500 969888 1101612 81056 13020 581204
-/+ buffers/cache: 375664 1695836
Swap: 0 0 0

david.hromas · January 29, 2019, 11:26am

Ano, velikost paměti je vidět:

v Luci
pomocí příkazu free (z konzole)

252868kB je přesně ta špatná velikost, kterou nechcete vidět.

Ohledně modulů RAM - také jsem si myslel, že jsem ho při vyndavání poškodil, ale zkusil jsem místo něj jiné dva, a chovalo se to úplně stejně.
Navíc v boot logu je vidět úspěšná detekce 2GB modulu.
Opětovným rozebíráním Turrisu a šachováním se SO-DIMMem se asi nikdo nemusí zdržovat.

Takže je to jasné, něco se nepovedlo v Turris OS, zřejmě v posledních týdnech aktualizací (dle threadu Flash systemu turris do NOR přechod na btrfs v srpnu problémy nezpůsoboval). Snad na to brzy v NICu přijdou.

Gorole · January 29, 2019, 11:48am

Hlásím ten samý problém.
Factory reset, přechod na BTRFS, problémy
Tur_10

JEŠTĚ BY BYLO FAJN TO PŘESUNOUT DO SW PROBLEMS

heger.m · January 29, 2019, 6:20pm

Mám ho rozdělaný celou dobu. Vyjmutí několikrat.

Pepe · January 29, 2019, 8:37pm

Podařilo se nám to zreprodukovat a problém velmi intenzivně řešíme. Jakmile budu mít více informací, tak dám vědět.

heger.m · January 30, 2019, 11:24am

Děkuji bylo by to fajn.

Pepe · January 31, 2019, 4:07am

Michalovi (@miska) se podařilo chybě domluvit. S chybou jste se mohli setkat pouze tehdy, když jste v nejnovějším releasu tedy 3.11.2 přecházeli na BTRFS. Oprava se bude nacházet ve verzi Turris OS 3.11.3.

V tuto chvíli je potřeba překonfigurovat konfiguraci u-bootu, kterou lze provést i přes SSH, takže není nutné připojit sériovou linku, kterou provedete následujícími příkazy:

fw_setenv bootcmd 'env default -f -a; saveenv; reset'
reboot
pkgupdate
btrfs_migrate restore

Budeme rádi, když nám dáte vědět, že vám to pomohlo i přesto, že jsme to otestovali.

david.hromas · January 31, 2019, 7:40am

Funguje! Velmi děkuji!
Jestli to dobře chápu, tak příkazy zresetují boot proměnné, čimž se nabootuje zpět z interní paměti a máme již plnou RAM. Při updatu asi přijde opravený migrační skript (nebo oprava skriptu nebyla potřeba, stačilo jen to promazání?), jehož zavolání s přepínačem restore provede jen úpravu boot proměnných, tedy především přepnutí na bootování z SD (bez opětovné migrace systému na SD).
Tím jsme tedy ve stavu, kdy běžíme z interní paměti (tedy starého systému, ale updatovali jsme ho), a příští reboot už půjde z SD, ale tam zase bude chybět update (dokud se neprovede automaticky).
Takže já jsem přidal ještě

reboot
pkgupdate

Mohu potvrdit, že i z SD už Turris naběhne s plnou RAM 2GB, update už se povedl.
Ještě mi tedy nefunguje Foris, i když při běhu z interní paměti fungoval. Stále tam mám chybu - viz AttributeError("'ForisState' object has no attribute 'password_set'",) - SW bugs discussion - Turris forum (kde zatím není žádné řešení). Zkusím se na to podívat.

Ještě jedou díky: @Pepe, @miska

Edit: Foris vyřešen, moje blbost - zakázal jsem si foris-controller, protože při nedostatku paměti padal a zůstávaly po něm v paměti procesy

Gorole · January 31, 2019, 11:22am

Skvělá práce, funguje to.

Ale ještě mi to vyhodilo hlášku po 2. restartu

UPDATE:
Další hláška: