/var/log/debug felnövekszik 2 óra alatt fél gigásra

Linux-haladó

Sziasztok.

Egy érdekes jelenség lőtte ki magát itt a szórakoztatásomra, melyre keresgéltem a problémát, és megleltem egy SD kártya használatakor.
A tárgyban megadott fájlom akkor kezd alien-szerűen gyors növekedésbe, amikor használni kezdem a kártyát. Furcsamód az a wine-os program is belassul, mellyel a kártyát írom/olvasom éppen --- az ok vélhetően a debug fájl írása miatt van.

Szerintetek mi lehet a jelenség hátterében?

Ez a mai adag a fájlban, ezzel írja tele nekem úgy, hogy nap végére majdnem 0.5 GB a mérete:

-----------

a[log]$ head -n 40 debug
May 28 13:28:03 darkstar kernel: [ 6.776362] usb-storage: Status code 0; transferred 31/31
May 28 13:28:03 darkstar kernel: [ 6.776367] usb-storage: -- transfer complete
May 28 13:28:03 darkstar kernel: [ 6.776372] usb-storage: Bulk command transfer result=0
May 28 13:28:03 darkstar kernel: [ 6.776379] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1 entries
May 28 13:28:03 darkstar kernel: [ 6.777131] usb-storage: Status code 0; transferred 4096/4096
May 28 13:28:03 darkstar kernel: [ 6.777137] usb-storage: -- transfer complete
May 28 13:28:03 darkstar kernel: [ 6.777143] usb-storage: Bulk data transfer result 0x0
May 28 13:28:03 darkstar kernel: [ 6.777148] usb-storage: Attempting to get CSW...
May 28 13:28:03 darkstar kernel: [ 6.777154] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
May 28 13:28:03 darkstar kernel: [ 6.777237] usb-storage: Status code 0; transferred 13/13
May 28 13:28:03 darkstar kernel: [ 6.777242] usb-storage: -- transfer complete
May 28 13:28:03 darkstar kernel: [ 6.777247] usb-storage: Bulk status result = 0
May 28 13:28:03 darkstar kernel: [ 6.777254] usb-storage: Bulk Status S 0x53425355 T 0x6a R 0 Stat 0x0
May 28 13:28:03 darkstar kernel: [ 6.777263] usb-storage: scsi cmd done, result=0x0
May 28 13:28:03 darkstar kernel: [ 6.777271] usb-storage: *** thread sleeping.
May 28 13:28:03 darkstar kernel: [ 6.777401] usb-storage: queuecommand_lck called
May 28 13:28:03 darkstar kernel: [ 6.777415] usb-storage: *** thread awakened.
May 28 13:28:03 darkstar kernel: [ 6.777422] usb-storage: Command READ_10 (10 bytes)
May 28 13:28:03 darkstar kernel: [ 6.777426] usb-storage: 28 00 00 00 03 f0 00 00 08 00
May 28 13:28:03 darkstar kernel: [ 6.777447] usb-storage: Bulk Command S 0x43425355 T 0x6b L 4096 F 128 Trg 0 LUN 0 CL 10
May 28 13:28:03 darkstar kernel: [ 6.777454] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
May 28 13:28:03 darkstar kernel: [ 6.777627] usb-storage: Status code 0; transferred 31/31
May 28 13:28:03 darkstar kernel: [ 6.777632] usb-storage: -- transfer complete
May 28 13:28:03 darkstar kernel: [ 6.777638] usb-storage: Bulk command transfer result=0
May 28 13:28:03 darkstar kernel: [ 6.777646] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1 entries
May 28 13:28:03 darkstar kernel: [ 6.778252] usb-storage: Status code 0; transferred 4096/4096
May 28 13:28:03 darkstar kernel: [ 6.778259] usb-storage: -- transfer complete
May 28 13:28:03 darkstar kernel: [ 6.778265] usb-storage: Bulk data transfer result 0x0
May 28 13:28:03 darkstar kernel: [ 6.778270] usb-storage: Attempting to get CSW...
May 28 13:28:03 darkstar kernel: [ 6.778277] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
May 28 13:28:03 darkstar kernel: [ 6.778364] usb-storage: Status code 0; transferred 13/13
May 28 13:28:03 darkstar kernel: [ 6.778369] usb-storage: -- transfer complete
May 28 13:28:03 darkstar kernel: [ 6.778374] usb-storage: Bulk status result = 0
May 28 13:28:03 darkstar kernel: [ 6.778382] usb-storage: Bulk Status S 0x53425355 T 0x6b R 0 Stat 0x0
May 28 13:28:03 darkstar kernel: [ 6.778391] usb-storage: scsi cmd done, result=0x0
May 28 13:28:03 darkstar kernel: [ 6.778399] usb-storage: *** thread sleeping.
May 28 13:28:03 darkstar kernel: [ 6.783984] usb-storage: queuecommand_lck called
May 28 13:28:03 darkstar kernel: [ 6.784058] usb-storage: *** thread awakened.
May 28 13:28:03 darkstar kernel: [ 6.784067] usb-storage: Command READ_10 (10 bytes)
May 28 13:28:03 darkstar kernel: [ 6.784072] usb-storage: 28 00 00 00 03 f8 00 00 08 00
a[log]$
a[log]$
a[log]$
a[log]$ head -n 40 /var/log/debug
May 28 13:28:03 darkstar kernel: [ 6.776362] usb-storage: Status code 0; transferred 31/31
May 28 13:28:03 darkstar kernel: [ 6.776367] usb-storage: -- transfer complete
May 28 13:28:03 darkstar kernel: [ 6.776372] usb-storage: Bulk command transfer result=0
May 28 13:28:03 darkstar kernel: [ 6.776379] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1 entries
May 28 13:28:03 darkstar kernel: [ 6.777131] usb-storage: Status code 0; transferred 4096/4096
May 28 13:28:03 darkstar kernel: [ 6.777137] usb-storage: -- transfer complete
May 28 13:28:03 darkstar kernel: [ 6.777143] usb-storage: Bulk data transfer result 0x0
May 28 13:28:03 darkstar kernel: [ 6.777148] usb-storage: Attempting to get CSW...
May 28 13:28:03 darkstar kernel: [ 6.777154] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
May 28 13:28:03 darkstar kernel: [ 6.777237] usb-storage: Status code 0; transferred 13/13
May 28 13:28:03 darkstar kernel: [ 6.777242] usb-storage: -- transfer complete
May 28 13:28:03 darkstar kernel: [ 6.777247] usb-storage: Bulk status result = 0
May 28 13:28:03 darkstar kernel: [ 6.777254] usb-storage: Bulk Status S 0x53425355 T 0x6a R 0 Stat 0x0
May 28 13:28:03 darkstar kernel: [ 6.777263] usb-storage: scsi cmd done, result=0x0
May 28 13:28:03 darkstar kernel: [ 6.777271] usb-storage: *** thread sleeping.
May 28 13:28:03 darkstar kernel: [ 6.777401] usb-storage: queuecommand_lck called
May 28 13:28:03 darkstar kernel: [ 6.777415] usb-storage: *** thread awakened.
May 28 13:28:03 darkstar kernel: [ 6.777422] usb-storage: Command READ_10 (10 bytes)
May 28 13:28:03 darkstar kernel: [ 6.777426] usb-storage: 28 00 00 00 03 f0 00 00 08 00
May 28 13:28:03 darkstar kernel: [ 6.777447] usb-storage: Bulk Command S 0x43425355 T 0x6b L 4096 F 128 Trg 0 LUN 0 CL 10
May 28 13:28:03 darkstar kernel: [ 6.777454] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
May 28 13:28:03 darkstar kernel: [ 6.777627] usb-storage: Status code 0; transferred 31/31
May 28 13:28:03 darkstar kernel: [ 6.777632] usb-storage: -- transfer complete
May 28 13:28:03 darkstar kernel: [ 6.777638] usb-storage: Bulk command transfer result=0
May 28 13:28:03 darkstar kernel: [ 6.777646] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1 entries
May 28 13:28:03 darkstar kernel: [ 6.778252] usb-storage: Status code 0; transferred 4096/4096
May 28 13:28:03 darkstar kernel: [ 6.778259] usb-storage: -- transfer complete
May 28 13:28:03 darkstar kernel: [ 6.778265] usb-storage: Bulk data transfer result 0x0
May 28 13:28:03 darkstar kernel: [ 6.778270] usb-storage: Attempting to get CSW...
May 28 13:28:03 darkstar kernel: [ 6.778277] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
May 28 13:28:03 darkstar kernel: [ 6.778364] usb-storage: Status code 0; transferred 13/13
May 28 13:28:03 darkstar kernel: [ 6.778369] usb-storage: -- transfer complete
May 28 13:28:03 darkstar kernel: [ 6.778374] usb-storage: Bulk status result = 0
May 28 13:28:03 darkstar kernel: [ 6.778382] usb-storage: Bulk Status S 0x53425355 T 0x6b R 0 Stat 0x0
May 28 13:28:03 darkstar kernel: [ 6.778391] usb-storage: scsi cmd done, result=0x0
May 28 13:28:03 darkstar kernel: [ 6.778399] usb-storage: *** thread sleeping.
May 28 13:28:03 darkstar kernel: [ 6.783984] usb-storage: queuecommand_lck called
May 28 13:28:03 darkstar kernel: [ 6.784058] usb-storage: *** thread awakened.
May 28 13:28:03 darkstar kernel: [ 6.784067] usb-storage: Command READ_10 (10 bytes)
May 28 13:28:03 darkstar kernel: [ 6.784072] usb-storage: 28 00 00 00 03 f8 00 00 08 00
a[log]$

