search

tbird20d / grabserial

Grabserial - python-based serial dump and timing program - good for embedded Linux development
GNU General Public License v2.0
195 stars 77 forks source link

grabserial stops printing #2

Closed embLin closed 9 years ago

embLin commented 10 years ago

Hi,

I used grabserial for checking the startupcode by using the serial port of my target device, on the host side it's connected to a ttyUSB Port.

When I start grabserial only a few messages are shown and it stops printing out the lines (i know there are more, since i usually communicate with other serial programs like putty, without any problems). The program doesn't quit, it just freezes, i have to break it with [CTRL-C].

For debugging reasons, I added a loop at my startup, and then it printed out everything. So what i guess: the script is a bit slow on processing, since it only prints messages out as long as it physically receives anything. I already tried to force printing out the missing message by some flush commands, without any success.

I like that script, the results come in quiet handy, but unfortunately I can't use it as it is at the moment.

embLin commented 10 years ago

That error didn't show up today. I just tried this version from GitHub. If that happened just by coincidence I'll let you know, else it's working.

embLin commented 10 years ago

no problem is not solved, this morning it worked because it was a very short boottime.

tbird20d commented 10 years ago

I'm not sure how to reproduce this. Can you debug this on your side, by putting the following at the top of the loop, right after x = s.read(), and let me know the results: sys.stdout.write(x) sys.stdout.flush()

Thanks. If you could provide this, as well as an example of what is expected from the program, that would be great.

embLin commented 10 years ago

ah yes i should have added this to the post: here is the output which i can see using the serial terminal "putty":

RomBOOT

Start AT91Bootstrap...

version 0.8 SDCard

Watchdog disabled

GPIO configured

GSM enabled

Ethernet needs to be initialized in Uboot

PMECC disabled...

Init DDR... Done!

Downloading image...

Copy 0x1 bytes from 0x0 to 0x26f00000 Downloading Kernel

Done!

U-Boot 2010.06 (Dec 10 2013 - 10:01:41) version 0.4 SDCARD

I2C: Found AT91 i2c ready DRAM: 128 MiB NAND: Atmel NAND disable PMECC NAND device: Manufacturer ID: 0x01, Chip ID: 0xf1 (Toshiba ) NAND device: Erasesize: 0x20000, Writesize: 0x800 oobsize: 0x40 busw: 0 128 MiB *\ Warning - bad CRC or NAND, using default environment

Found AT91 i2c In: serial Out: serial Err: serial Net: show_boot_progress 64 show_boot_progress 65 macb0 macb0: Starting autonegotiation... macb0: Autonegotiation complete macb0: link up, 100Mbps full-duplex (lpa: 0x41e1) Hit any key to stop autoboot: 1  0 show_boot_progress 1

Booting kernel from Legacy Image at 22000000 ...

show_boot_progress 2 show_boot_progress 3 Image Name: Linux-2.6.39 Image Type: ARM Linux Kernel Image (uncompressed) Data Size: 9229644 Bytes = 8.8 MiB Load Address: 20008000 Entry Point: 20008000 Verifying Checksum ... OK show_boot_progress 4 show_boot_progress 5 show_boot_progress 6 show_boot_progress 14 Loading Kernel Image ... OK OK show_boot_progress 7 show_boot_progress 8 show_boot_progress 15

Starting kernel ...

