debian内核代码执行流程(三)

时间:2021-03-19 17:07:15

接续《debian内核代码执行流程(二)》未完成部分

 

下面这行输出信息是启动udevd进程产生的输出信息:

[    3.306217] udevd[49]: starting version 175

175是udevd的版本号。

 

根据《essential linux device drivers》中关于udev的说明(英文书140页),设备可以分成热插拔和冷插拔。

热插拔是在已经运行的系统中连接的设备,冷插拔是系统启动前插入的设备。

当系统检测到热插拔设备时,系统使用netlink socket向用户空间发送uevents。

在用户端,udev管理设备的创建和删除、接收uevent,管理热插拔设备。udev也可以处理冷插拔。因为udev是用户程序,只能在内核驱动后才开始执行,需要特殊的机制来模拟热插拔时间。

在启动时,内核在sysfs下为所有设备创建名为uevent的文件,然后向这些文件发送冷插拔时间。

当udev启动后,它会从sysfs中读取uevent,为每个冷插拔设备产生热插拔时间。

 

此时加载r8169.ko模块,执行r8169.ko模块的初始化函数,即rtl8169_init_module(drivers/net/ethernet/realtek/r8169.c).

之后调用rtl8169_pci_driver.probe函数,即rtl8169_init_one,rtl8169_init_one中输出下面信息:

[    3.346446] r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded

rtl8169_init_one中调用pci_set_master,pci_set_master调用pcibios_set_master,输出下面信息:

[    2.854355] pcieport 0000:00:1c.0: setting latency timer to 64

下面信息是由setup_msi_irq输出的(但不知道是从哪个函数调用过去的):

[    2.854395] pcieport 0000:00:1c.0: irq 40 for MSI/MSI-X

rtl8169_init_one中继续输出下面信息:

[    3.346995] r8169 0000:04:00.0: eth0: RTL8102e at 0xf821a000, 00:e0:4c:26:51:75, XID 14c00000 IRQ 44

 

接着加载scsi模块,执行init_scsi,输出下面信息:

[    3.372657] SCSI subsystem initialized

 

接着加载usbcore模块,执行usb_init(drivers/usb/core/usb.c),usb_init调用usb_register(&usbfs_driver),输出下面信息:

[    3.384633] usbcore: registered new interface driver usbfs

usb_init调用usb_hub_init,usb_hub_init调用usb_register(&hub_driver),输出下面信息:

[    3.384665] usbcore: registered new interface driver hub

usb_hub_init中还创建了khubd线程,线程执行函数是hub_thread,用于监控hub状态变更。

usb_init中接着调用usb_register_device_driver(&usb_generic_driver,THIS_MODULE),输出下面信息:

[    3.384694] usbcore: registered new device driver usb

 

接着加载ehci-hcd模块,执行ehci_hcd_init(drivers/usb/host/ehci-hcd.c),输出下面信息:

[    3.385735] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver

ehci_hcd_init调用pci_register_driver(&PCI_DRIVER),而PCI_DRIVER即ehci_pci_driver.

之后会调用ehci_pci_driver.probe,即usb_hcd_pci_probe函数。

usb_hcd_pci_probe调用pci_set_master, pci_set_master调用pcibios_set_master,输出下面信息:

[    3.385794] ehci_hcd 0000:00:1d.7: setting latency timer to 64

usb_hcd_pci_probe接着调用usb_add_hcd,输出下面信息:

[    3.385798] ehci_hcd 0000:00:1d.7: EHCI Host Controller

usb_add_hcd调用usb_register_bus,输出下面信息:

[    3.385826] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 1

usb_add_hcd调用hcd->driver->reset,即ehci_pci_hc_driver->reset,也即ehci_pci_setup,echi_pci_setup中输出下面信息:

[    3.385849] ehci_hcd 0000:00:1d.7: using broken periodic workaround
[
3.385859] ehci_hcd 0000:00:1d.7: debug port 1

ehci_pci_setup调用echi_pci_reinit,ehci_pci_reinit调用pci_set_mwi,pci_set_mwi调用pci_set_cacheline_size, 输出下面信息:

[    3.389747] ehci_hcd 0000:00:1d.7: cache line size of 32 is not supported

usb_add_hcd调用usb_hcd_request_irqs,输出下面信息:

[    3.408370] ehci_hcd 0000:00:1d.7: irq 23, io mem 0xfdfff000

 

接着加载libata.ko模块,调用ata_init,输出下面信息:

[    3.412309] libata version 3.00 loaded.

 

接着加载uhci-hcd.ko模块,调用uhci_hcd_init,输出下面信息:

