[Stgt-devel] dd fails with iSER

[Erez Zilber]

> > When I run with "tgtd -d 9", I don't see any logs. I only see:
> >
> > Aug  8 12:01:27 noni tgtd: Target daemon logger with pid=6353 started!
>
> It seems to produce logs at level <31>, which syslog ignores.  You
> can do this though:
>
> ./tgtd -d 9 -f &
>
> Then run the tgtadm commands and you should see output on stderr.
>

I ran the following command:

sgp_dd bs=512 of=/dev/null if=/dev/sg3 bpt=256 thr=8 time=1 count=8000

and got the following error:

[...]
iser_progress() Entry.
tgtd: iser_progress(1381) trying tx on fd 0 conn 0x636d90
tgtd: iscsi_task_tx_start(1750) found a task 3a 131072 0 0
tgtd: iscsi_data_rsp_build(969) 131072 131072 131072 131072 3a
[iSER]:in iser_rdma_write size 131072, 0x636f08.
tgtd: iser_rdma_write(1250) offset 131072 len 131072 last 1
[iSER]:in iser_post_rdma_wr size 131072 rem_va 46980000 rem_stag bef4005b.
[iSER]:iser_post_rdma_wr() Exit rdma = 0x63f090.
tgtd: iser_rdma_write(1267) removing ep 0 from tx ready list
tgtd: iser_event_modify(1434) ep 0 events 1
tgtd: iser_event_modify(1445) removing ep 0 from tx ready list
tgtd: iscsi_scsi_cmd_tx_done(1703) more data or sense or bidir 3a
[iSER]:iser_progress() Exit.
[iSER]:iser_progress() Entry.
[iSER]:iser_progress() Exit.
[iSER]:iser_progress() Entry.
[iSER]:iser_progress() Exit.
[iSER]:iser_progress() Entry.
[iSER]:iser_progress() Exit.
[iSER]:iser_cqe_handler() Entry.
[iSER]:iser_cqe_handler() Exit.
tgtd: bs_sync_handler(193) back to tgtd, 0x2aaaaab2c0f0
tgtd: iser_event_modify(1434) ep 0 events 5
tgtd: iser_event_modify(1438) adding ep 0 to tx ready list
tgtd: bs_sync_handler(193) back to tgtd, 0x2aaaaad300f0
tgtd: iser_event_modify(1434) ep 0 events 5
tgtd: iser_event_modify(1441) ep 0 was already on the list.  Track?
tgtd: bs_sync_handler(193) back to tgtd, 0x2aaaaae320f0
tgtd: iser_event_modify(1434) ep 0 events 5
tgtd: iser_event_modify(1441) ep 0 was already on the list.  Track?
[iSER]:tgtd: bs_sync_ack_fn(94) found 0x2aaaaac2e0f0
iser_progress() Entry.
tgtd: iser_progress(1381) trying tx on fd 0 conn 0x636d90
tgtd: iscsi_task_tx_start(1750) found a task 39 131072 131072 0
tgtd: iscsi_cmd_rsp_build(897) 0x2aaaaaaab010 28
[iSER]:in iscsi_iser_write_begin nbytes 48.
[iSER]:iscsi_iser_write_begin: alloc new send event 0x640010.
[iSER]:iscsi_iser_write_end: continue send event 0x640010, writeb 76.
tgtd: iscsi_iser_write_end(1065) waking up progress to finish cmd
[iSER]:iscsi_iser_write_end exit 0x640010.
tgtd: __cmd_done(618) 1 2aaaaaaab2b0 131072 0
tgtd: iser_progress(1381) trying tx on fd 0 conn 0x636d90
tgtd: iscsi_task_tx_start(1750) found a task 0 4294967295 0 0
[iSER]:in iscsi_iser_write_begin nbytes 48.
[iSER]:iscsi_iser_write_begin: alloc new send event 0x6410e0.
[iSER]:iscsi_iser_write_end: continue send event 0x6410e0, writeb 76.
tgtd: iscsi_iser_write_end(1065) waking up progress to finish cmd
[iSER]:iscsi_iser_write_end exit 0x6410e0.
tgtd: iser_progress(1381) trying tx on fd 0 conn 0x636d90
tgtd: iscsi_task_tx_start(1750) found a task 3b 131072 0 0
tgtd: iscsi_data_rsp_build(969) 131072 131072 131072 131072 3b
[iSER]:in iser_rdma_write size 131072, 0x636f08.
tgtd: iser_rdma_write(1250) offset 131072 len 131072 last 1
[iSER]:in iser_post_rdma_wr size 131072 rem_va 46980000 rem_stag bef4005b.
[iSER]:iser_post_rdma_wr() Exit rdma = 0x63f010.
tgtd: iser_rdma_write(1267) removing ep 0 from tx ready list
tgtd: iser_event_modify(1434) ep 0 events 1
tgtd: iser_event_modify(1445) removing ep 0 from tx ready list
tgtd: iscsi_scsi_cmd_tx_done(1703) more data or sense or bidir 3b
[iSER]:iser_progress() Exit.
[iSER]:iser_progress() Entry.
[iSER]:iser_progress() Exit.
[iSER]:iser_progress() Entry.
[iSER]:iser_progress() Exit.
[iSER]:iser_progress() Entry.
[iSER]:iser_progress() Exit.
[iSER]:iser_progress() Entry.
[iSER]:iser_progress() Exit.
[iSER]:iser_cqe_handler() Entry.
tgtd: iser_cqe_handler(888) bad WC status 10 for wr_id 0x63f090

If you need the whole log, I can send it.

Erez