Uncompressing Linux... done, booting the kernel. Linux version 2.6.39 (gcc version 4.5.2 (Sourcery G++ Lite 2011.03-41) ) #4 Fri Dec 20 10:28:32 CET 2013 CPU: ARM926EJ-S [41069265] revision 5 (ARMv5TEJ), cr=00053177 CPU: VIVT data cache, VIVT instruction cache Machine: Atmel AT91SAM9X5-EK Memory policy: ECC disabled, Data cache writeback Clocks: CPU 400 MHz, master 133 MHz, main 12.000 MHz Built 1 zonelists in Zone order, mobility grouping on. Total pages: 32512 Kernel command line: mem=128M console=ttyAT0,115200 ubi.mtd=User PID hash table entries: 512 (order: -1, 2048 bytes) Dentry cache hash table entries: 16384 (order: 4, 65536 bytes) Inode-cache hash table entries: 8192 (order: 3, 32768 bytes) Memory: 128MB = 128MB total Memory: 106764k/106764k available, 24308k reserved, 0K highmem Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB) DMA : 0xff000000 - 0xffe00000 ( 14 MB) vmalloc : 0xc8800000 - 0xfee00000 ( 870 MB) lowmem : 0xc0000000 - 0xc8000000 ( 128 MB) modules : 0xbf000000 - 0xc0000000 ( 16 MB) .init : 0xc0008000 - 0xc11ee000 (18328 kB) .text : 0xc11ee000 - 0xc1651908 (4495 kB) .data : 0xc1652000 - 0xc1683080 ( 197 kB) NR_IRQS:192 AT91: 128 gpio irqs in 4 banks Console: colour dummy device 80x30 console [ttyS0] enabled Calibrating delay loop... 199.06 BogoMIPS (lpj=995328) pid_max: default: 32768 minimum: 301 Mount-cache hash table entries: 512 CPU: Testing write buffer coherency: ok NET: Registered protocol family 16 AT91: CM rev A AT91: EK rev A AT91: Power Management (with slow clock mode) AT91: Starting after power-restored wakeup bio: create slab at 0 SCSI subsystem initialized usbcore: registered new interface driver usbfs usbcore: registered new interface driver hub usbcore: registered new device driver usb i2c-gpio i2c-gpio.0: using pins 62 (SDA) and 63 (SCL) at_hdmac at_hdmac.0: Atmel AHB DMA Controller ( cpy slave ), 8 channels at_hdmac at_hdmac.1: Atmel AHB DMA Controller ( cpy slave ), 8 channels Advanced Linux Sound Architecture Driver Version 1.0.24. cfg80211: Calling CRDA to update world regulatory domain Switching to clocksource tcb_clksrc NET: Registered protocol family 2 IP route cache hash table entries: 1024 (order: 0, 4096 bytes) TCP established hash table entries: 4096 (order: 3, 32768 bytes) TCP bind hash table entries: 4096 (order: 2, 16384 bytes) TCP: Hash tables configured (established 4096 bind 4096) TCP reno registered UDP hash table entries: 256 (order: 0, 4096 bytes) UDP-Lite hash table entries: 256 (order: 0, 4096 bytes) NET: Registered protocol family 1 RPC: Registered udp transport module. RPC: Registered tcp transport module. RPC: Registered tcp NFSv4.1 backchannel transport module. NetWinder Floating Point Emulator V0.97 (double precision) JFFS2 version 2.2. (NAND) (SUMMARY) © 2001-2006 Red Hat, Inc. msgmni has been set to 208 io scheduler noop registered (default) atmel_usart.0: ttyS0 at MMIO 0xfefff200 (irq = 1) is a ATMEL_SERIAL atmel_usart.1: ttyS1 at MMIO 0xf801c000 (irq = 5) is a ATMEL_SERIAL brd: module loaded loop: module loaded ssc ssc.0: Atmel SSC device at 0xc8870000 (irq 28) atmel_nand atmel_nand: Using dma0chan0 for DMA transfers. nand_get_flash_type: second ID read did not match 30,2f against 00,8d No NAND device found. UBI error: ubi_init: UBI error: cannot initialize UBI, error -2 CAN device driver interface macb macb.0: invalid hw address, using random macb macb.0: eth0: Features changed: 0x00004800 -> 0x00004000 MACB_mii_bus: probed eth0: Atmel MACB at 0xf802c000 irq 24 (9e:d6:23:43:e7:98) eth0: attached PHY driver [Davicom DM9161A](mii_bus:phy_addr=0:00, irq=-1) ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver atmel-ehci atmel-ehci: Atmel EHCI UHP HS atmel-ehci atmel-ehci: new USB bus registered, assigned bus number 1 atmel-ehci atmel-ehci: irq 22, io mem 0x00700000 atmel-ehci atmel-ehci: USB 2.0 started, EHCI 1.00 hub 1-0:1.0: USB hub found hub 1-0:1.0: 3 ports detected ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver at91_ohci at91_ohci: AT91 OHCI at91_ohci at91_ohci: new USB bus registered, assigned bus number 2 at91_ohci at91_ohci: irq 22, io mem 0x00600000 hub 2-0:1.0: USB hub found hub 2-0:1.0: 3 ports detected Initializing USB Mass Storage driver... usbcore: registered new interface driver usb-storage USB Mass Storage support registered. usbcore: registered new interface driver libusual atmel_usba_udc atmel_usba_udc: MMIO registers at 0xf803c000 mapped at c8864000 atmel_usba_udc atmel_usba_udc: FIFO at 0x00500000 mapped at c8900000 input: gpio-keys as /devices/platform/gpio-keys/input/input0 qt1070 0-001b: can not read register, returned -6 qt1070 0-001b: ID -6 not supported at91_rtc at91_rtc: rtc core: registered at91_rtc as rtc0 AT91 Real Time Clock driver. i2c /dev entries driver Linux media interface: v0.10 lirc_dev: IR Remote Control driver registered, major 251 IR NEC protocol handler initialized IR RC5(x) protocol handler initialized IR RC6 protocol handler initialized IR JVC protocol handler initialized IR Sony protocol handler initialized IR RC5 (streamzap) protocol handler initialized IR LIRC bridge handler initialized Linux video capture interface: v2.00 camera 0-0: Probing 0-0 ov2640 0-0030: Product ID error fa:fa usbcore: registered new interface driver usbhid usbhid: USB HID core driver wm8731-codec 0-001a: Failed to issue reset: -6 wm8731-codec 0-001a: asoc: failed to probe CODEC wm8731-codec.0-001a: -6 asoc: failed to instantiate card AT91SAM9X5: -6 ASoC: at91sam9x5ek_init ok ALSA device list: No soundcards found. nf_conntrack version 0.5.0 (1668 buckets, 6672 max) ip_tables: (C) 2000-2006 Netfilter Core Team TCP cubic registered NET: Registered protocol family 17 can: controller area network core (rev 20090105 abi 8) NET: Registered protocol family 29 can: raw protocol (rev 20090105) lib80211: common routines for IEEE802.11 drivers Registering the dns_resolver key type at91_rtc at91_rtc: setting system clock to 2007-01-24 20:49:33 UTC (1169671773) atmel_mci atmel_mci.0: Using dma0chan0 for DMA transfers atmel_mci atmel_mci.0: Atmel MCI controller at 0xf0008000 irq 12, 1 slots atmel_mci atmel_mci.1: Using dma1chan0 for DMA transfers atmel_mci atmel_mci.1: Atmel MCI controller at 0xf000c000 irq 26, 1 slots Freeing init memory: 18328K mknod: /dev/null: File exists Populating /dev using udev: udevd (551): /proc/551/oom_adj is deprecated, please use /proc/551/oom_score_adj instead. done Starting portmap: done Initializing random number generator... done. ALSA: Restoring mixer settings... Starting network... Starting dropbear sshd: generating rsa key... generating dsa key... OK