[    3.412721] uhci_hcd: USB Universal Host Controller Interface driver

 

接着加载floppy.ko模块,调用floppy_module_init, floppy_module_init调用floppy_init,floppy_init调用get_fdc_version,输出下面信息:

[    3.423746] FDC 0 is a post-1991 82077

 

上面ehci-hcd模块中usb_add_hcd调用hcd->driver->start,即ehci_pci_hc_driver->start,即ehci_run函数,输出下面信息:

[    3.424024] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00

由此可见,模块加载可能是并发执行的。

usb_add_hcd中接着调用register_root_hub,register_root_hub调用usb_new_device,usb_new_device调用annouce_device,输出下面信息:

[    3.424090] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[
3.424095] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[
3.424100] usb usb1: Product: EHCI Host Controller
[
3.424104] usb usb1: Manufacturer: Linux 3.2.57 ehci_hcd
[
3.424107] usb usb1: SerialNumber: 0000:00:1d.7

 

调用hub_probe(不知道从哪儿开始调用的),输出下面信息:

[    3.424288] hub 1-0:1.0: USB hub found

hub_probe调用hub_configure,输出下面信息:

[    3.424294] hub 1-0:1.0: 8 ports detected

 

上面uhci_hcd_init调用pci_register_driver(&PCI_DRIVER),而PCI_DRIVER即uhci_pci_driver,

之后调用uhci_pci_driver.probe,即usb_hcd_pci_probe函数,跟前面ehci的例子类似输出下面信息:

[    3.425261] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[
3.425267] uhci_hcd 0000:00:1d.0: UHCI Host Controller
[
3.425283] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2
[
3.425315] uhci_hcd 0000:00:1d.0: irq 23, io base 0x0000fe00
[
3.425351] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001
[
3.425353] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[
3.425355] usb usb2: Product: UHCI Host Controller
[
3.425357] usb usb2: Manufacturer: Linux 3.2.57 uhci_hcd
[
3.425359] usb usb2: SerialNumber: 0000:00:1d.0
[
3.425647] hub 2-0:1.0: USB hub found
[
3.425653] hub 2-0:1.0: 2 ports detected
[
3.425743] uhci_hcd 0000:00:1d.1: setting latency timer to 64
[
3.425746] uhci_hcd 0000:00:1d.1: UHCI Host Controller
[
3.425753] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3
[
3.425790] uhci_hcd 0000:00:1d.1: irq 19, io base 0x0000fd00
[
3.425824] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001
[
3.425826] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[
3.425828] usb usb3: Product: UHCI Host Controller
[
3.425830] usb usb3: Manufacturer: Linux 3.2.57 uhci_hcd
[
3.425832] usb usb3: SerialNumber: 0000:00:1d.1
[
3.425938] hub 3-0:1.0: USB hub found
[
3.425942] hub 3-0:1.0: 2 ports detected
[
3.426010] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[
3.426014] uhci_hcd 0000:00:1d.2: UHCI Host Controller
[
3.426020] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4
[
3.426051] uhci_hcd 0000:00:1d.2: irq 18, io base 0x0000fc00
[
3.426082] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001
[
3.426085] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[
3.426087] usb usb4: Product: UHCI Host Controller
[
3.426089] usb usb4: Manufacturer: Linux 3.2.57 uhci_hcd
[
3.426091] usb usb4: SerialNumber: 0000:00:1d.2
[
3.426198] hub 4-0:1.0: USB hub found
[
3.426202] hub 4-0:1.0: 2 ports detected
[
3.426273] uhci_hcd 0000:00:1d.3: setting latency timer to 64
[
3.426276] uhci_hcd 0000:00:1d.3: UHCI Host Controller
[
3.426283] uhci_hcd 0000:00:1d.3: new USB bus registered, assigned bus number 5
[
3.426314] uhci_hcd 0000:00:1d.3: irq 16, io base 0x0000fb00
[
3.426344] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001
[
3.426347] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[
3.426349] usb usb5: Product: UHCI Host Controller
[
3.426351] usb usb5: Manufacturer: Linux 3.2.57 uhci_hcd
[
3.426353] usb usb5: SerialNumber: 0000:00:1d.3
[
3.426454] hub 5-0:1.0: USB hub found
[
3.426457] hub 5-0:1.0: 2 ports detected

因为当前系统上有多个uhci hcd controller,对每个uhci hcd controller都有相应的输出信息。

在当前系统上可以列出所有的hcd controller信息:

