cs: pcmcia_socket0: unable to apply power.
pccard: PCMCIA card inserted into slot 0
cs: memory probe 0x0c0000-0x0fffff: excluding 0xc0000-0xcbfff 0xdc000-0xfffff
cs: memory probe 0x60000000-0x60ffffff: clean.
cs: memory probe 0xa0000000-0xa0ffffff: clean.
pcmcia: registering new device pcmcia0.0
cardmgr[4791]: unsupported card in socket 0
pc_debug = 0
vmb_hcd: driver vmb_hcd $Revision: 1.3 $ built at 14:28:10 on Nov  9 2007
cardmgr[4791]:   product info: "NetIndex", "D01NX", "", ""
vmb_cs: driver vmb_cs $Revision: 1.3 $ built at 14:28:05 on Nov  9 2007
vmb_cs: probing card with ID=c036:1003
pcmcia_request_io: base=0100(8) aligned=3 res=d41c9580 OK
vmb_cs: MEM 60001000 --> 60001fff  60001000 + 1000
pcmcia_request_window: base=60001000(4096) res=d41c9560 OK
pcmcia_map_mem_page: offset=00000000 OK
pcmcia: request for exclusive IRQ could not be fulfilled.
pcmcia: the driver needs updating to supported shared IRQ lines.
vmb_cs: index 0x35: Vcc 3.3, Vpp 3.3, irq 11, io 0x0100-0x0107, mem 0x60001000-0x60001fff
vmb_hcd vmb_hcd: ELAN VMB Host Controller
vmb_hcd vmb_hcd: new USB bus registered, assigned bus number 3
vmb_hcd vmb_hcd: irq 11, io mem 0x60001000
usb usb3: configuration #1 chosen from 1 choice
hub 3-0:1.0: USB hub found
hub 3-0:1.0: 2 ports detected
3-V:SetPortFeature~1 = 8 ~ POWER
3-V:SetPortFeature~2 = 8 ~ POWER
3-V:GetStatus~1 = 00030100
3-V:ClearPortFeature~1 = 16 ~ C_CONNECTION
3-V:ClearPortFeature~1 = 17 ~ C_ENABLE
3-V:GetStatus~1 = 00000100
3-V:GetStatus~1 = 00000100
3-V:GetStatus~1 = 00000100
3-V:GetStatus~1 = 00000100
3-V:GetStatus~1 = 00000100
3-V:GetStatus~2 = 00030100
3-V:ClearPortFeature~2 = 16 ~ C_CONNECTION
3-V:ClearPortFeature~2 = 17 ~ C_ENABLE
3-V:GetStatus~2 = 00000100
3-V:GetStatus~2 = 00000100
3-V:GetStatus~2 = 00000100
3-V:GetStatus~2 = 00000100
3-V:GetStatus~2 = 00000100
3-V:status_data 02
3-V:GetStatus~1 = 00010101
3-V:ClearPortFeature~1 = 16 ~ C_CONNECTION
3-V:GetStatus~1 = 00000101
3-V:GetStatus~1 = 00000101
3-V:GetStatus~1 = 00000101
3-V:GetStatus~1 = 00000101
3-V:GetStatus~1 = 00000101
3-V:SetPortFeature~1 = 4 ~ RESET
3-V:GetStatus~1 = 00000103
3-V:ClearPortFeature~1 = 20 ~ C_RESET
usb 3-1: new full speed USB device using vmb_hcd and address 2
3-V:SetPortFeature~1 = 4 ~ RESET
3-V:GetStatus~1 = 00000103
3-V:ClearPortFeature~1 = 20 ~ C_RESET
usb 3-1: configuration #1 chosen from 1 choice
cdc_acm 3-1:1.0: ttyACM0: USB ACM device
ASSERTION((((1<<(2+4))-1) & slot->next_urb_index) == (((1<<(2+4))-1) & (slot->queue_next-slot->queue_size))) in plan_frame at /tmp/vmb-driver-1.4/vmb_hcd/vmb_sched.c:855
[<d79fa44c>] plan_frame+0x7cd/0xcc6 [vmb_hcd]
[<d7a0b692>] vmb_scheduler+0x1d1d/0x787b [vmb_hcd]
[<c017b19e>] proc_alloc_inode+0x39/0x55
[<c01626f4>] iget_locked+0x5e/0x111
[<c01146b6>] __wake_up+0x32/0x43
[<c012642b>] insert_work+0x61/0x65
[<c012671c>] __queue_work+0x1c/0x28
[<c0126777>] queue_work+0x33/0x3c
[<d7a15fca>] vmb_scheduler_event+0x0/0x327 [vmb_hcd]
[<d7a1621e>] vmb_scheduler_event+0x254/0x327 [vmb_hcd]
[<c0126324>] run_workqueue+0x76/0xf6
[<c01290fc>] prepare_to_wait+0x12/0x49
[<c0126a4a>] worker_thread+0x0/0xc6
[<c0126b04>] worker_thread+0xba/0xc6
[<c0128fe9>] autoremove_wake_function+0x0/0x35
[<c0128f23>] kthread+0x38/0x5d
[<c0128eeb>] kthread+0x0/0x5d
[<c01058bf>] kernel_thread_helper+0x7/0x10
=======================
vmb_scheduler:4(frame FC=05D1 T=05D0 H=05D1) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=05D1 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=05D1 00000103*00000100)
N P -> D:EP TLER  type mx --urb--- --usb--- (slot_count=F next_slot=0000000D max=03000000 TxR=00C6 TxW=00C6)
C 1 -> 2:00 0080  out  64 cf72d740 d5769400 E_C=71 ID=1 01:01 QS=1 EC=0 IT=1 AC=1 RE=0 RT=0 RU=1 length(A=7 T=7 M=64) status=6
Frame=05D1 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
vmb_scheduler:2717(FC=05D1 T=05D0 H=05D1 rxR=014E rxW=014E txR=00C6 txW=00C6 txL=0000 txT=0000 txPC=00)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=06CC 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=06CC 00000103*00000100)
N P -> D:EP TLER  type mx --urb--- --usb--- (slot_count=F next_slot=0000000D max=03000000 TxR=00C6 TxW=00C6)
C 1 -> 2:00 0080  out  64 cf72d740 d5769400 E_C=71 ID=1 01:01 QS=1 EC=0 IT=1 AC=1 RE=0 RT=0 RU=1 length(A=7 T=7 M=64) status=6
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=06CC T=05D0 H=05D1 R=00C6 W=00C6) size=1 done=252 
vmb_scheduler:1(frame FC=06CC T=05D0 H=05D1-->06CC F=00C6) size=252 done=252
done_frame(TD=C idref=2:1 queue=1 running=1 actioned=1 frame_tail=05D0)
TD=C TLER=0000 write_tler(/tmp/vmb-driver-1.4/vmb_hcd/vmb_sched.c # 558 in function done_frame)
done_frame(TD=C CONTROL_ACK IN urb=cf72d740 len=7 idref=0 queue=1)
vmb_scheduler:3(frame FC=06CC T=06CB H=06CC) size=1 done=1 
vmb_scheduler:4(frame FC=06CC T=06CB H=06CC) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=06CC budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=06CD 00000103*00000100)
N P -> D:EP TLER  type mx --urb--- --usb--- (slot_count=F next_slot=0000000D max=03000000 TxR=00C6 TxW=00C6)
C 1 -> 2:00 0000 ----- 64 cf72d740 d5769400 E_C=74 ID=0 01:01 QS=1 EC=0 IT=1 AC=0 RE=0 RT=0 RU=0 length(A=7 T=7 M=64) status=6
Frame=06CC bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
fill_input(urb=cf72d740 slot=C CONTROL_IN actioned=0 changes=0)
TD=C TLER=00B1 write_tler(/tmp/vmb-driver-1.4/vmb_hcd/vmb_sched.c # 1309 in function fill_input)
fill_input            1440(CR=01 SR=04 TL=00 TP=00 PC=11 FC=06CD 00000103*00000100)
N P -> D:EP TLER  type mx --urb--- --usb--- (slot_count=F next_slot=0000000D max=03000000 TxR=00C6 TxW=00C6)
C 1 -> 2:00 00B1 I_SPD 64 cf72d740 d5769400 E_C=74 ID=0 01:01 QS=1 EC=0 IT=1 AC=1 RE=0 RT=0 RU=1 length(A=7 T=7 M=64) status=6
API:vmb_scheduler_event(completed)
vmb_hcd_irq(REALLY FOR US)
vmb_hcd_irq            432(CR=01 SR=0C TL=00 TP=00 PC=11 FC=06CE 00000103*00000100)
vmb_hcd_irq(RX THRESH)
vmb_hcd_irq() HANDLED
vmb_rxthresh_event    4241(CR=01 SR=0C TL=00 TP=00 PC=11 FC=06CE 00000103*00000100)
N P -> D:EP TLER  type mx --urb--- --usb--- (slot_count=F next_slot=0000000D max=03000000 TxR=00C6 TxW=00C6)
C 1 -> 2:00 00B0 i_spd 64 cf72d740 d5769400 E_C=74 ID=0 01:01 QS=1 EC=0 IT=1 AC=1 RE=0 RT=0 RU=1 length(A=7 T=7 M=64) status=6
vmb_rxthresh_event(RX THRESH) kicked=E worked=E count=1 rxR = C150 rxW = 0154 length=6
vmb_check_rx_fifo(urb=cf72d740 error_count=74 INPUT PACKET crc=0000 utle=C frame=06CE data01=1)
TD=12 urb=cf72d740 input_packet(0 bytes)=
rx_packet_received(CONTROL_OUT ACK IN packet.length=0)
TD=C TLER=0000 write_tler(/tmp/vmb-driver-1.4/vmb_hcd/vmb_sched.c # 3562 in function rx_packet_received)
vmb_urb_completed(urb=cf72d740 error_count=74 len=7 2~OUTPUT->0 80 25 00 00 00 00 08)
rx_packet_received:3568(TD=C usb_hcd_giveback_urb=cf72d740 E_C=74 error_count=0) actual_length=7 result status=0
vmb_urb_completed(stacking urb=cf72d740 onto giveback queue) queue size = 1
rx_packet_received    3585(CR=01 SR=04 TL=00 TP=00 PC=11 FC=06CE 00000103*00000100)
vmb_check_rx_fifo() 1 packets completed
vmb_giveback_urbs(removing urb=cf72d740 from giveback queue) queue size = 0
usbcore: registered new interface driver cdc_acm
drivers/usb/class/cdc-acm.c: v0.25:USB Abstract Control Model driver for USB modems and ISDN adapters
vmb_diagnostics_event 5348(CR=01 SR=04 TL=00 TP=00 PC=11 FC=06D8 00000103*00000100)
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=06D8 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=06D8 T=06CB H=06CC R=00C6 W=00C6) size=1 done=13 
vmb_scheduler:1(frame FC=06D8 T=06CB H=06CC-->06D8 F=00C6) size=13 done=13
vmb_scheduler:3(frame FC=06D8 T=06D7 H=06D8) size=1 done=1 
vmb_scheduler:4(frame FC=06D8 T=06D7 H=06D8) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=06D8 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=06D9 00000103*00000100)
Frame=06D8 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
vmb_scheduler:2717(FC=06D9 T=06D7 H=06D8 rxR=0154 rxW=0154 txR=00C6 txW=00C6 txL=0000 txT=0000 txPC=00)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=07C8 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=07C8 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=07C8 T=06D7 H=06D8 R=00C6 W=00C6) size=1 done=241 
vmb_scheduler:1(frame FC=07C8 T=06D7 H=06D8-->07C8 F=00C6) size=241 done=241
vmb_scheduler:3(frame FC=07C8 T=07C7 H=07C8) size=1 done=1 
vmb_scheduler:4(frame FC=07C8 T=07C7 H=07C8) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=07C8 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=07C9 00000103*00000100)
Frame=07C8 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=00C4 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=00C4 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=00C4 T=07C7 H=07C8 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=00C4 T=07C7 H=07C8-->00C4 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=00C4 T=00C3 H=00C4) size=1 done=1 
vmb_scheduler:4(frame FC=00C4 T=00C3 H=00C4) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=00C4 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=00C5 00000103*00000100)
Frame=00C4 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=01C0 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=01C0 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=01C0 T=00C3 H=00C4 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=01C0 T=00C3 H=00C4-->01C0 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=01C0 T=01BF H=01C0) size=1 done=1 
vmb_scheduler:4(frame FC=01C0 T=01BF H=01C0) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=01C0 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=01C1 00000103*00000100)
Frame=01C0 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=02BC 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=02BC 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=02BC T=01BF H=01C0 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=02BC T=01BF H=01C0-->02BC F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=02BC T=02BB H=02BC) size=1 done=1 
vmb_scheduler:4(frame FC=02BC T=02BB H=02BC) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=02BC budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=02BD 00000103*00000100)
Frame=02BC bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=03B8 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=03B8 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=03B8 T=02BB H=02BC R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=03B8 T=02BB H=02BC-->03B8 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=03B8 T=03B7 H=03B8) size=1 done=1 
vmb_scheduler:4(frame FC=03B8 T=03B7 H=03B8) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=03B8 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=03B9 00000103*00000100)
Frame=03B8 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=04B4 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=08 TP=00 PC=11 FC=04B5 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=04B5 T=03B7 H=03B8 R=00C6 W=00C6) size=1 done=254 
vmb_scheduler:1(frame FC=04B5 T=03B7 H=03B8-->04B5 F=00C6) size=254 done=254
vmb_scheduler:3(frame FC=04B5 T=04B4 H=04B5) size=1 done=1 
vmb_scheduler:4(frame FC=04B5 T=04B4 H=04B5) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=04B5 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=04B5 00000103*00000100)
Frame=04B5 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=05B0 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=05B0 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=05B1 T=04B4 H=04B5 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=05B1 T=04B4 H=04B5-->05B1 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=05B1 T=05B0 H=05B1) size=1 done=1 
vmb_scheduler:4(frame FC=05B1 T=05B0 H=05B1) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=05B1 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=05B1 00000103*00000100)
Frame=05B1 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=06AC 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=06AD 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=06AD T=05B0 H=05B1 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=06AD T=05B0 H=05B1-->06AD F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=06AD T=06AC H=06AD) size=1 done=1 
vmb_scheduler:4(frame FC=06AD T=06AC H=06AD) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=06AD budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=06AD 00000103*00000100)
Frame=06AD bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=07A8 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=07A9 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=07A9 T=06AC H=06AD R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=07A9 T=06AC H=06AD-->07A9 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=07A9 T=07A8 H=07A9) size=1 done=1 
vmb_scheduler:4(frame FC=07A9 T=07A8 H=07A9) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=07A9 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=07A9 00000103*00000100)
Frame=07A9 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=00A5 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=00A5 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=00A5 T=07A8 H=07A9 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=00A5 T=07A8 H=07A9-->00A5 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=00A5 T=00A4 H=00A5) size=1 done=1 
vmb_scheduler:4(frame FC=00A5 T=00A4 H=00A5) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=00A5 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=00A5 00000103*00000100)
Frame=00A5 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=01A1 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=01A1 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=01A1 T=00A4 H=00A5 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=01A1 T=00A4 H=00A5-->01A1 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=01A1 T=01A0 H=01A1) size=1 done=1 
vmb_scheduler:4(frame FC=01A1 T=01A0 H=01A1) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=01A1 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=01A1 00000103*00000100)
Frame=01A1 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=029D 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=029D 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=029D T=01A0 H=01A1 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=029D T=01A0 H=01A1-->029D F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=029D T=029C H=029D) size=1 done=1 
vmb_scheduler:4(frame FC=029D T=029C H=029D) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=029D budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=029D 00000103*00000100)
Frame=029D bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0399 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0399 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0399 T=029C H=029D R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0399 T=029C H=029D-->0399 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0399 T=0398 H=0399) size=1 done=1 
vmb_scheduler:4(frame FC=0399 T=0398 H=0399) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0399 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0399 00000103*00000100)
Frame=0399 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0495 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0495 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0495 T=0398 H=0399 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0495 T=0398 H=0399-->0495 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0495 T=0494 H=0495) size=1 done=1 
vmb_scheduler:4(frame FC=0495 T=0494 H=0495) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0495 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0495 00000103*00000100)
Frame=0495 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0591 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0591 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0591 T=0494 H=0495 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0591 T=0494 H=0495-->0591 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0591 T=0590 H=0591) size=1 done=1 
vmb_scheduler:4(frame FC=0591 T=0590 H=0591) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0591 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0591 00000103*00000100)
Frame=0591 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=068D 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=068D 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=068D T=0590 H=0591 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=068D T=0590 H=0591-->068D F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=068D T=068C H=068D) size=1 done=1 
vmb_scheduler:4(frame FC=068D T=068C H=068D) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=068D budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=068D 00000103*00000100)
Frame=068D bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0789 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0789 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0789 T=068C H=068D R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0789 T=068C H=068D-->0789 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0789 T=0788 H=0789) size=1 done=1 
vmb_scheduler:4(frame FC=0789 T=0788 H=0789) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0789 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0789 00000103*00000100)
Frame=0789 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0085 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0085 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0085 T=0788 H=0789 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0085 T=0788 H=0789-->0085 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0085 T=0084 H=0085) size=1 done=1 
vmb_scheduler:4(frame FC=0085 T=0084 H=0085) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0085 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0085 00000103*00000100)
Frame=0085 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0181 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0181 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0181 T=0084 H=0085 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0181 T=0084 H=0085-->0181 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0181 T=0180 H=0181) size=1 done=1 
vmb_scheduler:4(frame FC=0181 T=0180 H=0181) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0181 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0181 00000103*00000100)
Frame=0181 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
vmb_diagnostics_event 5348(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0261 00000103*00000100)
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0261 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0261 T=0180 H=0181 R=00C6 W=00C6) size=1 done=225 
vmb_scheduler:1(frame FC=0261 T=0180 H=0181-->0261 F=00C6) size=225 done=225
vmb_scheduler:3(frame FC=0261 T=0260 H=0261) size=1 done=1 
vmb_scheduler:4(frame FC=0261 T=0260 H=0261) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0261 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0261 00000103*00000100)
Frame=0261 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=027D 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=027D 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=027D T=0260 H=0261 R=00C6 W=00C6) size=1 done=29 
vmb_scheduler:1(frame FC=027D T=0260 H=0261-->027D F=00C6) size=29 done=29
vmb_scheduler:3(frame FC=027D T=027C H=027D) size=1 done=1 
vmb_scheduler:4(frame FC=027D T=027C H=027D) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=027D budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=027D 00000103*00000100)
Frame=027D bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0379 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0379 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0379 T=027C H=027D R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0379 T=027C H=027D-->0379 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0379 T=0378 H=0379) size=1 done=1 
vmb_scheduler:4(frame FC=0379 T=0378 H=0379) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0379 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0379 00000103*00000100)
Frame=0379 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0475 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0475 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0475 T=0378 H=0379 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0475 T=0378 H=0379-->0475 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0475 T=0474 H=0475) size=1 done=1 
vmb_scheduler:4(frame FC=0475 T=0474 H=0475) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0475 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0475 00000103*00000100)
Frame=0475 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0571 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0571 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0571 T=0474 H=0475 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0571 T=0474 H=0475-->0571 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0571 T=0570 H=0571) size=1 done=1 
vmb_scheduler:4(frame FC=0571 T=0570 H=0571) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0571 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0571 00000103*00000100)
Frame=0571 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=066D 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=066D 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=066D T=0570 H=0571 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=066D T=0570 H=0571-->066D F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=066D T=066C H=066D) size=1 done=1 
vmb_scheduler:4(frame FC=066D T=066C H=066D) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=066D budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=066D 00000103*00000100)
Frame=066D bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0769 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0769 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0769 T=066C H=066D R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0769 T=066C H=066D-->0769 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0769 T=0768 H=0769) size=1 done=1 
vmb_scheduler:4(frame FC=0769 T=0768 H=0769) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0769 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0769 00000103*00000100)
Frame=0769 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0065 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0065 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0065 T=0768 H=0769 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0065 T=0768 H=0769-->0065 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0065 T=0064 H=0065) size=1 done=1 
vmb_scheduler:4(frame FC=0065 T=0064 H=0065) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0065 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0065 00000103*00000100)
Frame=0065 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0161 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0161 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0161 T=0064 H=0065 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0161 T=0064 H=0065-->0161 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0161 T=0160 H=0161) size=1 done=1 
vmb_scheduler:4(frame FC=0161 T=0160 H=0161) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0161 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0161 00000103*00000100)
Frame=0161 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=025D 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=025D 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=025D T=0160 H=0161 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=025D T=0160 H=0161-->025D F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=025D T=025C H=025D) size=1 done=1 
vmb_scheduler:4(frame FC=025D T=025C H=025D) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=025D budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=025D 00000103*00000100)
Frame=025D bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0359 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0359 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0359 T=025C H=025D R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0359 T=025C H=025D-->0359 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0359 T=0358 H=0359) size=1 done=1 
vmb_scheduler:4(frame FC=0359 T=0358 H=0359) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0359 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0359 00000103*00000100)
Frame=0359 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0455 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0455 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0455 T=0358 H=0359 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0455 T=0358 H=0359-->0455 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0455 T=0454 H=0455) size=1 done=1 
vmb_scheduler:4(frame FC=0455 T=0454 H=0455) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0455 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0455 00000103*00000100)
Frame=0455 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0551 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0551 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0551 T=0454 H=0455 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0551 T=0454 H=0455-->0551 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0551 T=0550 H=0551) size=1 done=1 
vmb_scheduler:4(frame FC=0551 T=0550 H=0551) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0551 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0551 00000103*00000100)
Frame=0551 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=064D 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=064D 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=064D T=0550 H=0551 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=064D T=0550 H=0551-->064D F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=064D T=064C H=064D) size=1 done=1 
vmb_scheduler:4(frame FC=064D T=064C H=064D) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=064D budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=064D 00000103*00000100)
Frame=064D bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0749 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0749 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0749 T=064C H=064D R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0749 T=064C H=064D-->0749 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0749 T=0748 H=0749) size=1 done=1 
vmb_scheduler:4(frame FC=0749 T=0748 H=0749) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0749 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0749 00000103*00000100)
Frame=0749 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0045 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0045 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0045 T=0748 H=0749 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0045 T=0748 H=0749-->0045 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0045 T=0044 H=0045) size=1 done=1 
vmb_scheduler:4(frame FC=0045 T=0044 H=0045) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0045 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0045 00000103*00000100)
Frame=0045 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0141 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0141 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0141 T=0044 H=0045 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0141 T=0044 H=0045-->0141 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0141 T=0140 H=0141) size=1 done=1 
vmb_scheduler:4(frame FC=0141 T=0140 H=0141) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0141 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0141 00000103*00000100)
Frame=0141 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=023D 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=023D 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=023D T=0140 H=0141 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=023D T=0140 H=0141-->023D F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=023D T=023C H=023D) size=1 done=1 
vmb_scheduler:4(frame FC=023D T=023C H=023D) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=023D budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=023D 00000103*00000100)
Frame=023D bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0339 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0339 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0339 T=023C H=023D R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0339 T=023C H=023D-->0339 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0339 T=0338 H=0339) size=1 done=1 
vmb_scheduler:4(frame FC=0339 T=0338 H=0339) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0339 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0339 00000103*00000100)
Frame=0339 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0435 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0435 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0435 T=0338 H=0339 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0435 T=0338 H=0339-->0435 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0435 T=0434 H=0435) size=1 done=1 
vmb_scheduler:4(frame FC=0435 T=0434 H=0435) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0435 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0435 00000103*00000100)
Frame=0435 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0531 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0531 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0531 T=0434 H=0435 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0531 T=0434 H=0435-->0531 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0531 T=0530 H=0531) size=1 done=1 
vmb_scheduler:4(frame FC=0531 T=0530 H=0531) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0531 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0531 00000103*00000100)
Frame=0531 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
vmb_diagnostics_event 5348(CR=01 SR=04 TL=00 TP=00 PC=11 FC=05E9 00000103*00000100)
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=05E9 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=05E9 T=0530 H=0531 R=00C6 W=00C6) size=1 done=185 
vmb_scheduler:1(frame FC=05E9 T=0530 H=0531-->05E9 F=00C6) size=185 done=185
vmb_scheduler:3(frame FC=05E9 T=05E8 H=05E9) size=1 done=1 
vmb_scheduler:4(frame FC=05E9 T=05E8 H=05E9) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=05E9 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=05E9 00000103*00000100)
Frame=05E9 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=062D 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=062D 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=062D T=05E8 H=05E9 R=00C6 W=00C6) size=1 done=69 
vmb_scheduler:1(frame FC=062D T=05E8 H=05E9-->062D F=00C6) size=69 done=69
vmb_scheduler:3(frame FC=062D T=062C H=062D) size=1 done=1 
vmb_scheduler:4(frame FC=062D T=062C H=062D) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=062D budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=062D 00000103*00000100)
Frame=062D bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0729 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0729 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0729 T=062C H=062D R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0729 T=062C H=062D-->0729 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0729 T=0728 H=0729) size=1 done=1 
vmb_scheduler:4(frame FC=0729 T=0728 H=0729) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0729 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0729 00000103*00000100)
Frame=0729 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0025 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0025 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0025 T=0728 H=0729 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0025 T=0728 H=0729-->0025 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0025 T=0024 H=0025) size=1 done=1 
vmb_scheduler:4(frame FC=0025 T=0024 H=0025) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0025 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0026 00000103*00000100)
Frame=0025 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0121 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0121 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0121 T=0024 H=0025 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0121 T=0024 H=0025-->0121 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0121 T=0120 H=0121) size=1 done=1 
vmb_scheduler:4(frame FC=0121 T=0120 H=0121) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0121 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0122 00000103*00000100)
Frame=0121 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=021D 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=021D 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=021D T=0120 H=0121 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=021D T=0120 H=0121-->021D F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=021D T=021C H=021D) size=1 done=1 
vmb_scheduler:4(frame FC=021D T=021C H=021D) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=021D budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=021E 00000103*00000100)
Frame=021D bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0319 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0319 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0319 T=021C H=021D R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0319 T=021C H=021D-->0319 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0319 T=0318 H=0319) size=1 done=1 
vmb_scheduler:4(frame FC=0319 T=0318 H=0319) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0319 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=031A 00000103*00000100)
Frame=0319 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0415 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0415 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0415 T=0318 H=0319 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0415 T=0318 H=0319-->0415 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0415 T=0414 H=0415) size=1 done=1 
vmb_scheduler:4(frame FC=0415 T=0414 H=0415) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0415 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0416 00000103*00000100)
Frame=0415 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0511 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0511 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0511 T=0414 H=0415 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0511 T=0414 H=0415-->0511 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0511 T=0510 H=0511) size=1 done=1 
vmb_scheduler:4(frame FC=0511 T=0510 H=0511) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0511 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0512 00000103*00000100)
Frame=0511 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=060D 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=060D 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=060D T=0510 H=0511 R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=060D T=0510 H=0511-->060D F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=060D T=060C H=060D) size=1 done=1 
vmb_scheduler:4(frame FC=060D T=060C H=060D) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=060D budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=060E 00000103*00000100)
Frame=060D bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
API:vmb_hub_status_data()
vmb_hub_status_data(hw_lock=0 at hw_line=386 sw_lock=0 at sw_line=2725)
vmb_hub_status_data    757(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0709 00000103*00000100)
API:vmb_hub_status_data() completed 0
API:vmb_scheduler_event(starting)
vmb_scheduler         2305(CR=01 SR=04 TL=00 TP=00 PC=11 FC=0709 00000103*00000100)
vmb_check_rx_fifo() 0 packets completed
vmb_scheduler:0(start FC=0709 T=060C H=060D R=00C6 W=00C6) size=1 done=253 
vmb_scheduler:1(frame FC=0709 T=060C H=060D-->0709 F=00C6) size=253 done=253
vmb_scheduler:3(frame FC=0709 T=0708 H=0709) size=1 done=1 
vmb_scheduler:4(frame FC=0709 T=0708 H=0709) done=1 ahead=0 requested=0 budget=11960
fill_frame(vmb=cf0800cc frame_head=0709 budget_bits=11960)
fill_frame            2274(CR=01 SR=04 TL=00 TP=00 PC=11 FC=070A 00000103*00000100)
Frame=0709 bits=    0 bytes=0000 packets(O=0 A=0 I=0 B=0)
API:vmb_scheduler_event(completed)
pccard: card ejected from slot 0
API:vmb_remove(d4beb090)
vmb_hcd vmb_hcd: remove, state 1
usb usb3: USB disconnect, address 1
usb 3-1: USB disconnect, address 2