Re: Bug#767042: [jessie daily 2014-10-27] [armhf] Installation report: LeMaker Banana Pi - problems with autoloading the realtek ethernet PHY driver module
On Tue, Oct 28, 2014 at 11:14:40PM +0100, Karsten Merker wrote:
> On Mon, Oct 27, 2014 at 11:54:47PM +0000, Ben Hutchings wrote:
> > I don't understand this.
> >
> > > Karsten Merker <merker@debian.org> (2014-10-27):
> > [...]
> > > > [ 73.104782] libphy: stmmac: probed
> > > > [ 73.104812] eth0: No PHY found
> > > >
> > > > i.e. the correct ethernet MAC driver (stmmac) gets loaded
> > > > automatically, but the necessary PHY driver (realtek) does not.
> > [...]
> > > > [ 499.392561] libphy: stmmac: probed
> > > > [ 499.392592] eth0: PHY ID 001cc915 at 0 IRQ POLL (stmmac-0:00) active
> > > > [ 499.392604] eth0: PHY ID 001cc915 at 1 IRQ POLL (stmmac-0:01)
> > > >
> > > > and the ethernet interface works. The kernel version used in this
> > > > installer build is 3.16.5-1.
> >
> > $ modinfo -F alias realtek
> > mdio:???????????111001100100100010101
> > mdio:???????????111001100100100010010
> >
> > In hex those are 1cc915 and 1cc912. (The 11 most significant bits are
> > unspecified.) So modprobe certainly should find this module when
> > requested by phylib.
>
> I have retried the installation today and tried something I had
> not done yesterday: just rmmod and directly afterwards modprobe
> the stmmac module. This results in the realtek PHY module
> getting auto-loaded, so the modprobe mechanism seems to work
> correctly there, but the question remains why this does not
> happen upon the first loading of the stmmac module.
>
> A protocol from d-i:
>
> "No Ethernet card was detected. If you know the name of the driver
> needed by your Ethernet card, you can select it from the list."
>
> --> start shell
>
> ~ # lsmod
> Module Size Used by
> stmmac 73442 0
> nls_utf8 1170 2
> nls_cp437 4767 1
> loop 16202 2
> isofs 31318 1
> vfat 9621 1
> fat 52693 1 vfat
> ext4 485433 0
> crc16 1146 1 ext4
> mbcache 8210 1 ext4
> jbd2 88199 1 ext4
> sg 20824 0
> sd_mod 38535 2
> crc_t10dif 1041 1 sd_mod
> crct10dif_common 1159 1 crc_t10dif
> usb_storage 41751 1
> ahci_sunxi 2652 0
> libahci_platform 4679 1 ahci_sunxi
> libahci 23069 1 libahci_platform
> libata 161761 3 libahci,libahci_platform,ahci_sunxi
> ohci_platform 4062 0
> ohci_hcd 37591 1 ohci_platform
> scsi_mod 175644 4 sg,usb_storage,libata,sd_mod
> ehci_platform 4526 0
> phy_sun4i_usb 4216 4
> ehci_hcd 64373 1 ehci_platform
> sunxi_mmc 10557 0
> ~ # dmesg | tail -8
> [ 31.558145] ISO 9660 Extensions: RRIP_1991A
> [ 77.839161] stmmaceth 1c50000.ethernet: no reset control found
> [ 77.839194] Ring mode enabled
> [ 77.839202] No HW DMA feature register supported
> [ 77.839210] Normal descriptors
> [ 77.839217] TX Checksum insertion supported
> [ 77.844560] libphy: stmmac: probed
> [ 77.844589] eth0: No PHY found
> ~ # rmmod stmmac
> ~ # modprobe stmmac
> ~ # dmesg | tail -8
> [ 330.112850] stmmaceth 1c50000.ethernet: no reset control found
> [ 330.112883] Ring mode enabled
> [ 330.112891] No HW DMA feature register supported
> [ 330.112898] Normal descriptors
> [ 330.112905] TX Checksum insertion supported
> [ 330.140101] libphy: stmmac: probed
> [ 330.140139] eth0: PHY ID 001cc915 at 0 IRQ POLL (stmmac-0:00) active
> [ 330.140150] eth0: PHY ID 001cc915 at 1 IRQ POLL (stmmac-0:01)
> ~ # lsmod
> Module Size Used by
> realtek 1563 0
> stmmac 73442 0
> nls_utf8 1170 2
> nls_cp437 4767 1
> loop 16202 2
> isofs 31318 1
> vfat 9621 1
> fat 52693 1 vfat
> ext4 485433 0
> crc16 1146 1 ext4
> mbcache 8210 1 ext4
> jbd2 88199 1 ext4
> sg 20824 0
> sd_mod 38535 2
> crc_t10dif 1041 1 sd_mod
> crct10dif_common 1159 1 crc_t10dif
> usb_storage 41751 1
> ahci_sunxi 2652 0
> libahci_platform 4679 1 ahci_sunxi
> libahci 23069 1 libahci_platform
> libata 161761 3 libahci,libahci_platform,ahci_sunxi
> ohci_platform 4062 0
> ohci_hcd 37591 1 ohci_platform
> scsi_mod 175644 4 sg,usb_storage,libata,sd_mod
> ehci_platform 4526 0
> phy_sun4i_usb 4216 4
> ehci_hcd 64373 1 ehci_platform
> sunxi_mmc 10557 0
>
> > As udev is *not* involved in loading MDIO PHY drivers (NIC drivers
> > expect them to be bound synchronously) it isn't easy to monitor what's
> > going on. You could replace modprobe with a script that logs its
> > arguments to a file before calling the real modprobe. That should tell
> > us whether the bug is in the kernel or userland.
>
> I am currently short on time, but I will try that during the next
> days.
Following is the log from a d-i run where /sbin/modprobe and
/sbin/insmod have been replaced by a script that logs all
invocations of these two binaries with a "modules-debug" prefix
before executing the original binary:
Jan 1 00:02:13 syslogd started: BusyBox v1.22.1
Jan 1 00:02:13 kernel: klogd started: BusyBox v1.22.1 (Debian 1:1.22.0-9)
Jan 1 00:02:13 kernel: [ 0.000000] Booting Linux on physical CPU 0x0
Jan 1 00:02:13 kernel: [ 0.000000] Initializing cgroup subsys cpuset
Jan 1 00:02:13 kernel: [ 0.000000] Initializing cgroup subsys cpu
Jan 1 00:02:13 kernel: [ 0.000000] Initializing cgroup subsys cpuacct
Jan 1 00:02:13 kernel: [ 0.000000] Linux version 3.16-3-armmp (debian-kernel@lists.debian.org) (gcc version 4.8.3 (Debian 4.8.3-12) ) #1 SMP Debian 3.16.5-1 (2014-10-10)
Jan 1 00:02:13 kernel: [ 0.000000] CPU: ARMv7 Processor [410fc074] revision 4 (ARMv7), cr=10c5387d
Jan 1 00:02:13 kernel: [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Jan 1 00:02:13 kernel: [ 0.000000] Machine model: LeMaker Banana Pi
Jan 1 00:02:13 kernel: [ 0.000000] Memory policy: Data cache writealloc
Jan 1 00:02:13 kernel: [ 0.000000] On node 0 totalpages: 262144
Jan 1 00:02:13 kernel: [ 0.000000] free_area_init_node: node 0, pgdat c09c5200, node_mem_map eeff9000
Jan 1 00:02:13 kernel: [ 0.000000] DMA zone: 1520 pages used for memmap
Jan 1 00:02:13 kernel: [ 0.000000] DMA zone: 0 pages reserved
Jan 1 00:02:13 kernel: [ 0.000000] DMA zone: 194560 pages, LIFO batch:31
Jan 1 00:02:13 kernel: [ 0.000000] HighMem zone: 528 pages used for memmap
Jan 1 00:02:13 kernel: [ 0.000000] HighMem zone: 67584 pages, LIFO batch:15
Jan 1 00:02:13 kernel: [ 0.000000] psci: probing for conduit method from DT.
Jan 1 00:02:13 kernel: [ 0.000000] psci: Using PSCI v0.1 Function IDs from DT
Jan 1 00:02:13 kernel: [ 0.000000] PERCPU: Embedded 9 pages/cpu @eefd0000 s13184 r8192 d15488 u36864
Jan 1 00:02:13 kernel: [ 0.000000] pcpu-alloc: s13184 r8192 d15488 u36864 alloc=9*4096
Jan 1 00:02:13 kernel: [ 0.000000] pcpu-alloc: [0] 0 [0] 1
Jan 1 00:02:13 kernel: [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 260624
Jan 1 00:02:13 kernel: [ 0.000000] Kernel command line: console=ttyS0,115200
Jan 1 00:02:13 kernel: [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
Jan 1 00:02:13 kernel: [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Jan 1 00:02:13 kernel: [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Jan 1 00:02:13 kernel: [ 0.000000] Memory: 1023812K/1048576K available (6357K kernel code, 823K rwdata, 2164K rodata, 684K init, 392K bss, 24764K reserved, 270336K highmem)
Jan 1 00:02:13 kernel: [ 0.000000] Virtual kernel memory layout:
Jan 1 00:02:13 kernel: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
Jan 1 00:02:13 kernel: [ 0.000000] fixmap : 0xffc00000 - 0xffe00000 (2048 kB)
Jan 1 00:02:13 kernel: [ 0.000000] vmalloc : 0xf0000000 - 0xff000000 ( 240 MB)
Jan 1 00:02:13 kernel: [ 0.000000] lowmem : 0xc0000000 - 0xef800000 ( 760 MB)
Jan 1 00:02:13 kernel: [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
Jan 1 00:02:13 kernel: [ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
Jan 1 00:02:13 kernel: [ 0.000000] .text : 0xc0008000 - 0xc085a6a0 (8522 kB)
Jan 1 00:02:13 kernel: [ 0.000000] .init : 0xc085b000 - 0xc0906380 ( 685 kB)
Jan 1 00:02:13 kernel: [ 0.000000] .data : 0xc0908000 - 0xc09d5c18 ( 824 kB)
Jan 1 00:02:13 kernel: [ 0.000000] .bss : 0xc09d5c18 - 0xc0a37ff0 ( 393 kB)
Jan 1 00:02:13 kernel: [ 0.000000] Hierarchical RCU implementation.
Jan 1 00:02:13 kernel: [ 0.000000] RCU dyntick-idle grace-period acceleration is enabled.
Jan 1 00:02:13 kernel: [ 0.000000] RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=2.
Jan 1 00:02:13 kernel: [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
Jan 1 00:02:13 kernel: [ 0.000000] NR_IRQS:16 nr_irqs:16 16
Jan 1 00:02:13 kernel: [ 0.000000] Architected cp15 timer(s) running at 24.00MHz (phys).
Jan 1 00:02:13 kernel: [ 0.000009] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 2863311519744ns
Jan 1 00:02:13 kernel: [ 0.000018] Switching to timer-based delay loop
Jan 1 00:02:13 kernel: [ 0.001004] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 178956969942ns
Jan 1 00:02:13 kernel: [ 0.001370] sched_clock: 32 bits at 160MHz, resolution 6ns, wraps every 26843545593ns
Jan 1 00:02:13 kernel: [ 0.001813] Console: colour dummy device 80x30
Jan 1 00:02:13 kernel: [ 0.001848] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
Jan 1 00:02:13 kernel: [ 0.001863] pid_max: default: 32768 minimum: 301
Jan 1 00:02:13 kernel: [ 0.001994] Security Framework initialized
Jan 1 00:02:13 kernel: [ 0.002065] AppArmor: AppArmor disabled by boot time parameter
Jan 1 00:02:13 kernel: [ 0.002074] Yama: disabled by default; enable with sysctl kernel.yama.*
Jan 1 00:02:13 kernel: [ 0.002152] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jan 1 00:02:13 kernel: [ 0.002165] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jan 1 00:02:13 kernel: [ 0.003116] Initializing cgroup subsys memory
Jan 1 00:02:13 kernel: [ 0.003152] Initializing cgroup subsys devices
Jan 1 00:02:13 kernel: [ 0.003191] Initializing cgroup subsys freezer
Jan 1 00:02:13 kernel: [ 0.003210] Initializing cgroup subsys net_cls
Jan 1 00:02:13 kernel: [ 0.003243] Initializing cgroup subsys blkio
Jan 1 00:02:13 kernel: [ 0.003269] Initializing cgroup subsys perf_event
Jan 1 00:02:13 kernel: [ 0.003282] Initializing cgroup subsys net_prio
Jan 1 00:02:13 kernel: [ 0.003367] CPU: Testing write buffer coherency: ok
Jan 1 00:02:13 kernel: [ 0.003413] ftrace: allocating 22905 entries in 68 pages
Jan 1 00:02:13 kernel: [ 0.038260] /cpus/cpu@0 missing clock-frequency property
Jan 1 00:02:13 kernel: [ 0.038296] /cpus/cpu@1 missing clock-frequency property
Jan 1 00:02:13 kernel: [ 0.038311] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Jan 1 00:02:13 kernel: [ 0.038559] Setting up static identity map for 0x405b5718 - 0x405b5770
Jan 1 00:02:13 kernel: [ 0.048036] CPU1: Booted secondary processor
Jan 1 00:02:13 kernel: [ 0.048093] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Jan 1 00:02:13 kernel: [ 0.048220] Brought up 2 CPUs
Jan 1 00:02:13 kernel: [ 0.048243] SMP: Total of 2 processors activated.
Jan 1 00:02:13 kernel: [ 0.048251] CPU: All CPU(s) started in HYP mode.
Jan 1 00:02:13 kernel: [ 0.048256] CPU: Virtualization extensions available.
Jan 1 00:02:13 kernel: [ 0.049010] devtmpfs: initialized
Jan 1 00:02:13 kernel: [ 0.053441] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 4
Jan 1 00:02:13 kernel: [ 0.059299] pinctrl core: initialized pinctrl subsystem
Jan 1 00:02:13 kernel: [ 0.059832] regulator-dummy: no parameters
Jan 1 00:02:13 kernel: [ 0.065948] NET: Registered protocol family 16
Jan 1 00:02:13 kernel: [ 0.066339] DMA: preallocated 256 KiB pool for atomic coherent allocations
Jan 1 00:02:13 kernel: [ 0.074339] No ATAGs?
Jan 1 00:02:13 kernel: [ 0.074393] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Jan 1 00:02:13 kernel: [ 0.074407] hw-breakpoint: maximum watchpoint size is 8 bytes.
Jan 1 00:02:13 kernel: [ 0.074587] EXYNOS: PMU not supported
Jan 1 00:02:13 kernel: [ 0.075573] Serial: AMBA PL011 UART driver
Jan 1 00:02:13 kernel: [ 0.093335] edma-dma-engine edma-dma-engine.0: Can't allocate PaRAM dummy slot
Jan 1 00:02:13 kernel: [ 0.093366] edma-dma-engine: probe of edma-dma-engine.0 failed with error -5
Jan 1 00:02:13 kernel: [ 0.093810] reg-fixed-voltage usb1-vbus: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/usb1_vbus_pin@0, deferring probe
Jan 1 00:02:13 kernel: [ 0.093829] platform usb1-vbus: Driver reg-fixed-voltage requests probe deferral
Jan 1 00:02:13 kernel: [ 0.093854] reg-fixed-voltage usb2-vbus: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/usb2_vbus_pin@0, deferring probe
Jan 1 00:02:13 kernel: [ 0.093867] platform usb2-vbus: Driver reg-fixed-voltage requests probe deferral
Jan 1 00:02:13 kernel: [ 0.094088] vcc3v0: 3000 mV
Jan 1 00:02:13 kernel: [ 0.094431] vcc3v3: 3300 mV
Jan 1 00:02:13 kernel: [ 0.094544] reg-fixed-voltage gmac-3v3: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/gmac_power_pin@0, deferring probe
Jan 1 00:02:13 kernel: [ 0.094561] platform gmac-3v3: Driver reg-fixed-voltage requests probe deferral
Jan 1 00:02:13 kernel: [ 0.095776] vgaarb: loaded
Jan 1 00:02:13 kernel: [ 0.096325] usbcore: registered new interface driver usbfs
Jan 1 00:02:13 kernel: [ 0.096400] usbcore: registered new interface driver hub
Jan 1 00:02:13 kernel: [ 0.096612] usbcore: registered new device driver usb
Jan 1 00:02:13 kernel: [ 0.097133] media: Linux media interface: v0.10
Jan 1 00:02:13 kernel: [ 0.097199] Linux video capture interface: v2.00
Jan 1 00:02:13 kernel: [ 0.097306] pps_core: LinuxPPS API ver. 1 registered
Jan 1 00:02:13 kernel: [ 0.097314] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Jan 1 00:02:13 kernel: [ 0.097344] PTP clock support registered
Jan 1 00:02:13 kernel: [ 0.098884] Switched to clocksource arch_sys_counter
Jan 1 00:02:13 kernel: [ 0.150623] NET: Registered protocol family 2
Jan 1 00:02:13 kernel: [ 0.151958] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
Jan 1 00:02:13 kernel: [ 0.152049] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
Jan 1 00:02:13 kernel: [ 0.152171] TCP: Hash tables configured (established 8192 bind 8192)
Jan 1 00:02:13 kernel: [ 0.152261] TCP: reno registered
Jan 1 00:02:13 kernel: [ 0.152276] UDP hash table entries: 512 (order: 2, 16384 bytes)
Jan 1 00:02:13 kernel: [ 0.152335] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
Jan 1 00:02:13 kernel: [ 0.152652] NET: Registered protocol family 1
Jan 1 00:02:13 kernel: [ 0.152716] PCI: CLS 0 bytes, default 64
Jan 1 00:02:13 kernel: [ 0.153271] Unpacking initramfs...
Jan 1 00:02:13 kernel: [ 0.609496] Freeing initrd memory: 5124K (cfafe000 - cffff000)
Jan 1 00:02:13 kernel: [ 0.609930] hw perfevents: enabled with ARMv7 Cortex-A7 PMU driver, 5 counters available
Jan 1 00:02:13 kernel: [ 0.612152] futex hash table entries: 512 (order: 3, 32768 bytes)
Jan 1 00:02:13 kernel: [ 0.612333] audit: initializing netlink subsys (disabled)
Jan 1 00:02:13 kernel: [ 0.612437] audit: type=2000 audit(0.596:1): initialized
Jan 1 00:02:13 kernel: [ 0.613928] zbud: loaded
Jan 1 00:02:13 kernel: [ 0.614323] VFS: Disk quotas dquot_6.5.2
Jan 1 00:02:13 kernel: [ 0.614401] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Jan 1 00:02:13 kernel: [ 0.614564] msgmni has been set to 1481
Jan 1 00:02:13 kernel: [ 0.616335] alg: No test for stdrng (krng)
Jan 1 00:02:13 kernel: [ 0.616478] bounce: pool size: 64 pages
Jan 1 00:02:13 kernel: [ 0.616581] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Jan 1 00:02:13 kernel: [ 0.616828] io scheduler noop registered
Jan 1 00:02:13 kernel: [ 0.616848] io scheduler deadline registered
Jan 1 00:02:13 kernel: [ 0.617001] io scheduler cfq registered (default)
Jan 1 00:02:13 kernel: [ 0.620354] sun7i-a20-pinctrl 1c20800.pinctrl: initialized sunXi PIO driver
Jan 1 00:02:13 kernel: [ 0.622503] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
Jan 1 00:02:13 kernel: [ 0.624755] console [ttyS0] disabled
Jan 1 00:02:13 kernel: [ 0.645023] 1c28000.serial: ttyS0 at MMIO 0x1c28000 (irq = 33, base_baud = 1500000) is a U6_16550A
Jan 1 00:02:13 kernel: [ 1.360409] console [ttyS0] enabled
Jan 1 00:02:13 kernel: [ 1.385979] dw-apb-uart 1c28c00.serial: Couldn't set LCR to 0
Jan 1 00:02:13 kernel: [ 1.392729] dw-apb-uart 1c28c00.serial: Couldn't set LCR to 0
Jan 1 00:02:13 kernel: [ 1.398523] 1c28c00.serial: ttyS1 at MMIO 0x1c28c00 (irq = 36, base_baud = 1500000) is a U6_16550A
Jan 1 00:02:13 kernel: [ 1.429440] dw-apb-uart 1c29c00.serial: Couldn't set LCR to 0
Jan 1 00:02:13 kernel: [ 1.436192] dw-apb-uart 1c29c00.serial: Couldn't set LCR to 0
Jan 1 00:02:13 kernel: [ 1.441991] 1c29c00.serial: ttyS2 at MMIO 0x1c29c00 (irq = 52, base_baud = 1500000) is a U6_16550A
Jan 1 00:02:13 kernel: [ 1.451424] Serial: AMBA driver
Jan 1 00:02:13 kernel: [ 1.454924] Serial: IMX driver
Jan 1 00:02:13 kernel: [ 1.461918] mousedev: PS/2 mouse device common for all mice
Jan 1 00:02:13 kernel: [ 1.468914] sunxi-rtc 1c20d00.rtc: rtc core: registered rtc-sunxi as rtc0
Jan 1 00:02:13 kernel: [ 1.475736] sunxi-rtc 1c20d00.rtc: RTC enabled
Jan 1 00:02:13 kernel: [ 1.481205] ledtrig-cpu: registered to indicate activity on CPUs
Jan 1 00:02:13 kernel: [ 1.487334] hidraw: raw HID events driver (C) Jiri Kosina
Jan 1 00:02:13 kernel: [ 1.493429] usbcore: registered new interface driver usbhid
Jan 1 00:02:13 kernel: [ 1.499014] usbhid: USB HID core driver
Jan 1 00:02:13 kernel: [ 1.503570] TCP: cubic registered
Jan 1 00:02:13 kernel: [ 1.507013] NET: Registered protocol family 10
Jan 1 00:02:13 kernel: [ 1.512596] mip6: Mobile IPv6
Jan 1 00:02:13 kernel: [ 1.515638] NET: Registered protocol family 17
Jan 1 00:02:13 kernel: [ 1.520132] mpls_gso: MPLS GSO support
Jan 1 00:02:13 kernel: [ 1.523964] ThumbEE CPU extension supported.
Jan 1 00:02:13 kernel: [ 1.528269] Registering SWP/SWPB emulation handler
Jan 1 00:02:13 kernel: [ 1.533901] registered taskstats version 1
Jan 1 00:02:13 kernel: [ 1.539268] usb1-vbus: 5000 mV
Jan 1 00:02:13 kernel: [ 1.543004] usb2-vbus: 5000 mV
Jan 1 00:02:13 kernel: [ 1.546586] gmac-3v3: 3300 mV
Jan 1 00:02:13 kernel: [ 1.550105] sunxi-rtc 1c20d00.rtc: setting system clock to 1970-01-01 00:02:12 UTC (132)
Jan 1 00:02:13 kernel: [ 1.563071] PM: Hibernation image not present or could not be loaded.
Jan 1 00:02:13 kernel: [ 1.563109] gmac-3v3: disabling
Jan 1 00:02:13 kernel: [ 1.566261] usb2-vbus: disabling
Jan 1 00:02:13 kernel: [ 1.569553] usb1-vbus: disabling
Jan 1 00:02:13 kernel: [ 1.572802] vcc3v3: disabling
Jan 1 00:02:13 kernel: [ 1.575779] vcc3v0: disabling
Jan 1 00:02:13 kernel: [ 1.580045] Freeing unused kernel memory: 684K (c085b000 - c0906000)
Jan 1 00:02:13 kernel: [ 1.633008] systemd-udevd[49]: starting version 215
Jan 1 00:02:13 kernel: [ 1.640181] random: systemd-udevd urandom read with 0 bits of entropy available
Jan 1 00:02:13 kernel: [ 1.688386] sunxi-mmc 1c0f000.mmc: No vqmmc regulator found
Jan 1 00:02:13 kernel: [ 1.699539] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Jan 1 00:02:13 kernel: [ 1.715869] sunxi-mmc 1c0f000.mmc: Got CD GPIO #234.
Jan 1 00:02:13 kernel: [ 1.726520] ehci-platform: EHCI generic platform driver
Jan 1 00:02:13 kernel: [ 1.727152] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
Jan 1 00:02:13 kernel: [ 1.728157] ohci-platform: OHCI generic platform driver
Jan 1 00:02:13 kernel: [ 1.753905] SCSI subsystem initialized
Jan 1 00:02:13 kernel: [ 1.758989] sunxi-mmc 1c0f000.mmc: base:0xf0110000 irq:64
Jan 1 00:02:13 kernel: [ 1.766842] ohci-platform 1c14400.usb: Generic Platform OHCI controller
Jan 1 00:02:13 kernel: [ 1.773999] libata version 3.00 loaded.
Jan 1 00:02:13 kernel: [ 1.777094] ohci-platform 1c14400.usb: new USB bus registered, assigned bus number 1
Jan 1 00:02:13 kernel: [ 1.787148] ohci-platform 1c14400.usb: irq 96, io mem 0x01c14400
Jan 1 00:02:13 kernel: [ 1.817909] mmc0: host does not support reading read-only switch. assuming write-enable.
Jan 1 00:02:13 kernel: [ 1.833732] mmc0: new high speed SDHC card at address 0007
Jan 1 00:02:13 kernel: [ 1.840115] mmcblk0: mmc0:0007 SD16G 14.6 GiB
Jan 1 00:02:13 kernel: [ 1.848894] mmcblk0: p1 p2 p3 < p5 >
Jan 1 00:02:13 kernel: [ 1.852255] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
Jan 1 00:02:13 kernel: [ 1.852264] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jan 1 00:02:13 kernel: [ 1.852271] usb usb1: Product: Generic Platform OHCI controller
Jan 1 00:02:13 kernel: [ 1.852277] usb usb1: Manufacturer: Linux 3.16-3-armmp ohci_hcd
Jan 1 00:02:13 kernel: [ 1.852283] usb usb1: SerialNumber: 1c14400.usb
Jan 1 00:02:13 kernel: [ 1.883999] hub 1-0:1.0: USB hub found
Jan 1 00:02:13 kernel: [ 1.887963] hub 1-0:1.0: 1 port detected
Jan 1 00:02:13 kernel: [ 1.894794] ohci-platform 1c1c400.usb: Generic Platform OHCI controller
Jan 1 00:02:13 kernel: [ 1.901998] ohci-platform 1c1c400.usb: new USB bus registered, assigned bus number 2
Jan 1 00:02:13 kernel: [ 1.910041] ohci-platform 1c1c400.usb: irq 97, io mem 0x01c1c400
Jan 1 00:02:13 kernel: [ 1.975175] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001
Jan 1 00:02:13 kernel: [ 1.982042] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jan 1 00:02:13 kernel: [ 1.989306] usb usb2: Product: Generic Platform OHCI controller
Jan 1 00:02:13 kernel: [ 1.995298] usb usb2: Manufacturer: Linux 3.16-3-armmp ohci_hcd
Jan 1 00:02:13 kernel: [ 2.001242] usb usb2: SerialNumber: 1c1c400.usb
Jan 1 00:02:13 kernel: [ 2.011220] hub 2-0:1.0: USB hub found
Jan 1 00:02:13 kernel: [ 2.015523] hub 2-0:1.0: 1 port detected
Jan 1 00:02:13 kernel: [ 2.020587] ehci-platform 1c14000.usb: EHCI Host Controller
Jan 1 00:02:13 kernel: [ 2.027908] ehci-platform 1c14000.usb: new USB bus registered, assigned bus number 3
Jan 1 00:02:13 kernel: [ 2.036328] ehci-platform 1c14000.usb: irq 71, io mem 0x01c14000
Jan 1 00:02:13 kernel: [ 2.050944] ehci-platform 1c14000.usb: USB 2.0 started, EHCI 1.00
Jan 1 00:02:13 kernel: [ 2.057553] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002
Jan 1 00:02:13 kernel: [ 2.064441] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jan 1 00:02:13 kernel: [ 2.071696] usb usb3: Product: EHCI Host Controller
Jan 1 00:02:13 kernel: [ 2.076642] usb usb3: Manufacturer: Linux 3.16-3-armmp ehci_hcd
Jan 1 00:02:13 kernel: [ 2.082594] usb usb3: SerialNumber: 1c14000.usb
Jan 1 00:02:13 kernel: [ 2.088203] hub 3-0:1.0: USB hub found
Jan 1 00:02:13 kernel: [ 2.092109] hub 3-0:1.0: 1 port detected
Jan 1 00:02:13 kernel: [ 2.139088] ahci-sunxi 1c18000.sata: controller can't do PMP, turning off CAP_PMP
Jan 1 00:02:13 kernel: [ 2.146652] ahci-sunxi 1c18000.sata: forcing PORTS_IMPL to 0x1
Jan 1 00:02:13 kernel: [ 2.152618] ahci-sunxi 1c18000.sata: AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl platform mode
Jan 1 00:02:13 kernel: [ 2.161618] ahci-sunxi 1c18000.sata: flags: ncq sntf pm led clo only pio slum part ccc
Jan 1 00:02:13 kernel: [ 2.171088] scsi0 : ahci_platform
Jan 1 00:02:13 kernel: [ 2.175071] ata1: SATA max UDMA/133 mmio [mem 0x01c18000-0x01c18fff] port 0x100 irq 88
Jan 1 00:02:13 kernel: [ 2.183412] ehci-platform 1c1c000.usb: EHCI Host Controller
Jan 1 00:02:13 kernel: [ 2.189462] ehci-platform 1c1c000.usb: new USB bus registered, assigned bus number 4
Jan 1 00:02:13 kernel: [ 2.197579] ehci-platform 1c1c000.usb: irq 72, io mem 0x01c1c000
Jan 1 00:02:13 kernel: [ 2.214916] ehci-platform 1c1c000.usb: USB 2.0 started, EHCI 1.00
Jan 1 00:02:13 kernel: [ 2.221292] usb usb4: New USB device found, idVendor=1d6b, idProduct=0002
Jan 1 00:02:13 kernel: [ 2.228105] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jan 1 00:02:13 kernel: [ 2.235334] usb usb4: Product: EHCI Host Controller
Jan 1 00:02:13 kernel: [ 2.240238] usb usb4: Manufacturer: Linux 3.16-3-armmp ehci_hcd
Jan 1 00:02:13 kernel: [ 2.246174] usb usb4: SerialNumber: 1c1c000.usb
Jan 1 00:02:13 kernel: [ 2.251742] hub 4-0:1.0: USB hub found
Jan 1 00:02:13 kernel: [ 2.255594] hub 4-0:1.0: 1 port detected
Jan 1 00:02:13 kernel: [ 2.406945] usb 3-1: new high-speed USB device number 2 using ehci-platform
Jan 1 00:02:13 kernel: [ 2.506915] ata1: SATA link down (SStatus 0 SControl 300)
Jan 1 00:02:13 kernel: [ 2.549165] usb 3-1: New USB device found, idVendor=090c, idProduct=1000
Jan 1 00:02:13 kernel: [ 2.555987] usb 3-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 1 00:02:13 kernel: [ 2.563152] usb 3-1: Product: Intenso Rainbow Line
Jan 1 00:02:13 kernel: [ 2.567956] usb 3-1: Manufacturer: SMI Technology
Jan 1 00:02:13 kernel: [ 2.572670] usb 3-1: SerialNumber: 11100800071766
Jan 1 00:02:13 kernel: [ 2.588189] usb-storage 3-1:1.0: USB Mass Storage device detected
Jan 1 00:02:13 kernel: [ 2.596016] scsi1 : usb-storage 3-1:1.0
Jan 1 00:02:13 kernel: [ 2.603819] usbcore: registered new interface driver usb-storage
Jan 1 00:02:14 kernel: [ 3.604588] scsi 1:0:0:0: Direct-Access Intenso Rainbow PQ: 0 ANSI: 4
Jan 1 00:02:14 root: modules-debug: /sbin/modprobe -q crct10dif
Jan 1 00:02:14 root: modules-debug: /sbin/modprobe -q crct10dif-all
Jan 1 00:02:14 kernel: [ 3.657807] sd 1:0:0:0: [sda] 15605760 512-byte logical blocks: (7.99 GB/7.44 GiB)
Jan 1 00:02:14 kernel: [ 3.659308] sd 1:0:0:0: [sda] Write Protect is off
Jan 1 00:02:14 kernel: [ 3.659330] sd 1:0:0:0: [sda] Mode Sense: 43 00 00 00
Jan 1 00:02:14 kernel: [ 3.660438] sd 1:0:0:0: [sda] No Caching mode page found
Jan 1 00:02:14 kernel: [ 3.660463] sd 1:0:0:0: [sda] Assuming drive cache: write through
Jan 1 00:02:14 kernel: [ 3.668821] sda: sda1
Jan 1 00:02:14 kernel: [ 3.674050] sd 1:0:0:0: [sda] Attached SCSI removable disk
Jan 1 00:02:14 kernel: [ 3.676879] sd 1:0:0:0: Attached scsi generic sg0 type 0
Jan 1 00:02:15 init: starting pid 166, tty '': '/sbin/reopen-console /sbin/debian-installer'
Jan 1 00:02:15 init: starting pid 169, tty '/dev/tty4': '/usr/bin/tail -f /var/log/syslog'
Jan 1 00:02:15 debconf: Setting debconf/language to en
Jan 1 00:02:15 main-menu[184]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Jan 1 00:02:15 main-menu[184]: INFO: Menu item 'localechooser' selected
Jan 1 00:02:15 debconf: Setting debconf/language to
Jan 1 00:02:20 localechooser: info: Language = 'en'
Jan 1 00:02:20 localechooser: info: line=en;0;US;en_US.UTF-8;;console-setup
Jan 1 00:02:20 localechooser: info: Set debian-installer/language = 'en'
Jan 1 00:02:20 localechooser: info: Default country = 'US'
Jan 1 00:02:20 localechooser: info: Default locale = 'en_US.UTF-8'
Jan 1 00:02:20 localechooser: info: Set debian-installer/consoledisplay = 'console-setup'
Jan 1 00:02:20 debconf: Setting debconf/language to en
Jan 1 00:02:28 localechooser: info: Set debian-installer/country = 'DE'
Jan 1 00:02:28 localechooser: info: Falling back to locale 'en_US.UTF-8'
Jan 1 00:02:32 localechooser: info: Set debian-installer/locale = 'en_US.UTF-8'
Jan 1 00:02:32 localechooser: info: System locale (debian-installer/locale) = 'en_US.UTF-8'
Jan 1 00:02:32 localechooser: info: Set debian-installer/language = 'en_US:en'
Jan 1 00:02:32 debconf: Setting debconf/language to en_US:en
Jan 1 00:02:32 main-menu[184]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Jan 1 00:02:32 main-menu[184]: INFO: Menu item 'console-setup-udeb' selected
Jan 1 00:02:32 main-menu[184]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Jan 1 00:02:32 main-menu[184]: INFO: Menu item 'iso-scan' selected
Jan 1 00:02:33 main-menu[184]: INFO: Falling back to the package description for loop-modules-3.16-3-armmp-di
Jan 1 00:02:33 main-menu[184]: INFO: Falling back to the package description for loop-modules-3.16-3-armmp-di
Jan 1 00:02:33 net/hw-detect.hotplug: Detected hotpluggable network interface lo
Jan 1 00:02:33 apt-install: Queueing package udev for later installation
Jan 1 00:02:33 apt-install: Queueing package pciutils for later installation
Jan 1 00:02:33 apt-install: Queueing package usbutils for later installation
Jan 1 00:02:35 check-missing-firmware: /dev/.udev/firmware-missing does not exist, skipping
Jan 1 00:02:35 check-missing-firmware: /run/udev/firmware-missing does not exist, skipping
Jan 1 00:02:35 check-missing-firmware: no missing firmware in loaded kernel modules
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe ext2
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe ext3
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe ext4
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe fat
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe vfat
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe xfs
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe jfs
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe iso9660
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe hfsplus
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe hfs
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe ntfs
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe btrfs
Jan 1 00:02:35 root: modules-debug: /sbin/modprobe loop
Jan 1 00:02:35 kernel: [ 24.597988] loop: module loaded
Jan 1 00:02:36 iso-scan: devices found: '/dev/mmcblk0p1 /dev/mmcblk0p2 /dev/mmcblk0p3 /dev/mmcblk0p5 /dev/sda1 /dev/mmcblk0 /dev/sda'
Jan 1 00:02:36 iso-scan: selected_device(s)='/dev/mmcblk0p1 /dev/mmcblk0p2 /dev/mmcblk0p3 /dev/mmcblk0p5 /dev/sda1 /dev/mmcblk0 /dev/sda'
Jan 1 00:02:36 kernel: [ 25.322996] EXT4-fs (mmcblk0p1): couldn't mount as ext3 due to feature incompatibilities
Jan 1 00:02:36 kernel: [ 25.323841] EXT4-fs (mmcblk0p1): mounting ext2 file system using the ext4 subsystem
Jan 1 00:02:36 kernel: [ 25.326593] EXT4-fs (mmcblk0p1): mounted filesystem without journal. Opts: (null)
Jan 1 00:02:36 iso-scan: Mounted /dev/mmcblk0p1 for first pass
Jan 1 00:02:36 iso-scan: Failed mounting /dev/mmcblk0p1 (from /dev/mmcblk0p1) as an ISO image
Jan 1 00:02:36 kernel: [ 25.425062] EXT4-fs (mmcblk0p2): couldn't mount as ext3 due to feature incompatibilities
Jan 1 00:02:36 kernel: [ 25.426184] EXT4-fs (mmcblk0p2): couldn't mount as ext2 due to feature incompatibilities
Jan 1 00:02:36 kernel: [ 25.438206] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Jan 1 00:02:36 iso-scan: Mounted /dev/mmcblk0p2 for first pass
Jan 1 00:02:36 kernel: [ 25.568637] random: nonblocking pool is initialized
Jan 1 00:02:36 iso-scan: Failed mounting /dev/mmcblk0p2 (from /dev/mmcblk0p2) as an ISO image
Jan 1 00:02:36 kernel: [ 25.869805] EXT4-fs (mmcblk0p3): unable to read superblock
Jan 1 00:02:36 kernel: [ 25.870000] EXT4-fs (mmcblk0p3): unable to read superblock
Jan 1 00:02:36 kernel: [ 25.870106] EXT4-fs (mmcblk0p3): unable to read superblock
Jan 1 00:02:36 kernel: [ 25.870200] FAT-fs (mmcblk0p3): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Jan 1 00:02:36 kernel: [ 25.871779] isofs_fill_super: bread failed, dev=mmcblk0p3, iso_blknum=16, block=32
Jan 1 00:02:36 iso-scan: Waiting for /dev/mmcblk0p3 to possibly get ready...
Jan 1 00:02:39 kernel: [ 28.889553] EXT4-fs (mmcblk0p3): unable to read superblock
Jan 1 00:02:39 kernel: [ 28.889754] EXT4-fs (mmcblk0p3): unable to read superblock
Jan 1 00:02:39 kernel: [ 28.889862] EXT4-fs (mmcblk0p3): unable to read superblock
Jan 1 00:02:39 kernel: [ 28.889955] FAT-fs (mmcblk0p3): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Jan 1 00:02:39 kernel: [ 28.891408] isofs_fill_super: bread failed, dev=mmcblk0p3, iso_blknum=16, block=32
Jan 1 00:02:39 kernel: [ 28.902426] FAT-fs (mmcblk0p5): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Jan 1 00:02:39 iso-scan: Waiting for /dev/mmcblk0p5 to possibly get ready...
Jan 1 00:02:42 kernel: [ 31.962111] FAT-fs (mmcblk0p5): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Jan 1 00:02:42 kernel: [ 32.016021] FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Jan 1 00:02:42 root: modules-debug: /sbin/modprobe -q nls_cp437
Jan 1 00:02:42 root: modules-debug: /sbin/modprobe -q nls_utf8
Jan 1 00:02:43 iso-scan: Mounted /dev/sda1 for first pass
Jan 1 00:02:43 iso-scan: Found ISO ./debian-testing-armhf-CD-1.iso on /dev/sda1
Jan 1 00:02:44 kernel: [ 33.083473] ISO 9660 Extensions: Microsoft Joliet Level 3
Jan 1 00:02:44 kernel: [ 33.091288] ISO 9660 Extensions: RRIP_1991A
Jan 1 00:02:44 iso-scan: Detected ISO with 'testing' (jessie) distribution
Jan 1 00:02:44 iso-scan: Detected ISO with distribution 'testing' (jessie)
Jan 1 00:02:44 iso-scan: Debian ISO ./debian-testing-armhf-CD-1.iso usable
Jan 1 00:02:44 iso-scan: Failed mounting /dev/sda1 (from /dev/sda1) as an ISO image
Jan 1 00:02:44 kernel: [ 33.462271] FAT-fs (mmcblk0): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Jan 1 00:02:44 iso-scan: Waiting for /dev/mmcblk0 to possibly get ready...
Jan 1 00:02:47 kernel: [ 36.519020] FAT-fs (mmcblk0): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Jan 1 00:02:47 kernel: [ 36.570010] FAT-fs (sda): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Jan 1 00:02:47 iso-scan: Waiting for /dev/sda to possibly get ready...
Jan 1 00:02:50 kernel: [ 39.658132] FAT-fs (sda): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Jan 1 00:02:50 iso-scan: ISOS_FOUND='[sda1] /debian-testing-armhf-CD-1.iso (testing)'
Jan 1 00:02:50 iso-scan: Selected ISO: /debian-testing-armhf-CD-1.iso on /dev/sda1
Jan 1 00:02:50 kernel: [ 39.785516] FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Jan 1 00:02:50 kernel: [ 39.800389] ISO 9660 Extensions: Microsoft Joliet Level 3
Jan 1 00:02:51 kernel: [ 40.777730] ISO 9660 Extensions: RRIP_1991A
Jan 1 00:02:51 iso-scan: Detected ISO with 'testing' (jessie) distribution
Jan 1 00:02:51 iso-scan: Detected ISO with distribution 'testing' (jessie)
Jan 1 00:02:51 anna-install: Queueing udeb apt-mirror-setup for later installation
Jan 1 00:02:51 anna-install: Queueing udeb apt-cdrom-setup for later installation
Jan 1 00:02:51 anna-install: Queueing udeb jessie-support for later installation
Jan 1 00:02:51 main-menu[184]: (process:603): [sda1] debian-testing-armhf-CD-1.iso (testing)
Jan 1 00:02:51 main-menu[184]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Jan 1 00:02:51 main-menu[184]: INFO: Menu item 'load-iso' selected
Jan 1 00:02:52 cdrom-retriever: warning: Unable to find contrib/debian-installer/binary-armhf/Packages in /cdrom/dists/jessie/Release.
Jan 1 00:02:52 cdrom-retriever: warning: Unable to find contrib/debian-installer/binary-armhf/Packages.gz in /cdrom/dists/jessie/Release.
Jan 1 00:02:52 anna[1133]: DEBUG: resolver (cdrom-detect): package doesn't exist (ignored)
Jan 1 00:02:52 anna[1133]: DEBUG: resolver (xfs-modules): package doesn't exist (ignored)
Jan 1 00:02:52 anna[1133]: DEBUG: resolver (cdrom-detect): package doesn't exist (ignored)
Jan 1 00:02:52 anna[1133]: DEBUG: resolver (xfs-modules): package doesn't exist (ignored)
Jan 1 00:02:52 anna[1133]: DEBUG: retrieving apt-cdrom-setup 1:0.92
Jan 1 00:02:52 anna[1133]: DEBUG: retrieving apt-mirror-setup 1:0.92
Jan 1 00:02:53 anna[1133]: DEBUG: retrieving apt-setup-udeb 1:0.92
Jan 1 00:02:53 anna[1133]: DEBUG: retrieving base-installer 1.151
Jan 1 00:02:54 anna[1133]: DEBUG: retrieving bootstrap-base 1.151
Jan 1 00:02:54 anna[1133]: DEBUG: retrieving btrfs-tools-udeb 3.17-1
Jan 1 00:02:55 anna[1133]: DEBUG: retrieving choose-mirror-bin 2.59
Jan 1 00:02:55 anna[1133]: DEBUG: retrieving clock-setup 0.121
Jan 1 00:02:56 anna[1133]: DEBUG: retrieving debian-archive-keyring-udeb 2014.1
Jan 1 00:02:56 anna[1133]: DEBUG: retrieving di-utils-mapdevfs 1.109
Jan 1 00:02:56 anna[1133]: DEBUG: retrieving debootstrap-udeb 1.0.64
Jan 1 00:02:56 anna[1133]: DEBUG: retrieving dosfstools-udeb 3.0.26-4
Jan 1 00:02:57 anna[1133]: DEBUG: retrieving e2fsprogs-udeb 1.42.12-1
Jan 1 00:02:57 anna[1133]: DEBUG: retrieving finish-install 2.53
Jan 1 00:02:58 anna[1133]: DEBUG: retrieving flash-kernel-installer 3.28
Jan 1 00:02:58 anna[1133]: DEBUG: retrieving libc6-udeb 2.19-12
Jan 1 00:02:59 anna[1133]: DEBUG: retrieving libnss-files-udeb 2.19-12
Jan 1 00:02:59 anna[1133]: DEBUG: retrieving gpgv-udeb 1.4.18-4
Jan 1 00:02:59 anna[1133]: DEBUG: retrieving disk-detect 1.106
Jan 1 00:03:00 anna[1133]: DEBUG: retrieving ethdetect 1.106
Jan 1 00:03:01 anna[1133]: DEBUG: retrieving jfsutils-udeb 1.1.15-2.1
Jan 1 00:03:01 anna[1133]: DEBUG: retrieving kickseed-common 0.61
Jan 1 00:03:01 anna[1133]: DEBUG: retrieving libbsd0-udeb 0.7.0-2
Jan 1 00:03:01 anna[1133]: DEBUG: retrieving libnl-3-200-udeb 3.2.24-2
Jan 1 00:03:01 anna[1133]: DEBUG: retrieving libnl-genl-3-200-udeb 3.2.24-2
Jan 1 00:03:01 anna[1133]: DEBUG: retrieving btrfs-modules-3.16-3-armmp-di 3.16.5-1
Jan 1 00:03:02 anna[1133]: DEBUG: retrieving crypto-modules-3.16-3-armmp-di 3.16.5-1
Jan 1 00:03:02 anna[1133]: DEBUG: retrieving jfs-modules-3.16-3-armmp-di 3.16.5-1
Jan 1 00:03:02 anna[1133]: DEBUG: retrieving md-modules-3.16-3-armmp-di 3.16.5-1
Jan 1 00:03:02 anna[1133]: DEBUG: retrieving nic-modules-3.16-3-armmp-di 3.16.5-1
Jan 1 00:03:04 anna[1133]: DEBUG: retrieving nic-shared-modules-3.16-3-armmp-di 3.16.5-1
Jan 1 00:03:04 anna[1133]: DEBUG: retrieving nic-usb-modules-3.16-3-armmp-di 3.16.5-1
Jan 1 00:03:04 anna[1133]: DEBUG: retrieving nic-wireless-modules-3.16-3-armmp-di 3.16.5-1
Jan 1 00:03:06 anna[1133]: DEBUG: retrieving zlib-modules-3.16-3-armmp-di 3.16.5-1
Jan 1 00:03:06 anna[1133]: DEBUG: retrieving libdevmapper1.02.1-udeb 2:1.02.90-2
Jan 1 00:03:06 anna[1133]: DEBUG: retrieving liblzo2-2-udeb 2.08-1
Jan 1 00:03:06 anna[1133]: DEBUG: retrieving mdadm-udeb 3.3.2-2
Jan 1 00:03:06 anna[1133]: DEBUG: retrieving ndisc6-udeb 1.0.1-1+b1
Jan 1 00:03:06 anna[1133]: DEBUG: retrieving rdnssd-udeb 1.0.1-1+b1
Jan 1 00:03:06 anna[1133]: DEBUG: retrieving netcfg 1.122
Jan 1 00:03:08 anna[1133]: DEBUG: retrieving nobootloader 1.44
Jan 1 00:03:09 anna[1133]: DEBUG: retrieving open-iscsi-udeb 2.0.873+git0.3b4b4500-4
Jan 1 00:03:09 anna[1133]: DEBUG: retrieving libcrypto1.0.0-udeb 1.0.1j-1
Jan 1 00:03:09 anna[1133]: DEBUG: retrieving libparted-fs-resize0-udeb 3.2-6
Jan 1 00:03:09 anna[1133]: DEBUG: retrieving libparted2-udeb 3.2-6
Jan 1 00:03:09 anna[1133]: DEBUG: retrieving partman-auto 124
Jan 1 00:03:10 anna[1133]: DEBUG: retrieving partman-auto-raid 27
Jan 1 00:03:11 anna[1133]: DEBUG: retrieving partman-base 179
Jan 1 00:03:12 anna[1133]: DEBUG: retrieving partman-utils 179
Jan 1 00:03:13 anna[1133]: DEBUG: retrieving partman-basicfilesystems 104
Jan 1 00:03:14 anna[1133]: DEBUG: retrieving partman-basicmethods 58
Jan 1 00:03:15 anna[1133]: DEBUG: retrieving partman-btrfs 15
Jan 1 00:03:16 anna[1133]: DEBUG: retrieving partman-ext3 83
Jan 1 00:03:18 anna[1133]: DEBUG: retrieving partman-iscsi 28
Jan 1 00:03:19 anna[1133]: DEBUG: retrieving partman-jfs 43
Jan 1 00:03:20 anna[1133]: DEBUG: retrieving partman-md 71
Jan 1 00:03:21 anna[1133]: DEBUG: retrieving partman-partitioning 106
Jan 1 00:03:23 anna[1133]: DEBUG: retrieving partman-target 92
Jan 1 00:03:24 anna[1133]: DEBUG: retrieving partman-xfs 53
Jan 1 00:03:26 anna[1133]: DEBUG: retrieving pkgsel 0.42
Jan 1 00:03:27 anna[1133]: DEBUG: retrieving network-preseed 1.66
Jan 1 00:03:29 anna[1133]: DEBUG: retrieving rdate-udeb 1:1.2-6
Jan 1 00:03:29 anna[1133]: DEBUG: retrieving tzsetup-udeb 1:0.60
Jan 1 00:03:30 anna[1133]: DEBUG: retrieving user-setup-udeb 1.58
Jan 1 00:03:32 anna[1133]: DEBUG: retrieving wide-dhcpv6-client-udeb 20080615-12
Jan 1 00:03:32 anna[1133]: DEBUG: retrieving libiw30-udeb 30~pre9-8
Jan 1 00:03:32 anna[1133]: DEBUG: retrieving wpasupplicant-udeb 2.3-1
Jan 1 00:03:32 anna[1133]: DEBUG: retrieving xfsprogs-udeb 3.2.1
Jan 1 00:03:35 main-menu[184]: INFO: Menu item 'ethdetect' selected
Jan 1 00:03:37 kernel: [ 86.183129] stmmaceth 1c50000.ethernet: no reset control found
Jan 1 00:03:37 kernel: [ 86.183167] Ring mode enabled
Jan 1 00:03:37 kernel: [ 86.183176] No HW DMA feature register supported
Jan 1 00:03:37 kernel: [ 86.183184] Normal descriptors
Jan 1 00:03:37 kernel: [ 86.183191] TX Checksum insertion supported
Jan 1 00:03:37 kernel: [ 86.188892] libphy: stmmac: probed
Jan 1 00:03:37 kernel: [ 86.188920] eth0: No PHY found
Jan 1 00:03:37 net/hw-detect.hotplug: Detected hotpluggable network interface lo
Jan 1 00:03:37 net/hw-detect.hotplug: Detected hotpluggable network interface eth0
Jan 1 00:03:39 check-missing-firmware: /dev/.udev/firmware-missing does not exist, skipping
Jan 1 00:03:39 check-missing-firmware: /run/udev/firmware-missing does not exist, skipping
Jan 1 00:03:39 check-missing-firmware: no missing firmware in loaded kernel modules
Jan 1 00:03:48 main-menu[184]: INFO: Menu item 'ethdetect' succeeded but requested to be left unconfigured.
Jan 1 00:03:52 main-menu[184]: INFO: Menu item 'di-utils-shell' selected
The entry at 00:03:37 does not show a modprobe invocation before
the initialization of the stmmac module, so the module does not
seem to be loaded by calling modprobe, which would explain why no
autoloading of the PHY driver happens there. What I do not
understand is that I also see no insmod invocation, and logging
insmod invocations works when I run insmod from a shell. So the
question remains: how is the stmmac module loaded at that point?
I first thought that something might invoke kmod directly (i.e.
not by calling the insmod/modprobe aliases), but logging direct
kmod invocations also shows nothing, so I am probably overlooking
something.
Any ideas?
Regards,
Karsten
--
Gem. Par. 28 Abs. 4 Bundesdatenschutzgesetz widerspreche ich der Nutzung
sowie der Weitergabe meiner personenbezogenen Daten für Zwecke der
Werbung sowie der Markt- oder Meinungsforschung.
Reply to: