search

benhylau / mesh-workshop

Nodes used to facilitate workshops and demos for mesh networking
GNU General Public License v3.0
35 stars 2 forks source link

Failure to apply host configs on 8 GB sdcard #4

Closed benhylau closed 6 years ago

benhylau commented 6 years ago

I have two types of SD cards: 16 GB and 8 GB. Configuration loading works if I use a 16 GB card to flash this image then apply this config. But if I use a 8 GB card, the tar.gz archived configs aren't applied. None of these are applied. This step occurs before conf.d sh scripts are executed, so it has nothing to do with the rootfs expansion with swap space.

So you will know conf.d/*.tar.gz did not apply when the AP you see is named test2 instead of something like bloor. I only got to try this on the two types of cards I have (but I tried two cards of each type) so it's not just due to a broken sdcard. I also do not have tools to probe boot to check for logs.

Note that the rootfs is a little tight on space, but I don't see how this could be affected by sdcard size:

root@bloor:~# df -h
Filesystem      Size  Used Avail Use% Mounted on
rootfs          435M  378M   58M  87% /
...

cc. @darkdrgn2k @hamishcoleman

hamishcoleman commented 6 years ago

The error you describe is not really possible - if the image fits, the image fits.. and the image is less than 8Gig.

You will need serial console logs to read the error messages

benhylau commented 6 years ago

Yes, this makes no sense to me. I wish I have another sdcard to try... or a serial console, but I am still traveling.

darkdrgn2k commented 6 years ago

Installed Rasperry 0.5 image Copied BLOOR config to vfat partition

After boot

Filesystem      Size  Used Avail Use% Mounted on
rootfs          5.8G  377M  5.4G   7% /
devtmpfs        435M     0  435M   0% /dev
tmpfs           486M     0  486M   0% /dev/shm
tmpfs           486M  6.4M  480M   2% /run
tmpfs           5.0M     0  5.0M   0% /run/lock
tmpfs           486M     0  486M   0% /sys/fs/cgroup
root@bloor:~#
    Interface wlan0
                ifindex 3
                wdev 0x1
                addr b8:27:eb:0a:5a:69
                ssid bloor
                type AP
                channel 11 (2462 MHz), width: 20 MHz, center1: 2462 MHz
                txpower 31.00 dBm
              total        used        free      shared  buff/cache   available
Mem:         994232       37604      551272      391964      405356      552732
Swap:       6143996           0     6143996
root@bloor:~#

Boot

Debian GNU/Linux 9 ramdisk-d847f107 ttyS0

ramdisk-d847f107 login: [    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.9.35-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #1014 SMP Fri Jun 30 14:47:43 BST 2017
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt:Machine model: Raspberry Pi 3 Model B Rev 1.2
[    0.000000] cma: Reserved 8 MiB at 0x3d800000
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] percpu: Embedded 14 pages/cpu @bcef8000 s25600 r8192 d23552 u57344
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 251720
[    0.000000] Kernel command line: 8250.nr_uarts=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ea00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 elevator=deadline
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 890108K/1015808K available (7168K kernel code, 484K rwdata, 2012K rodata, 1024K init, 778K bss, 117508K reserved, 8192K cma-reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xbe800000 - 0xff800000   (1040 MB)
[    0.000000]     lowmem  : 0x80000000 - 0xbe000000   ( 992 MB)
[    0.000000]     modules : 0x7f000000 - 0x80000000   (  16 MB)
[    0.000000]       .text : 0x80008000 - 0x80800000   (8160 kB)
[    0.000000]       .init : 0x80b00000 - 0x80c00000   (1024 kB)
[    0.000000]       .data : 0x80c00000 - 0x80c79014   ( 485 kB)
[    0.000000]        .bss : 0x80c7b000 - 0x80d3da64   ( 779 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] arm_arch_timer: Architected cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000010] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000027] Switching to timer-based delay loop, resolution 52ns
[    0.000332] Console: colour dummy device 80x30
[    0.001216] console [tty1] enabled
[    0.001271] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.001341] pid_max: default: 32768 minimum: 301
[    0.001704] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001746] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.002835] Disabling cpuset control group subsystem
[    0.003026] CPU: Testing write buffer coherency: ok
[    0.003094] ftrace: allocating 22390 entries in 66 pages
[    0.055744] CPU0: update cpu_capacity 1024
[    0.055811] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.055907] Setting up static identity map for 0x100000 - 0x100034
[    0.058134] CPU1: update cpu_capacity 1024
[    0.058142] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.058852] CPU2: update cpu_capacity 1024
[    0.058859] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.059524] CPU3: update cpu_capacity 1024
[    0.059531] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.059614] Brought up 4 CPUs
[    0.059785] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.059815] CPU: All CPU(s) started in HYP mode.
[    0.059841] CPU: Virtualization extensions available.
[    0.060600] devtmpfs: initialized
[    0.071441] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.071754] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.071816] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.072377] pinctrl core: initialized pinctrl subsystem
[    0.073299] NET: Registered protocol family 16
[    0.075465] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.084234] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.084283] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.084455] Serial: AMBA PL011 UART driver
[    0.086355] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.086901] uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
[    0.087263] irq: no irq domain found for /soc/aux@0x7e215000 !
[    0.156354] bcm2835-dma 3f007000.dma: DMA legacy API manager at be80f000, dmachans=0x1
[    0.158218] SCSI subsystem initialized
[    0.158401] usbcore: registered new interface driver usbfs
[    0.158503] usbcore: registered new interface driver hub
[    0.158619] usbcore: registered new device driver usb
[    0.165303] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-07-03 14:17
[    0.166604] clocksource: Switched to clocksource arch_sys_counter
[    0.213415] VFS: Disk quotas dquot_6.6.0
[    0.213526] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.213753] FS-Cache: Loaded
[    0.214017] CacheFiles: Loaded
[    0.226193] NET: Registered protocol family 2
[    0.227135] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.227272] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.227484] TCP: Hash tables configured (established 8192 bind 8192)
[    0.227597] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.227665] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.227899] NET: Registered protocol family 1
[    0.228330] RPC: Registered named UNIX socket transport module.
[    0.228362] RPC: Registered udp transport module.
[    0.228390] RPC: Registered tcp transport module.
[    0.228417] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.228692] Trying to unpack rootfs image as initramfs...
[   86.703536] Freeing initrd memory: 94908K (a9351000 - af000000)
[   86.708620] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[   86.711020] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[   86.727320] FS-Cache: Netfs 'nfs' registered for caching
[   86.728328] NFS: Registering the id_resolver key type
[   86.728385] Key type id_resolver registered
[   86.728413] Key type id_legacy registered
[   86.730882] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[   86.731029] io scheduler noop registered
[   86.731060] io scheduler deadline registered (default)
[   86.731387] io scheduler cfq registered
[   86.737139] BCM2708FB: allocated DMA memory fd910000
[   86.737193] BCM2708FB: allocated DMA channel 0 @ be80f000
[   86.742298] Console: switching to colour frame buffer device 82x26
[   86.747173] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[   86.749148] bcm2835-aux-uart 3f215040.serial: could not get clk: -517
[   86.751596] bcm2835-rng 3f104000.rng: hwrng registered
[   86.753141] vc-cma: Videocore CMA driver
[   86.754549] vc-cma: vc_cma_base      = 0x00000000
[   86.755934] vc-cma: vc_cma_size      = 0x00000000 (0 MiB)
[   86.757299] vc-cma: vc_cma_initial   = 0x00000000 (0 MiB)
[   86.758747] vc-mem: phys_addr:0x00000000 mem_base=0x3ea00000 mem_size:0x3f000000(1008 MiB)
[   86.776387] brd: module loaded
[   86.786474] loop: module loaded
[   86.787767] Loading iSCSI transport class v2.0-870.
[   86.789568] usbcore: registered new interface driver smsc95xx
[   86.790832] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[   87.020084] Core Release: 2.80a
[   87.021299] Setting default values for core params
[   87.022558] Finished setting default values for core params
[   87.224205] Using Buffer DMA mode
[   87.225454] Periodic Transfer Interrupt Enhancement - disabled
[   87.226786] Multiprocessor Interrupt Enhancement - disabled
[   87.228095] OTG VER PARAM: 0, OTG VER FLAG: 0
[   87.229379] Dedicated Tx FIFOs mode
[   87.230947] WARN::dwc_otg_hcd_init:1032: FIQ DMA bounce buffers: virt = 0xbd904000 dma = 0xfd904000 len=9024
[   87.233557] FIQ FSM acceleration enabled for :
[   87.233557] Non-periodic Split Transactions
[   87.233557] Periodic Split Transactions
[   87.233557] High-Speed Isochronous Endpoints
[   87.233557] Interrupt/Control Split Transaction hack enabled
[   87.240244] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x8058ef90
[   87.241597] WARN::hcd_init_fiq:460: FIQ ASM at 0x8058f300 length 36
[   87.242959] WARN::hcd_init_fiq:486: MPHI regs_base at 0xc007a000
[   87.244357] dwc_otg 3f980000.usb: DWC OTG Controller
[   87.245725] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[   87.247138] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
[   87.248512] Init: Port Power? op_state=1
[   87.249830] Init: Power Port (0)
[   87.251300] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[   87.252648] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   87.253985] usb usb1: Product: DWC OTG Controller
[   87.255294] usb usb1: Manufacturer: Linux 4.9.35-v7+ dwc_otg_hcd
[   87.256636] usb usb1: SerialNumber: 3f980000.usb
[   87.258761] hub 1-0:1.0: USB hub found
[   87.260060] hub 1-0:1.0: 1 port detected
[   87.262162] usbcore: registered new interface driver usb-storage
[   87.263626] mousedev: PS/2 mouse device common for all mice
[   87.265808] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
[   87.267420] bcm2835-cpufreq: min=600000 max=1200000
[   87.269124] sdhci: Secure Digital Host Controller Interface driver
[   87.270470] sdhci: Copyright(c) Pierre Ossman
[   87.272051] sdhost-bcm2835 3f202000.sdhost: could not get clk, deferring probe
[   87.275609] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
[   87.277152] sdhci-pltfm: SDHCI platform and OF driver helper
[   87.281196] ledtrig-cpu: registered to indicate activity on CPUs
[   87.282812] hidraw: raw HID events driver (C) Jiri Kosina
[   87.284410] usbcore: registered new interface driver usbhid
[   87.285842] usbhid: USB HID core driver
[   87.288057] vchiq: vchiq_init_state: slot_zero = 0xbd980000, is_master = 0
[   87.291149] Initializing XFRM netlink socket
[   87.292631] NET: Registered protocol family 17
[   87.294173] Key type dns_resolver registered
[   87.295860] Registering SWP/SWPB emulation handler
[   87.298029] registered taskstats version 1
[   87.299778] vc-sm: Videocore shared memory driver
[   87.301231] [vc_sm_connected_init]: start
[   87.302863] vc_vchi_sm_init: failed to open VCHI service (-1)
[   87.302962] [vc_sm_connected_init]: failed to initialize shared memory service
[   87.305834] [vc_sm_connected_init]: end - returning -1
[   87.313336] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
[   87.317982] console [ttyS0] disabled
[   87.319480] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 220, base_baud = 31250000) is a 16550
[   88.341109] console [ttyS0] enabled
[   88.346885] sdhost: log_buf @ bd907000 (fd907000)
[   88.426631] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[   88.435865] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
[   88.443292] mmc-bcm2835 3f300000.mmc: DMA channel allocated
[   88.471154] Indeed it is in host mode hprt0 = 00021501
[   88.565205] random: fast init done
[   88.566725] of_cfs_init
[   88.566818] of_cfs_init: OK
[   88.579956] Freeing unused kernel memory: 1024K (80b00000 - 80c00000)
[   88.586623] mmc0: host does not support reading read-only switch, assuming write-enable
[   88.588717] mmc0: new high speed SDHC card at address 59b4
[   88.589425] mmcblk0: mmc0:59b4 SD    7.23 GiB
[   88.595482] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[   88.597048] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[   88.598593] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[   88.600116]  mmcblk0: p1
[   88.601419] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[   88.676682] usb 1-1: new high-speed USB device number 2 using dwc_otg
[   88.684646] Indeed it is in host mode hprt0 = 00001101
[   88.775434] mmc1: new high speed SDIO card at address 0001
[   88.916962] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
[   88.925269] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[   88.934871] hub 1-1:1.0: USB hub found
[   88.940241] hub 1-1:1.0: 5 ports detected
[   89.000783] Adding 6143996k swap on /dev/mmcblk0p2.  Priority:-1 extents:1 across:6143996k SSFS
[   89.112557] systemd[1]: System time before build time, advancing clock.
[   89.141214] NET: Registered protocol family 10
[   89.152088] ip_tables: (C) 2000-2006 Netfilter Core Team
[   89.168499] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[   89.192405] systemd[1]: Detected architecture arm.
[   89.199040] systemd[1]: Running with unpopulated /etc.
[   89.211451] systemd[1]: Set hostname to <bloor>.
[   89.217913] systemd[1]: Initializing machine ID from D-Bus machine ID.
[   89.286663] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[   89.345499] systemd[1]: Populated /etc with preset unit settings.
[   89.416969] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
[   89.425652] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[   89.437793] smsc95xx v1.0.5
[   89.529822] systemd[1]: Listening on udev Control Socket.
[   89.540687] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:5f:0f:3c
[   89.558642] systemd[1]: Created slice User and Session Slice.
[   89.568870] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[   89.587982] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[   90.163139] systemd-journald[139]: Received request to flush runtime journal from PID 1
[   91.785681] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[   91.861166] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[   91.870240] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   91.917360] usbcore: registered new interface driver brcmfmac
[   92.025455] brcmfmac: Firmware version = wl0: Aug  7 2017 00:46:29 version 7.45.41.46 (r666254 CY) FWID 01-f8a78378
[   92.396515] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   92.404059] brcmfmac: power management disabled
[   92.424908] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[   92.544301] tun: Universal TUN/TAP device driver, 1.6
[   92.550815] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[   92.814284] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready

Debian GNU/Linux 9 bloor ttyS0

bloor login:
benhylau commented 6 years ago

@darkdrgn2k used a 8 GB sdcard he has. So somehow two of my 8 GB sdcards of the same batch are failing, but the different sdcard @darkdrgn2k has worked. I will take my cards to do a serial session once I have access to the hardware.

benhylau commented 6 years ago

Tested the latest builds on two other models of 16 GB SD cards, and one of the 8 GB cards that did not work before, and they all seem to be working now.

hamishcoleman commented 6 years ago

Given that no other changes to fix this have been made, I think it is possible that you have some SDcards that are slower than others (No, it doesnt matter that they look like they came from the same make and model - there are enough manufacturing variations and counterfeits in the supply chain for sdcards that you just cannot know)

If that is the case, then hamishcoleman/debian-minimal-builder@96eb754514b33d0969b10714201af8915b2a64ca is the commit that waits for slower devices to be ready, thus fixing your issue.

No way to know for sure without the serial console log - you should order one, here, here (mouser has quick shipping options), or even here (for USD0.30, plus a long shipping wait)