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 <mer...@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 <giome...@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. -- To UNSUBSCRIBE, email to debian-kernel-requ...@lists.debian.org with a subject of "unsubscribe". Trouble? Contact listmas...@lists.debian.org Archive: https://lists.debian.org/20141102204337.ga9...@excalibur.cnev.de