$ tree /sys/bus/usb/devices
/sys/bus/usb/devices
├──
1-0:1.0 -> ../../../devices/pci0000:00/0000:00:1d.7/usb1/1-0:1.0
├──
1-1 -> ../../../devices/pci0000:00/0000:00:1d.7/usb1/1-1
├──
1-1:1.0 -> ../../../devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0
├──
1-1.2 -> ../../../devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1.2
├──
1-1.2:1.0 -> ../../../devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1.2/1-1.2:1.0
├──
1-1.2:1.1 -> ../../../devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1.2/1-1.2:1.1
├──
1-4 -> ../../../devices/pci0000:00/0000:00:1d.7/usb1/1-4
├──
1-4:1.0 -> ../../../devices/pci0000:00/0000:00:1d.7/usb1/1-4/1-4:1.0
├──
2-0:1.0 -> ../../../devices/pci0000:00/0000:00:1d.0/usb2/2-0:1.0
├──
2-2 -> ../../../devices/pci0000:00/0000:00:1d.0/usb2/2-2
├──
2-2:1.0 -> ../../../devices/pci0000:00/0000:00:1d.0/usb2/2-2/2-2:1.0
├──
3-0:1.0 -> ../../../devices/pci0000:00/0000:00:1d.1/usb3/3-0:1.0
├──
3-1 -> ../../../devices/pci0000:00/0000:00:1d.1/usb3/3-1
├──
3-1:1.0 -> ../../../devices/pci0000:00/0000:00:1d.1/usb3/3-1/3-1:1.0
├──
4-0:1.0 -> ../../../devices/pci0000:00/0000:00:1d.2/usb4/4-0:1.0
├──
5-0:1.0 -> ../../../devices/pci0000:00/0000:00:1d.3/usb5/5-0:1.0
├── usb1
-> ../../../devices/pci0000:00/0000:00:1d.7/usb1
├── usb2
-> ../../../devices/pci0000:00/0000:00:1d.0/usb2
├── usb3
-> ../../../devices/pci0000:00/0000:00:1d.1/usb3
├── usb4
-> ../../../devices/pci0000:00/0000:00:1d.2/usb4
└── usb5
-> ../../../devices/pci0000:00/0000:00:1d.3/usb5

 

加载ata-piix.ko模块,调用piix_init,piix_init调用pci_register_driver(&piix_pci_driver),之后调用piix_pci_driver.probe,即piix_init_one。

输出下面信息:

[    3.426522] ata_piix 0000:00:1f.1: version 2.13

piix_init_one调用pci_set_master,pci_set_master调用pcibios_set_master,pcibios_set_master调用, 输出下面信息:

[    3.426578] ata_piix 0000:00:1f.1: setting latency timer to 64

piix_init_one调用ata_pci_sff_activate_host,ata_pci_sff_activate_host调用ata_host_register,ata_host_register调用ata_scsi_add_hosts,

ata_scsi_add_hosts对每个port调用scsi_add_host,scsi_add_host调用scsi_add_host_with_dma,输出下面信息(因为多次调用,所以多行输出):

[    3.427370] scsi0 : ata_piix
[
3.427473] scsi1 : ata_piix

ata_host_register中对每个port输出信息,得到如下内容:

[    3.427936] ata1: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0xfa00 irq 14
[
3.427939] ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0xfa08 irq 15

 

接着对于新的IDE controller,调用piix_pci_driver.probe,即piix_init_one。

piinx_init_one调用piix_init_sata_map,输出下面信息:

[    3.427974] ata_piix 0000:00:1f.2: MAP [ P0 P2 P1 P3 ]

piix_init_one调用pci_set_master,pci_set_master调用pcibios_set_master,pcibios_set_master调用, 输出下面信息:

[    3.426578] ata_piix 0000:00:1f.1: setting latency timer to 64

下面一行输出是ata2的错误信息(懒得分析是从哪儿产生的):

[    3.428496] ata2: port disabled--ignoring

与前面类似,还有下面的输出:

[    3.428513] scsi2 : ata_piix
[
3.428907] scsi3 : ata_piix
[
3.429356] ata3: SATA max UDMA/133 cmd 0xf900 ctl 0xf800 bmdma 0xf500 irq 19
[
3.429359] ata4: SATA max UDMA/133 cmd 0xf700 ctl 0xf600 bmdma 0xf508 irq 19

 

ata_host_register对每个port调用async_schedule异步执行async_port_probe.

async_port_probe调用ata_port_probe,ata_port_probe调用ata_bus_probe,ata_bus_probe对总线上设备调用ata_dev_configure.

[    3.600351] ata3.00: ATA-8: ST3250318AS, CC38, max UDMA/133
[
3.600358] ata3.00: 488397168 sectors, multi 16: LBA48 NCQ (depth 0/32)