Welcome to Buildroot

AT91SAM9 login: root

embLin commented 10 years ago

there goes the output with your script and the sys.stdout.write(x), sys.stdout.flush():

$ grabserial -v -d "/dev/ttyUSB0" -b 115200 -w 8 -p N -s 1 -t Opening serial port /dev/ttyUSB0 115200:8N1:xonxoff=0:rtcdtc=0 Printing timing information for each line Use Control-C to stop... DDoonnee!!0.000003]

[2.054873 2.054870]

[2.058914 0.004041] U[2.060884 0.001970] U--BBoooott 22001100..0066 ((DDeecc 1100 22001133 -- 1100::0011::4411)) T[2.142908 0.082024] vveerrssiioonn 00..44 SSDDCCAARRDD [2.199486 0.056578] I[2.199866 0.000380] I22CC:: FFoouunndd AATT9911 ii22cc
r[2.250103 0.050237] reeaaddyy D[2.263908 0.013805] DRRAAMM:: 112288 MMiiBB N[2.295844 0.031936] NAANNDD:: AAttmmeell NNAANNDD ddiissaabbllee PPMMEECCCC N[2.361839 0.065995] NAANNDD ddeevviiccee:: MMaannuuffaaccttuurreerr IIDD:: 00xx0011,, CChhiipp IIDD:: 00xxff11 ((TToosshhiibbaa <>)) N[2.503921 0.142082] NAANNDD ddeevviiccee:: EErraasseessiizzee:: 00xx2200000000,, WWrriitteessiizzee:: 00xx880000 oooobbssiizzee:: 00xx4400 bbuussww:: 00
1[2.654845 0.150924] 12288 MMiiBB [2.672846 0.018001] **\ WWaarrnniinngg -- bbaadd CCRRCC oorr NNAANNDD,, uussiinngg ddeeffaauulltt eennvviirroonnmmeenntt

[2.792895 0.120049] F[2.795102 0.002207] Foouunndd AATT9911 ii22cc
I[2.829050 0.033948] Inn:: sseerriiaall O[2.859009 0.029959] Ouutt:: sseerriiaall E[2.889099 0.030090] Errrr:: sseerriiaall N[2.919070 0.029971] Neett:: sshhoowwbboooottpprrooggrreessss 6644 s[2.978849 0.059779] shhoowwbboooottpprrooggrreessss 6655 m[3.024861 0.046012] maaccbb00 m[3.038848 0.013987] maaccbb00:: SSttaarrttiinngg aauuttoonneeggoottiiaattiioonn...... m[3.110906 0.072058] maaccbb00:: AAuuttoonneeggoottiiaattiioonn ccoommpplleettee m[3.182886 0.071980] maaccbb00:: lliinnkk uupp,, 110000MMbbppss ffuullll--dduupplleexx ((llppaa:: 00xx4411ee11)) H[3.284978 0.102092] Hiitt aannyy kkeeyy ttoo ssttoopp aauuttoobboooott:: 00
s[3.609900 0.324922] shhoowwbboooottpprrooggrreessss 11