-----------
A keletkező irreális méret kezdetben:

a[log]$ ls -lh /var/log/debug
-rw-r--r-- 1 root root 52M máj 28 15:31 /var/log/debug
a[log]$

...aztán nő.
Egyre csak.

  • 3098 megtekintés

( Lacyc3 v | 2012. 05. 28., h – 15:37 )

A kernelben az USB beállításoknál valószínűleg be van állítva az "USB verbose messages" vagy hasonló névre hallgató opció.

root[linux]# cat .config | grep "VERBOSE"
CONFIG_ATA_VERBOSE_ERROR=y
CONFIG_SND_VERBOSE_PROCFS=y
# CONFIG_SND_VERBOSE_PRINTK is not set
CONFIG_DEBUG_BUGVERBOSE=y
# CONFIG_X86_VERBOSE_BOOTUP is not set
root[linux]#

Erre gondoltál?

Vagy a bejelölt összes "USB":

root[linux]# cat .config | grep "USB" | grep -v "is not set"
CONFIG_BT_HCIBTUSB=m
CONFIG_BT_HCIBFUSB=m
# USB Network Adapters
CONFIG_USB_USBNET=y
CONFIG_USB_NET_AX8817X=y
CONFIG_USB_NET_CDCETHER=y
CONFIG_USB_NET_CDC_EEM=y
CONFIG_USB_NET_CDC_NCM=y
CONFIG_USB_NET_NET1080=y
CONFIG_USB_NET_RNDIS_HOST=y
CONFIG_USB_NET_CDC_SUBSET=y
CONFIG_USB_BELKIN=y
CONFIG_USB_ARMLINUX=y
CONFIG_USB_IPHETH=y
CONFIG_USB_ZD1201=y
CONFIG_V4L_USB_DRIVERS=y
CONFIG_USB_VIDEO_CLASS=y
CONFIG_USB_VIDEO_CLASS_INPUT_EVDEV=y
# USB Input Devices
CONFIG_USB_HID=y
CONFIG_USB_HIDDEV=y
CONFIG_USB_SUPPORT=y
CONFIG_USB_COMMON=y
CONFIG_USB_ARCH_HAS_HCD=y
CONFIG_USB_ARCH_HAS_OHCI=y
CONFIG_USB_ARCH_HAS_EHCI=y
CONFIG_USB_ARCH_HAS_XHCI=y
CONFIG_USB=y
# Miscellaneous USB options
CONFIG_USB_DEVICEFS=y
CONFIG_USB_SUSPEND=y
CONFIG_USB_MON=y
CONFIG_USB_WUSB=y
# USB Host Controller Drivers
CONFIG_USB_EHCI_HCD=y
CONFIG_USB_EHCI_ROOT_HUB_TT=y
CONFIG_USB_EHCI_TT_NEWSCHED=y
CONFIG_USB_OHCI_HCD=y
CONFIG_USB_OHCI_LITTLE_ENDIAN=y
CONFIG_USB_UHCI_HCD=y
CONFIG_USB_WHCI_HCD=y
# USB Device Class drivers
CONFIG_USB_ACM=y
CONFIG_USB_PRINTER=y
CONFIG_USB_WDM=y
CONFIG_USB_TMC=y
# NOTE: USB_STORAGE depends on SCSI but BLK_DEV_SD may
# also be needed; see USB_STORAGE Help for more info
CONFIG_USB_STORAGE=y
CONFIG_USB_STORAGE_DEBUG=y
# USB Imaging devices
# USB port drivers
CONFIG_USB_SERIAL=y
CONFIG_USB_SERIAL_CONSOLE=y
CONFIG_USB_SERIAL_GENERIC=y
CONFIG_USB_SERIAL_CYPRESS_M8=y
CONFIG_USB_SERIAL_GARMIN=y
CONFIG_USB_SERIAL_NAVMAN=y
CONFIG_USB_SERIAL_PL2303=y
CONFIG_USB_SERIAL_TI=y
CONFIG_USB_SERIAL_WWAN=y
CONFIG_USB_SERIAL_OPTION=y
# USB Miscellaneous drivers
CONFIG_USB_LED=y
CONFIG_USB_GADGET=y
CONFIG_USB_GADGET_VBUS_DRAW=2
CONFIG_USB_GADGET_STORAGE_NUM_BUFFERS=2
CONFIG_USB_R8A66597=y
CONFIG_USB_GADGET_DUALSPEED=y
CONFIG_USB_ETH=m
CONFIG_USB_ETH_RNDIS=y
CONFIG_USB_ETH_EEM=y
CONFIG_USB_G_WEBCAM=m
root[linux]#

---
--- A gond akkor van, ha látszólag minden működik. ---
---

Ha jól tudom, saját kernelt fordítottál, és ahogy a fenti grep kimenetéből is látszik, bent van a CONFIG_USB_DEBUG.
  


CONFIG_USB_DEBUG:

  Say Y here if you want the USB core & hub drivers to produce a bunch
  of debug messages to the system log. Select this if you are having a
  problem with USB support and want to see more of what is going on.

  Symbol: USB_DEBUG [=n]
  Type  : boolean
  Prompt: USB verbose debug messages
    Defined at drivers/usb/core/Kconfig:4
    Depends on: USB_SUPPORT [=y] && USB [=m]
    Location:
      -> Device Drivers
        -> USB support (USB_SUPPORT [=y])
          -> Support for Host-side USB (USB [=m])

 
 
(A logfile növekedését meggátolhatod a syslog daemon konfigurálásával is. Persze ez nem a megoldás, hanem a kerülőút lenne.)

Igen, ahogy ide bemásolva végigfutottam, egyből kiszúrtam.
Ezek szerint a DEBUG-dolgok mind kernelüzenetként befutnak a /var/log-ba valahova.
Erről nem tudtam.

Épp most töltöm újra a kernelt, fordítom, .config-om saját manualját bővítem ezzel az információval...