ata_bus_probe调用ata_set_mode,ata_set_mode 调用ata_do_set_mode,ata_do_set_mode调用ata_dev_set_mode,输出下面信息:

[    3.616337] ata3.00: configured for UDMA/133

 

前面提到过,在usbcore刚挂载时,创建了一个khubd内核线程,用于检查hub的状态并执行相应的功能,线程执行函数是hub_thread.

hub_thread中调用hub_events, hub_events调用hub_port_init,输出下面信息:

[    3.736020] usb 1-1: new high-speed USB device number 2 using ehci_hcd

 对ata1仍然也有调用async_schedule,虽然它先被调用,但是却执行比ata3还晚,输出下面信息:

[    3.760302] ata1.01: ATAPI: ATAPI   iHDP118   4, GL05, max UDMA/33
[
3.776191] ata1.01: configured for UDMA/33

下面两行不知道是哪个函数调用scsi_add_lun产生的:

[    3.778163] scsi 0:0:1:0: CD-ROM            ATAPI    iHDP118   4      GL05 PQ: 0 ANSI: 5
[
3.778453] scsi 2:0:0:0: Direct-Access ATA ST3250318AS CC38 PQ: 0 ANSI: 5

 

此时检测到硬盘,会调用sd_probe.sd_probe调用async_schedule异步调用sd_probe_async,sd_probe_async调用sd_revalidate_disk,

sd_revalidate_disk调用sd_read_capacity,输出下面信息:

[    3.783064] sd 2:0:0:0: [sda] 488397168 512-byte logical blocks: (250 GB/232 GiB)

sd_revalidate_disk调用sd_read_write_protect_flag,输出下面信息:

[    3.783126] sd 2:0:0:0: [sda] Write Protect is off
[ 3.783128] sd 2:0:0:0: [sda] Mode Sense: 00 3a 00 00

sd_revalidate_disk调用sd_read_cache_type,输出下面信息:

[    3.783148] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA

 

此时检测到光驱,调用sr_probe,sr_probe调用get_capabilites,输出下面信息:

[    3.785910] sr0: scsi3-mmc drive: 48x/48x cd/rw xa/form2 cdda tray

sr_probe调用register_cdrom,输出下面信息:

[    3.785915] cdrom: Uniform CD-ROM driver Revision: 3.20

sr_probe中输出下面信息:

[    3.786106] sr 0:0:1:0: Attached scsi CD-ROM sr0

 

下面两行输出不知道是从哪个地方调用过去的。

tsc_refine_calibration_work中产生下面输出:

[    3.836020] Refined TSC clocksource calibration: 2510.403 MHz.

clocksource_select中产生下面输出:

[    3.836028] Switching to clocksource tsc

 

下面一行输出信息不知道是如何产生的(应该是在sd_probe_async中产生的?):

[    3.842587]  sda: sda1 sda2 < sda5 sda6 sda7 sda8 > sda3

sd_probe_async中输出下面信息:

[    3.843109] sd 2:0:0:0: [sda] Attached SCSI disk

 

下面两行信息是从sg_add函数产生的(但不知道是从哪个地方调用过去的):

[    3.845469] sr 0:0:1:0: Attached scsi generic sg0 type 5
[
3.845545] sd 2:0:0:0: Attached scsi generic sg1 type 0

下面的输出信息和前面类似(khubd检测并输出信息):

[    3.868682] usb 1-1: New USB device found, idVendor=1a40, idProduct=0101
[
3.868685] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[
3.868687] usb 1-1: Product: USB 2.0 Hub
[
3.868967] hub 1-1:1.0: USB hub found
[
3.869062] hub 1-1:1.0: 4 ports detected
[
4.092020] usb 1-4: new high-speed USB device number 5 using ehci_hcd
[
4.224322] usb 1-4: New USB device found, idVendor=0b1e, idProduct=0006
[
4.224328] usb 1-4: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[
4.464020] usb 2-2: new low-speed USB device number 2 using uhci_hcd

接着三行信息是电源管理模块相关:

[    4.633397] PM: Starting manual resume from disk
[
4.633400] PM: Hibernation image partition 8:8 present
[
4.633401] PM: Looking for hibernation image.

检测到罗技光电鼠标:

[    4.639360] usb 2-2: New USB device found, idVendor=046d, idProduct=c05a
[
4.639365] usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[
4.639370] usb 2-2: Product: USB Optical Mouse
[
4.639373] usb 2-2: Manufacturer: Logitech

 

后面还有一些输出信息,以后有时间在分析具体模块的驱动时再来详细说明。