[3.653989 0.044089] ### BBoooottiinngg kkeerrnneell ffrroomm LLeeggaaccyy IImmaaggee aatt 2222000000000000 ......

s[3.759919 0.105930] shhoowwbboooottpprrooggrreessss 22 s[3.803987 0.044068] shhoowwbboooottpprrooggrreessss 33 [3.847902 0.043915] IImmaaggee NNaammee:: LLiinnuuxx--22..66..3399 [3.910094 0.062192] IImmaaggee TTyyppee:: AARRMM LLiinnuuxx KKeerrnneell IImmaaggee ((uunnccoommpprreesssseedd)) [4.021909 0.111815] DDaattaa SSiizzee:: 99222299664444 BByytteess == 88..88 MMiiBB [4.105907 0.083998] LLooaadd AAddddrreessss:: 2200000088000000 [4.159896 0.053989] EEnnttrryy PPooiinntt:: 2200000088000000 [4.213925 0.054029] VVeerriiffyyiinngg CChheecckkssuumm ...... OOKK s[4.798901 0.584976] shhoowwbboooottpprrooggrreessss 44 s[4.842918 0.044017] shhoowwbboooottpprrooggrreessss 55 s[4.886989 0.044071] shhoowwbboooottpprrooggrreessss 66 s[4.930967 0.043978] shhoowwbboooottpprrooggrreessss 1144 [4.976912 0.045945] LLooaaddiinngg KKeerrnneell IImmaaggee ...... OOKK

O[6.626894 1.649982] OKK s[6.634881 0.007987] shhoowwbboooottpprrooggrreessss 77 s[6.678862 0.043981] shhoowwbboooottpprrooggrreessss 88 s[6.722920 0.044058] shhoowwbboooottpprrooggrreessss 1155

[6.771005 0.048085] S[6.772947 0.001942] Sttaarrttiinngg kkeerrnneell ......

[6.817021 0.044074] U[6.818972 0.001951] Unnccoommpprreessssiinngg LLiinnuuxx...... ddoonnee,, bboooottiinngg tthhee kkeerrnneell.. L[8.482000 1.663028] Liinnuuxx vveerrssiioonn 22..66..3399 ((ggcccc vveerrssiioonn 44..55..22 ((SSoouurrcceerryy GG++++ LLiittee 22001111..0033--4411)) )) ##44 FFrrii DDeecc 2200 1100::2288::3322 CCEETT 22001133