Holnap letesztelek megint mindent.

Köszönöm!

---
--- A gond akkor van, ha látszólag minden működik. ---
---

Mivel a kernel nem egy konnyen debugolhato valami, igy a debug beallitasok egy resze azt celozza, hogy minel reszletesebb informaciokat szallitson a fejlesztoknek a kernel, hogy pontosan meg tudjak hatarozni, mely ponton kell belepni a kernelbe debugolas vegett.

Az innen kijovo dolgok alapbol nem a /var/log -ba kerulnek, hanem a kernel log pufferebe. Ezt a puffert lehet olvasgatni a dmesg parancs segitsegevel. A syslog is ezt a puffert monitorozza, es az uj elemeket lerakja, fuggoen az uzenet tipusatol a /var/log/messages illetve a /var/log/debug naplok egyikebe (vagy mindkettobe).
-- 

Ki oda vagyik, hol szall a galamb, elszalasztja a kincset itt alant. | Gentoo Portal

Kemény.
Átolvasok erről mindent, érdekes.

Ezek szerint ha mondjuk BeFS 64 bites fájlrendszert mountolva azt tapasztalom, hogy nem látok semmit csak Trash-t meg Home-ot, akkor a BeFS debugot bekapcsolva nyomozhatok a hibajelenségen? (Eddig ez be volt kapcsolva, de nem vettem észre, hogy BFS mountolásakor növekedne valami. Nem is figyeltem...)

Ezt találtam a BeFS-re:


CONFIG_BEFS_DEBUG: If you say Y here, you can use the 'debug' mount option to enable
debugging output from the driver.
Symbol: BEFS_DEBUG [=n]
Type :
boolean
Prompt: Debug
BeFS
Defined at
fs/befs/Kconfig:21
Depends on: MISC_FILESYSTEMS [=y] && BEFS_FS [=y]

Location: -> File systems
-> Miscellaneous filesystems (MISC_FILESYSTEMS [=y])
-> BeOS file system (BeFS) support (read only) (EXPERIMENTAL) (BEFS_FS [=y])

...már fordul a kernel.

Rogton nem is fog noveketni, be kell nyulni a mount folyamatba, de igen.

Illetve, azert ne kezdj rogton altalanositani. Nem minden DEBUG kapcsolo logolast kapcsol. Van olyan is, ami pl. bizonyos plusz belepesi pontokat szul, ha a driver epp olyan, hogy lehet neki pl. tesztadatokat is beadni. Illetve sok mas funkcioja is lehet, ezek egy resze dokumentalva van a KConfig-ban, egy mas resze a Documentation/ alatt, ismet mas resze meg csak ugy van. Ez a driver irojanak a vermersekletetol is fugg, hogy mi mennyire dokumentalt.

Szoval mindig olvasd el, mi mit csinal.
-- 

Ki oda vagyik, hol szall a galamb, elszalasztja a kincset itt alant. | Gentoo Portal

Olvasom ahogy bírom, de vannak korlátai az embernek. Egy kernelconfig 70ezer sort tartalmaz, minden sornak külön manualja van, egyelőre ha mindet végig kellene kötelező jelleggel olvasni, akkor injább Victor Hugo Talizmánját veszem elő meg az Ivanhoe-t, az kellemesebb.

Természetesen miután végre sikerült 1 hónap alatt kernelt fordítanom, kincsként őrzöm aa .configot és minden új információt, ami ezzel kapcsolatos.

---
--- A gond akkor van, ha látszólag minden működik. ---
---