C[8.777920 0.295920] CPPUU:: AARRMM992266EEJJ--SS [[4411006699226655]] rreevviissiioonn 55 ((AARRMMvv55TTEEJJ)),, ccrr==0000005533117777 C[8.903951 0.126031] CPPUU:: VVIIVVTT ddaattaa ccaacchhee,, VVIIVVTT iinnssttrruuccttiioonn ccaacchhee M[8.995892 0.091941] Maacchhiinnee:: AAttmmeell AATT9911SSAAMM99XX55--EEKK M[9.055911 0.060019] Meemmoorryy ppoolliiccyy:: EECCCC ddiissaabblleedd,, DDaattaa ccaacchhee wwrriitteebbaacckk C[9.157895 0.101984] Clloocckkss:: CCPPUU 440000 MMHHzz,, mmaasstteerr 113333 MMHHzz,, mmaaiinn 1122..000000 MMHHzz B[9.265926 0.108031] Buuiilltt 11 zzoonneelliissttss iinn ZZoonnee oorrddeerr,, mmoobbiilliittyy ggrroouuppiinngg oonn.. TToottaall ppaaggeess:: 3322551122 K[9.417911 0.151985] Keerrnneell ccoommmmaanndd lliinnee:: mmeemm==112288MM ccoonnssoollee==ttttyyAATT00,,111155220000 uubbii..mmttdd==UUsseerr
P[9.551958 0.134047] PIIDD hhaasshh ttaabbllee eennttrriieess:: 551122 ((oorrddeerr:: --11,, 22004488 bbyytteess)) D[9.660904 0.108946] Deennttrryy ccaacchhee hhaasshh ttaabbllee eennttrriieess:: 1166338844 ((oorrddeerr:: 44,, 6655553366 bbyytteess)) I[9.790881 0.129977] Innooddee--ccaacchhee hhaasshh ttaabbllee eennttrriieess:: 88119922 ((oorrddeerr:: 33,, 3322776688 bbyytteess)) M[9.914928 0.124047] Meemmoorryy:: 112288MMBB == 112288MMBB ttoottaall M[9.975919 0.060991] Meemmoorryy:: 110066776644kk//110066776644kk aavvaaiillaabbllee,, 2244330088kk rreesseerrvveedd,, 00KK hhiigghhmmeemm V[10.113889 0.137970] Viirrttuuaall kkeerrnneell mmeemmoorryy llaayyoouutt:: [10.179906 0.066017] vveeccttoorr :: 00xxffffffff00000000 -- 00xxffffffff11000000 (( 44 kkBB)) [10.285934 0.106028] ffiixxmmaapp :: 00xxffffff0000000000 -- 00xxffffffee00000000 (( 889966 kkBB)) [10.397663 0.111729] DDMMAA :: 00xxffff000000000000 -- 00xxffffee0000000000 (( 1144 MMBB)) [10.497905 0.100242] vvmmaalllloocc :: 00xxcc88880000000000 -- 00xxffeeee0000000000 (( 887700 MMBB)) [10.605620 0.107715] lloowwmmeemm :: 00xxcc00000000000000 -- 00xxcc88000000000000 (( 112288 MMBB)) [10.707932 0.102312] mmoodduulleess :: 00xxbbff000000000000 -- 00xxcc00000000000000 (( 1166 MMBB)) [10.814934 0.107002] ..iinniitt :: 00xxcc00000088000000 -- 00xxcc1111eeee000000 ((1188332288 kkBB)) [10.922919 0.107985] ..tteexxtt :: 00xxcc1111eeee000000 -- 00xxcc11665511990088 ((44449955 kkBB)) [11.037690 0.114771] ..ddaattaa :: 00xxcc11665522000000 -- 00xxcc11668833008800 (( 119977 kkBB)) N[11.138913 0.101223] NRRIIRRQQSS::119922 A[11.169961 0.031048] ATT9911:: 112288 ggppiioo iirrqqss iinn 44 bbaannkkss C[11.238989 0.069028] Coonnssoollee:: ccoolloouurr dduummmmyy ddeevviiccee 8800xx3300 c[11.313913 0.074924] coonnssoollee [[ttttyySS00]] eennaabblleedd C[11.368971 0.055058] Caalliibbrraattiinngg ddeellaayy lloooopp...... 119999..0066 BBooggooMMIIPPSS ((llppjj==999955332288)) p[11.485918 0.116947] piiddmmaaxx:: ddeeffaauulltt:: 3322776688 mmiinniimmuumm:: 330011 M[11.566906 0.080988] Moouunntt--ccaacchhee hhaasshh ttaabbllee eennttrriieess:: 551122 C[11.644921 0.078015] CPPUU:: TTeessttiinngg wwrriittee bbuuffffeerr ccoohheerreennccyy:: ookk N[11.731920 0.086999] NEETT:: RReeggiisstteerreedd pprroottooccooll ffaammiillyy 1166 A[11.808906 0.076986] ATT9911:: CCMM rreevv AA A[11.843969 0.035063] ATT9911:: EEKK rreevv AA A[11.880947 0.036978] ATT9911:: PPoowweerr MMaannaaggeemmeenntt ((wwiitthh ssllooww cclloocckk mmooddee)) A[11.978902 0.097955] ATT9911:: SSttaarrttiinngg aafftteerr ppoowweerr--rreessttoorreedd wwaakkeeuupp b[12.067903 0.089001] biioo:: ccrreeaattee ssllaabb <> aatt 00 S[12.134921 0.067018] SCCSSII ssuubbssyysstteemm iinniittiiaalliizzeedd u[12.194017 0.059096] ussbbccoorree:: rreeggiisstteerreedd nneeww iinntteerrffaaccee ddrriivveerr uussbbffss u[12.295005 0.100988] ussbbccoorree:: rreeggiisstteerreedd nneeww iinntteerrffaaccee ddrriivveerr hhuubb u[12.390901 0.095896] ussbbccoorree:: rreeggiisstteerreedd nneeww ddeevviiccee ddrriivveerr uussbb i[12.479941 0.089040] i22cc--ggppiioo ii22cc--ggppiioo..00:: uussiinngg ppiinnss 6622 ((SSDDAA)) aanndd 6633 ((SSCCLL)) a[12.593894 0.113953] atthhddmmaacc aatthhddmmaacc..00:: AAttmmeell AAHHBB DDMMAA CCoonnttrroolllleerr (( ccppyy ssllaavvee )),, 88 cchhaannnneellss a[12.745889 0.151995] atthhddmmaacc aatthhddmmaacc..11:: AAttmmeell AAHHBB DDMMAA CCoonnttrroolllleerr (( ccppyy ssllaavvee )),, 88 cchhaannnneellss A[12.892988 0.147099] Addvvaanncceedd LLiinnuuxx SSoouunndd AArrcchhiitteeccttuurree DDrriivveerr VVeerrssiioonn 11..00..2244.. c[13.008906 0.115918] cffgg8800221111:: CCaalllliinngg CCRRDDAA ttoo uuppddaattee wwoorrlldd rreegguullaattoorryy ddoommaaiinn S[13.127909 0.119003] Swwiittcchhiinngg ttoo cclloocckkssoouurrccee ttccbbccllkkssrrcc N[13.205908 0.077999] NEETT:: RReeggiisstteerreedd pprroottooccooll ffaammiillyy 22 I[13.280927 0.075019] IPP rroouuttee ccaacchhee hhaasshh ttaabbllee eennttrriieess:: 11002244 ((oorrddeerr:: 00,, 44009966 bbyytteess)) T[13.414905 0.133978] TCCPP eessttaabblliisshheedd hhaasshh ttaabbllee eennttrriieess:: 44009966 ((oorrddeerr:: 33,, 3322776688 bbyytteess)) T[13.549907 0.135002] TCCPP bbiinndd hhaasshh ttaabbllee eennttrriieess:: 44009966 ((oorrddeerr:: 22,, 1166338844 bbyytteess)) T[13.669879 0.119972] TCCPP:: HHaasshh ttaabblleess ccoonnffiigguurreedd ((eessttaabblliisshheedd 44009966 bbiinndd 44009966)) T[13.790940 0.121061] TCCPP rreennoo rreeggiisstteerreedd U[13.834944 0.044004] UDDPP hhaasshh ttaabbllee eennttrriieess:: 225566 ((oorrddeerr:: 00,, 44009966 bbyytteess)) U[13.941907 0.106963] UDDPP--LLiittee hhaasshh ttaabbllee eennttrriieess:: 225566 ((oorrddeerr:: 00,, 44009966 bbyytteess)) N[14.065907 0.124000] NEETT:: RReeggiisstteerreedd pprroottooccooll ffaammiillyy 11 R[14.135983 0.070076] RPPCC:: RReeggiisstteerreedd uuddpp ttrraannssppoorrtt mmoodduullee.. R[14.213902 0.077919] RPPCC:: RReeggiisstteerreedd ttccpp ttrraannssppoorrtt mmoodduullee.. R[14.291905 0.078003] RPPCC:: RReeggiisstteerreedd ttccpp NNFFSSvv44..11 bbaacckkcchhaannnneell ttrraannssppoorrtt mmoodduullee.. N[14.409906 0.118001] NeettWWiinnddeerr FFllooaattiinngg PPooiinntt EEmmuullaattoorr VV00..9977 ((ddoouubbllee pprreecciissiioonn)) J[14.529915 0.120009] JFFFFSS22 vveerrssiioonn 22..22.. ((NNAANNDD)) ((SSUUMMMMAARRYY)) �©� 22000011--22000066 RReedd HHaatt,, IInncc.. m[14.659928 0.130013] mssggmmnnii hhaass bbeeeenn sseett ttoo 220088 i[14.715912 0.055984] ioo sscchheedduulleerr nnoooopp rreeggiisstteerreedd ((ddeeffaauulltt)) a[14.795928 0.080016] attmmeelluussaarrtt..00:: ttttyySS00 aatt MMMMIIOO 00xxffeeffffff220000 ((iirrqq == 11)) iiss aa AATTMMEELLSSEERRIIAALL a[14.933935 0.138007] attmmeelluussaarrtt..11:: ttttyySS11 aatt MMMMIIOO 00xxff880011cc000000 ((iirrqq == 55)) iiss aa AATTMMEELLSSEERRIIAALL b[15.071900 0.137965] brrdd:: mmoodduullee llooaaddeedd l[15.111997 0.040097] loooopp:: mmoodduullee llooaaddeedd s[15.153987 0.041990] ssscc sssscc..00:: AAttmmeell SSSSCC ddeevviiccee aatt 00xxcc88887700000000 ((iirrqq 2288)) a[15.257909 0.103922] attmmeellnnaanndd aattmmeellnnaanndd:: UUssiinngg ddmmaa00cchhaann00 ffoorr DDMMAA ttrraannssffeerrss.. n[15.375931 0.118022] naannddggeettffllaasshhttyyppee:: sseeccoonndd IIDD rreeaadd ddiidd nnoott mmaattcchh 3300,,22ff aaggaaiinnsstt 0000,,88dd N[15.517919 0.141988] Noo NNAANNDD ddeevviiccee ffoouunndd.. U[15.563998 0.046079] UBBII eerrrroorr:: uubbiiiinniitt:: UUBBII eerrrroorr:: ccaannnnoott iinniittiiaalliizzee UUBBII,, eerrrroorr --22 C[15.693891 0.129893] CAANN ddeevviiccee ddrriivveerr iinntteerrffaaccee m[15.751923 0.058032] maaccbb mmaaccbb..00:: iinnvvaalliidd hhww aaddddrreessss,, uussiinngg rraannddoomm m[15.848931 0.097008] maaccbb mmaaccbb..00:: eetthh00:: FFeeaattuurreess cchhaannggeedd:: 00xx0000000044880000 -->> 00xx0000000044000000 M[15.978882 0.129951] MAACCBBmmiiiibbuuss:: pprroobbeedd e[16.027988 0.049106] etthh00:: AAttmmeell MMAACCBB aatt 00xxff880022cc000000 iirrqq 2244 ((7722::0033::eeee::4444::9944::33ee)) e[16.148918 0.120930] etthh00:: aattttaacchheedd PPHHYY ddrriivveerr [[DDaavviiccoomm DDMM99116611AA]](%28mmiiiibbuuss::pphhyyaaddddrr==00::0000,, iirrqq==--11%29) e[16.312870 0.163952] ehhcciihhccdd:: UUSSBB 22..00 ''EEnnhhaanncceedd'' HHoosstt CCoonnttrroolllleerr ((EEHHCCII)) DDrriivveerr a[16.442907 0.130037] attmmeell--eehhccii aattmmeell--eehhccii:: AAttmmeell EEHHCCII UUHHPP HHSS a[16.528997 0.086090] attmmeell--eehhccii aattmmeell--eehhccii:: nneeww UUSSBB bbuuss rreeggiisstteerreedd,, aassssiiggnneedd bbuuss nnuummbbeerr 11 a[16.669907 0.140910] attmmeell--eehhccii aattmmeell--eehhccii:: iirrqq 2222,, iioo mmeemm 00xx0000770000000000 a[16.780579 0.110672] attmmeell--eehhccii aattmmeell--eehhccii:: UUSSBB 22..00 ssttaarrtteedd,, EEHHCCII 11..0000 h[16.885993 0.105414] huubb 11--00::11..00:: UUSSBB hhuubb ffoouunndd h[16.946931 0.060938] huubb 11--00::11..00:: 33 ppoorrttss ddeetteecctteedd o[17.013917 0.066986] ohhcciihhccdd:: UUSSBB 11..11 ''OOppeenn'' HHoosstt CCoonnttrroolllleerr ((OOHHCCII)) DDrriivveerr a[17.128874 0.114957] att9911oohhccii aatt9911oohhccii:: AATT9911 OOHHCCII a[17.196952 0.068078] att9911oohhccii aatt9911oohhccii:: nneeww UUSSBB bbuuss rreeggiisstteerreedd,, aassssiiggnneedd bbuuss nnuummbbeerr 22 a[17.336884 0.139932] att9911oohhccii aatt9911oohhccii:: iirrqq 2222,, iioo mmeemm 00xx0000660000000000 h[17.438908 0.102024] huubb 22--00::11..00:: UUSSBB hhuubb ffoouunndd h[17.494910 0.056002] huubb 22--00::11..00:: 33 ppoorrttss ddeetteecctteedd I[17.556978 0.062068] Inniittiiaalliizziinngg UUSSBB MMaassss SSttoorraaggee ddrriivveerr...... u[17.638907 0.081929] ussbbccoorree:: rreeggiisstteerreedd nneeww iinntteerrffaaccee ddrriivveerr uussbb--ssttoorraaggee U[17.751964 0.113057] USSBB MMaassss SSttoorraaggee ssuuppppoorrtt rreeggiisstteerreedd.. u[17.827909 0.075945] ussbbccoorree:: rreeggiisstteerreedd nneeww iinntteerrffaaccee ddrriivveerr lliibbuussuuaall a[17.929883 0.101974] attmmeelluussbbaauuddcc aattmmeelluussbbaauuddcc:: MMMMIIOO rreeggiisstteerrss aatt 00xxff880033cc000000 mmaappppeedd aatt cc88886644000000 a[18.093908 0.164025] attmmeelluussbbaauuddcc aattmmeelluussbbaauuddcc:: FFIIFFOO aatt 00xx0000550000000000 mmaappppeedd aatt cc88990000000000 i[18.239012 0.145104] innppuutt:: ggppiioo--kkeeyyss aass //ddeevviicceess//ppllaattffoorrmm//ggppiioo--kkeeyyss//iinnppuutt//iinnppuutt00 q[18.362970 0.123958] qtt11007700 00--000011bb:: ccaann nnoott rreeaadd rreeggiisstteerr,, rreettuurrnneedd --66 q[18.467902 0.104932] qtt11007700 00--000011bb:: IIDD --66 nnoott ssuuppppoorrtteedd a[18.543944 0.076042] att9911rrttcc aatt9911rrttcc:: rrttcc ccoorree:: rreeggiisstteerreedd aatt9911rrttcc aass rrttcc00 A[18.662920 0.118976] ATT9911 RReeaall TTiimmee CClloocckk ddrriivveerr.. i[18.729897 0.066977] i22cc //ddeevv eennttrriieess ddrriivveerr L[18.784904 0.055007] Liinnuuxx mmeeddiiaa iinntteerrffaaccee:: vv00..1100 l[18.848893 0.063989] liirrccddeevv:: IIRR RReemmoottee CCoonnttrrooll ddrriivveerr rreeggiisstteerreedd,, mmaajjoorr 225511
I[18.972912 0.124019] IRR NNEECC pprroottooccooll hhaannddlleerr iinniittiiaalliizzeedd I[19.046895 0.073983] IRR RRCC55((xx)) pprroottooccooll hhaannddlleerr iinniittiiaalliizzeedd I[19.126905 0.080010] IRR RRCC66 pprroottooccooll hhaannddlleerr iinniittiiaalliizzeedd I[19.200841 0.073936] IRR JJVVCC pprroottooccooll hhaannddlleerr iinniittiiaalliizzeedd I[19.274853 0.074012] IRR SSoonnyy pprroottooccooll hhaannddlleerr iinniittiiaalliizzeedd I[19.350907 0.076054] IRR RRCC55 ((ssttrreeaammzzaapp)) pprroottooccooll hhaannddlleerr iinniittiiaalliizzeedd I[19.448998 0.098091] IRR LLIIRRCC bbrriiddggee hhaannddlleerr iinniittiiaalliizzeedd L[19.520924 0.071926] Liinnuuxx vviiddeeoo ccaappttuurree iinntteerrffaaccee:: vv22..0000 c[19.596903 0.075979] caammeerraa 00--00:: PPrroobbiinngg 00--00 o[19.648745 0.051842] ovv22664400 00--00003300:: PPrroodduucctt IIDD eerrrroorr ffaa::ffaa u[19.729951 0.081206] ussbbccoorree:: rreeggiisstteerreedd nneeww iinntteerrffaaccee ddrriivveerr uussbbhhiidd u[19.830884 0.100933] ussbbhhiidd:: UUSSBB HHIIDD ccoorree ddrriivveerr w[19.889902 0.059018] wmm88773311--ccooddeecc 00--000011aa:: FFaaiilleedd ttoo iissssuuee rreesseett:: --66 w[19.990919 0.101017] wmm88773311--ccooddeecc 00--000011aa:: aassoocc:: ffaaiilleedd ttoo pprroobbee CCOODDEECC wwmm88773311--ccooddeecc..00--000011aa:: --66 a[20.143904 0.152985] assoocc:: ffaaiilleedd ttoo iinnssttaannttiiaattee ccaarrdd AATT9911SSAAMM99XX55:: --66 A[20.250903 0.106999] ASSooCC:: aatt9911ssaamm99xx55eekkiinniitt ookk A[20.314913 0.064010] ALLSSAA ddeevviiccee lliisstt:: [20.357905 0.042992] NNoo ssoouunnddccaarrddss ffoouunndd.. n[20.409144 0.051239] nffccoonnnnttrraacckk vveerrssiioonn 00..55..00 ((11666688 bbuucckkeettss,, 66667722 mmaaxx)) i[20.520923 0.111779] ippttaabblleess:: ((CC)) 22000000--22000066 NNeettffiilltteerr CCoorree TTeeaamm T[20.617898 0.096975] TCCPP ccuubbiicc rreeggiisstteerreedd N[20.665902 0.048004] NEETT:: RReeggiisstteerreedd pprroottoocc

embLin commented 10 years ago

as mentioned above, trying to use the flush command didn't solve the problem. BTW the last line printed out is not complete

embLin commented 10 years ago

I made a small image, my system boots within about 5 s (counted by myself ;) and grabserial still doesn't get all the output but the latest lines tell me it took already 15 s - has anyone checked if the timestamps are alright? or is it more a relative value. Anyways, I'm not sure anymore if this problem occurs because of grabserial, the transmission, my system or whatever

embLin commented 10 years ago

i tried this method:

cat /dev/ttyUSB0 | ts [%H.%M.%.S] > ${FILENAME}

and it's working (measures the right time and until i break it). This is getting more and more confusing :/

tbird20d commented 9 years ago

Sorry we couldn't get to the bottom of this. I've seen strange stuff when there were other readers of the serial port active (and they are consuming some of the characters from the device). This report is, by now, quite old. I'm closing this issue, as I can't reproduce it, and don't have any way to proceed. -- Tim