Merge branch 'devel' of master.kernel.org:/home/rmk/linux-2.6-arm
[linux-2.6] / drivers / s390 / scsi / zfcp_dbf.c
1 /*
2  * zfcp device driver
3  *
4  * Debug traces for zfcp.
5  *
6  * Copyright IBM Corporation 2002, 2008
7  */
8
9 #define KMSG_COMPONENT "zfcp"
10 #define pr_fmt(fmt) KMSG_COMPONENT ": " fmt
11
12 #include <linux/ctype.h>
13 #include <asm/debug.h>
14 #include "zfcp_ext.h"
15
16 static u32 dbfsize = 4;
17
18 module_param(dbfsize, uint, 0400);
19 MODULE_PARM_DESC(dbfsize,
20                  "number of pages for each debug feature area (default 4)");
21
22 static void zfcp_dbf_hexdump(debug_info_t *dbf, void *to, int to_len,
23                              int level, char *from, int from_len)
24 {
25         int offset;
26         struct zfcp_dbf_dump *dump = to;
27         int room = to_len - sizeof(*dump);
28
29         for (offset = 0; offset < from_len; offset += dump->size) {
30                 memset(to, 0, to_len);
31                 strncpy(dump->tag, "dump", ZFCP_DBF_TAG_SIZE);
32                 dump->total_size = from_len;
33                 dump->offset = offset;
34                 dump->size = min(from_len - offset, room);
35                 memcpy(dump->data, from + offset, dump->size);
36                 debug_event(dbf, level, dump, dump->size + sizeof(*dump));
37         }
38 }
39
40 /* FIXME: this duplicate this code in s390 debug feature */
41 static void zfcp_dbf_timestamp(unsigned long long stck, struct timespec *time)
42 {
43         unsigned long long sec;
44
45         stck -= 0x8126d60e46000000LL - (0x3c26700LL * 1000000 * 4096);
46         sec = stck >> 12;
47         do_div(sec, 1000000);
48         time->tv_sec = sec;
49         stck -= (sec * 1000000) << 12;
50         time->tv_nsec = ((stck * 1000) >> 12);
51 }
52
53 static void zfcp_dbf_tag(char **p, const char *label, const char *tag)
54 {
55         int i;
56
57         *p += sprintf(*p, "%-24s", label);
58         for (i = 0; i < ZFCP_DBF_TAG_SIZE; i++)
59                 *p += sprintf(*p, "%c", tag[i]);
60         *p += sprintf(*p, "\n");
61 }
62
63 static void zfcp_dbf_outs(char **buf, const char *s1, const char *s2)
64 {
65         *buf += sprintf(*buf, "%-24s%s\n", s1, s2);
66 }
67
68 static void zfcp_dbf_out(char **buf, const char *s, const char *format, ...)
69 {
70         va_list arg;
71
72         *buf += sprintf(*buf, "%-24s", s);
73         va_start(arg, format);
74         *buf += vsprintf(*buf, format, arg);
75         va_end(arg);
76         *buf += sprintf(*buf, "\n");
77 }
78
79 static void zfcp_dbf_outd(char **p, const char *label, char *buffer,
80                           int buflen, int offset, int total_size)
81 {
82         if (!offset)
83                 *p += sprintf(*p, "%-24s  ", label);
84         while (buflen--) {
85                 if (offset > 0) {
86                         if ((offset % 32) == 0)
87                                 *p += sprintf(*p, "\n%-24c  ", ' ');
88                         else if ((offset % 4) == 0)
89                                 *p += sprintf(*p, " ");
90                 }
91                 *p += sprintf(*p, "%02x", *buffer++);
92                 if (++offset == total_size) {
93                         *p += sprintf(*p, "\n");
94                         break;
95                 }
96         }
97         if (!total_size)
98                 *p += sprintf(*p, "\n");
99 }
100
101 static int zfcp_dbf_view_header(debug_info_t *id, struct debug_view *view,
102                                 int area, debug_entry_t *entry, char *out_buf)
103 {
104         struct zfcp_dbf_dump *dump = (struct zfcp_dbf_dump *)DEBUG_DATA(entry);
105         struct timespec t;
106         char *p = out_buf;
107
108         if (strncmp(dump->tag, "dump", ZFCP_DBF_TAG_SIZE) != 0) {
109                 zfcp_dbf_timestamp(entry->id.stck, &t);
110                 zfcp_dbf_out(&p, "timestamp", "%011lu:%06lu",
111                              t.tv_sec, t.tv_nsec);
112                 zfcp_dbf_out(&p, "cpu", "%02i", entry->id.fields.cpuid);
113         } else  {
114                 zfcp_dbf_outd(&p, "", dump->data, dump->size, dump->offset,
115                               dump->total_size);
116                 if ((dump->offset + dump->size) == dump->total_size)
117                         p += sprintf(p, "\n");
118         }
119         return p - out_buf;
120 }
121
122 /**
123  * zfcp_hba_dbf_event_fsf_response - trace event for request completion
124  * @fsf_req: request that has been completed
125  */
126 void zfcp_hba_dbf_event_fsf_response(struct zfcp_fsf_req *fsf_req)
127 {
128         struct zfcp_adapter *adapter = fsf_req->adapter;
129         struct fsf_qtcb *qtcb = fsf_req->qtcb;
130         union fsf_prot_status_qual *prot_status_qual =
131                                         &qtcb->prefix.prot_status_qual;
132         union fsf_status_qual *fsf_status_qual = &qtcb->header.fsf_status_qual;
133         struct scsi_cmnd *scsi_cmnd;
134         struct zfcp_port *port;
135         struct zfcp_unit *unit;
136         struct zfcp_send_els *send_els;
137         struct zfcp_hba_dbf_record *rec = &adapter->hba_dbf_buf;
138         struct zfcp_hba_dbf_record_response *response = &rec->u.response;
139         int level;
140         unsigned long flags;
141
142         spin_lock_irqsave(&adapter->hba_dbf_lock, flags);
143         memset(rec, 0, sizeof(*rec));
144         strncpy(rec->tag, "resp", ZFCP_DBF_TAG_SIZE);
145
146         if ((qtcb->prefix.prot_status != FSF_PROT_GOOD) &&
147             (qtcb->prefix.prot_status != FSF_PROT_FSF_STATUS_PRESENTED)) {
148                 strncpy(rec->tag2, "perr", ZFCP_DBF_TAG_SIZE);
149                 level = 1;
150         } else if (qtcb->header.fsf_status != FSF_GOOD) {
151                 strncpy(rec->tag2, "ferr", ZFCP_DBF_TAG_SIZE);
152                 level = 1;
153         } else if ((fsf_req->fsf_command == FSF_QTCB_OPEN_PORT_WITH_DID) ||
154                    (fsf_req->fsf_command == FSF_QTCB_OPEN_LUN)) {
155                 strncpy(rec->tag2, "open", ZFCP_DBF_TAG_SIZE);
156                 level = 4;
157         } else if (qtcb->header.log_length) {
158                 strncpy(rec->tag2, "qtcb", ZFCP_DBF_TAG_SIZE);
159                 level = 5;
160         } else {
161                 strncpy(rec->tag2, "norm", ZFCP_DBF_TAG_SIZE);
162                 level = 6;
163         }
164
165         response->fsf_command = fsf_req->fsf_command;
166         response->fsf_reqid = (unsigned long)fsf_req;
167         response->fsf_seqno = fsf_req->seq_no;
168         response->fsf_issued = fsf_req->issued;
169         response->fsf_prot_status = qtcb->prefix.prot_status;
170         response->fsf_status = qtcb->header.fsf_status;
171         memcpy(response->fsf_prot_status_qual,
172                prot_status_qual, FSF_PROT_STATUS_QUAL_SIZE);
173         memcpy(response->fsf_status_qual,
174                fsf_status_qual, FSF_STATUS_QUALIFIER_SIZE);
175         response->fsf_req_status = fsf_req->status;
176         response->sbal_first = fsf_req->sbal_first;
177         response->sbal_last = fsf_req->sbal_last;
178         response->sbal_response = fsf_req->sbal_response;
179         response->pool = fsf_req->pool != NULL;
180         response->erp_action = (unsigned long)fsf_req->erp_action;
181
182         switch (fsf_req->fsf_command) {
183         case FSF_QTCB_FCP_CMND:
184                 if (fsf_req->status & ZFCP_STATUS_FSFREQ_TASK_MANAGEMENT)
185                         break;
186                 scsi_cmnd = (struct scsi_cmnd *)fsf_req->data;
187                 if (scsi_cmnd) {
188                         response->u.fcp.cmnd = (unsigned long)scsi_cmnd;
189                         response->u.fcp.serial = scsi_cmnd->serial_number;
190                 }
191                 break;
192
193         case FSF_QTCB_OPEN_PORT_WITH_DID:
194         case FSF_QTCB_CLOSE_PORT:
195         case FSF_QTCB_CLOSE_PHYSICAL_PORT:
196                 port = (struct zfcp_port *)fsf_req->data;
197                 response->u.port.wwpn = port->wwpn;
198                 response->u.port.d_id = port->d_id;
199                 response->u.port.port_handle = qtcb->header.port_handle;
200                 break;
201
202         case FSF_QTCB_OPEN_LUN:
203         case FSF_QTCB_CLOSE_LUN:
204                 unit = (struct zfcp_unit *)fsf_req->data;
205                 port = unit->port;
206                 response->u.unit.wwpn = port->wwpn;
207                 response->u.unit.fcp_lun = unit->fcp_lun;
208                 response->u.unit.port_handle = qtcb->header.port_handle;
209                 response->u.unit.lun_handle = qtcb->header.lun_handle;
210                 break;
211
212         case FSF_QTCB_SEND_ELS:
213                 send_els = (struct zfcp_send_els *)fsf_req->data;
214                 response->u.els.d_id = qtcb->bottom.support.d_id;
215                 response->u.els.ls_code = send_els->ls_code >> 24;
216                 break;
217
218         case FSF_QTCB_ABORT_FCP_CMND:
219         case FSF_QTCB_SEND_GENERIC:
220         case FSF_QTCB_EXCHANGE_CONFIG_DATA:
221         case FSF_QTCB_EXCHANGE_PORT_DATA:
222         case FSF_QTCB_DOWNLOAD_CONTROL_FILE:
223         case FSF_QTCB_UPLOAD_CONTROL_FILE:
224                 break;
225         }
226
227         debug_event(adapter->hba_dbf, level, rec, sizeof(*rec));
228
229         /* have fcp channel microcode fixed to use as little as possible */
230         if (fsf_req->fsf_command != FSF_QTCB_FCP_CMND) {
231                 /* adjust length skipping trailing zeros */
232                 char *buf = (char *)qtcb + qtcb->header.log_start;
233                 int len = qtcb->header.log_length;
234                 for (; len && !buf[len - 1]; len--);
235                 zfcp_dbf_hexdump(adapter->hba_dbf, rec, sizeof(*rec), level,
236                                  buf, len);
237         }
238
239         spin_unlock_irqrestore(&adapter->hba_dbf_lock, flags);
240 }
241
242 /**
243  * zfcp_hba_dbf_event_fsf_unsol - trace event for an unsolicited status buffer
244  * @tag: tag indicating which kind of unsolicited status has been received
245  * @adapter: adapter that has issued the unsolicited status buffer
246  * @status_buffer: buffer containing payload of unsolicited status
247  */
248 void zfcp_hba_dbf_event_fsf_unsol(const char *tag, struct zfcp_adapter *adapter,
249                                   struct fsf_status_read_buffer *status_buffer)
250 {
251         struct zfcp_hba_dbf_record *rec = &adapter->hba_dbf_buf;
252         unsigned long flags;
253
254         spin_lock_irqsave(&adapter->hba_dbf_lock, flags);
255         memset(rec, 0, sizeof(*rec));
256         strncpy(rec->tag, "stat", ZFCP_DBF_TAG_SIZE);
257         strncpy(rec->tag2, tag, ZFCP_DBF_TAG_SIZE);
258
259         rec->u.status.failed = atomic_read(&adapter->stat_miss);
260         if (status_buffer != NULL) {
261                 rec->u.status.status_type = status_buffer->status_type;
262                 rec->u.status.status_subtype = status_buffer->status_subtype;
263                 memcpy(&rec->u.status.queue_designator,
264                        &status_buffer->queue_designator,
265                        sizeof(struct fsf_queue_designator));
266
267                 switch (status_buffer->status_type) {
268                 case FSF_STATUS_READ_SENSE_DATA_AVAIL:
269                         rec->u.status.payload_size =
270                             ZFCP_DBF_UNSOL_PAYLOAD_SENSE_DATA_AVAIL;
271                         break;
272
273                 case FSF_STATUS_READ_BIT_ERROR_THRESHOLD:
274                         rec->u.status.payload_size =
275                             ZFCP_DBF_UNSOL_PAYLOAD_BIT_ERROR_THRESHOLD;
276                         break;
277
278                 case FSF_STATUS_READ_LINK_DOWN:
279                         switch (status_buffer->status_subtype) {
280                         case FSF_STATUS_READ_SUB_NO_PHYSICAL_LINK:
281                         case FSF_STATUS_READ_SUB_FDISC_FAILED:
282                                 rec->u.status.payload_size =
283                                         sizeof(struct fsf_link_down_info);
284                         }
285                         break;
286
287                 case FSF_STATUS_READ_FEATURE_UPDATE_ALERT:
288                         rec->u.status.payload_size =
289                             ZFCP_DBF_UNSOL_PAYLOAD_FEATURE_UPDATE_ALERT;
290                         break;
291                 }
292                 memcpy(&rec->u.status.payload,
293                        &status_buffer->payload, rec->u.status.payload_size);
294         }
295
296         debug_event(adapter->hba_dbf, 2, rec, sizeof(*rec));
297         spin_unlock_irqrestore(&adapter->hba_dbf_lock, flags);
298 }
299
300 /**
301  * zfcp_hba_dbf_event_qdio - trace event for QDIO related failure
302  * @adapter: adapter affected by this QDIO related event
303  * @qdio_error: as passed by qdio module
304  * @sbal_index: first buffer with error condition, as passed by qdio module
305  * @sbal_count: number of buffers affected, as passed by qdio module
306  */
307 void zfcp_hba_dbf_event_qdio(struct zfcp_adapter *adapter,
308                              unsigned int qdio_error, int sbal_index,
309                              int sbal_count)
310 {
311         struct zfcp_hba_dbf_record *r = &adapter->hba_dbf_buf;
312         unsigned long flags;
313
314         spin_lock_irqsave(&adapter->hba_dbf_lock, flags);
315         memset(r, 0, sizeof(*r));
316         strncpy(r->tag, "qdio", ZFCP_DBF_TAG_SIZE);
317         r->u.qdio.qdio_error = qdio_error;
318         r->u.qdio.sbal_index = sbal_index;
319         r->u.qdio.sbal_count = sbal_count;
320         debug_event(adapter->hba_dbf, 0, r, sizeof(*r));
321         spin_unlock_irqrestore(&adapter->hba_dbf_lock, flags);
322 }
323
324 /**
325  * zfcp_hba_dbf_event_berr - trace event for bit error threshold
326  * @adapter: adapter affected by this QDIO related event
327  * @req: fsf request
328  */
329 void zfcp_hba_dbf_event_berr(struct zfcp_adapter *adapter,
330                              struct zfcp_fsf_req *req)
331 {
332         struct zfcp_hba_dbf_record *r = &adapter->hba_dbf_buf;
333         struct fsf_status_read_buffer *sr_buf = req->data;
334         struct fsf_bit_error_payload *err = &sr_buf->payload.bit_error;
335         unsigned long flags;
336
337         spin_lock_irqsave(&adapter->hba_dbf_lock, flags);
338         memset(r, 0, sizeof(*r));
339         strncpy(r->tag, "berr", ZFCP_DBF_TAG_SIZE);
340         memcpy(&r->u.berr, err, sizeof(struct fsf_bit_error_payload));
341         debug_event(adapter->hba_dbf, 0, r, sizeof(*r));
342         spin_unlock_irqrestore(&adapter->hba_dbf_lock, flags);
343 }
344 static void zfcp_hba_dbf_view_response(char **p,
345                                        struct zfcp_hba_dbf_record_response *r)
346 {
347         struct timespec t;
348
349         zfcp_dbf_out(p, "fsf_command", "0x%08x", r->fsf_command);
350         zfcp_dbf_out(p, "fsf_reqid", "0x%0Lx", r->fsf_reqid);
351         zfcp_dbf_out(p, "fsf_seqno", "0x%08x", r->fsf_seqno);
352         zfcp_dbf_timestamp(r->fsf_issued, &t);
353         zfcp_dbf_out(p, "fsf_issued", "%011lu:%06lu", t.tv_sec, t.tv_nsec);
354         zfcp_dbf_out(p, "fsf_prot_status", "0x%08x", r->fsf_prot_status);
355         zfcp_dbf_out(p, "fsf_status", "0x%08x", r->fsf_status);
356         zfcp_dbf_outd(p, "fsf_prot_status_qual", r->fsf_prot_status_qual,
357                       FSF_PROT_STATUS_QUAL_SIZE, 0, FSF_PROT_STATUS_QUAL_SIZE);
358         zfcp_dbf_outd(p, "fsf_status_qual", r->fsf_status_qual,
359                       FSF_STATUS_QUALIFIER_SIZE, 0, FSF_STATUS_QUALIFIER_SIZE);
360         zfcp_dbf_out(p, "fsf_req_status", "0x%08x", r->fsf_req_status);
361         zfcp_dbf_out(p, "sbal_first", "0x%02x", r->sbal_first);
362         zfcp_dbf_out(p, "sbal_last", "0x%02x", r->sbal_last);
363         zfcp_dbf_out(p, "sbal_response", "0x%02x", r->sbal_response);
364         zfcp_dbf_out(p, "pool", "0x%02x", r->pool);
365
366         switch (r->fsf_command) {
367         case FSF_QTCB_FCP_CMND:
368                 if (r->fsf_req_status & ZFCP_STATUS_FSFREQ_TASK_MANAGEMENT)
369                         break;
370                 zfcp_dbf_out(p, "scsi_cmnd", "0x%0Lx", r->u.fcp.cmnd);
371                 zfcp_dbf_out(p, "scsi_serial", "0x%016Lx", r->u.fcp.serial);
372                 p += sprintf(*p, "\n");
373                 break;
374
375         case FSF_QTCB_OPEN_PORT_WITH_DID:
376         case FSF_QTCB_CLOSE_PORT:
377         case FSF_QTCB_CLOSE_PHYSICAL_PORT:
378                 zfcp_dbf_out(p, "wwpn", "0x%016Lx", r->u.port.wwpn);
379                 zfcp_dbf_out(p, "d_id", "0x%06x", r->u.port.d_id);
380                 zfcp_dbf_out(p, "port_handle", "0x%08x", r->u.port.port_handle);
381                 break;
382
383         case FSF_QTCB_OPEN_LUN:
384         case FSF_QTCB_CLOSE_LUN:
385                 zfcp_dbf_out(p, "wwpn", "0x%016Lx", r->u.unit.wwpn);
386                 zfcp_dbf_out(p, "fcp_lun", "0x%016Lx", r->u.unit.fcp_lun);
387                 zfcp_dbf_out(p, "port_handle", "0x%08x", r->u.unit.port_handle);
388                 zfcp_dbf_out(p, "lun_handle", "0x%08x", r->u.unit.lun_handle);
389                 break;
390
391         case FSF_QTCB_SEND_ELS:
392                 zfcp_dbf_out(p, "d_id", "0x%06x", r->u.els.d_id);
393                 zfcp_dbf_out(p, "ls_code", "0x%02x", r->u.els.ls_code);
394                 break;
395
396         case FSF_QTCB_ABORT_FCP_CMND:
397         case FSF_QTCB_SEND_GENERIC:
398         case FSF_QTCB_EXCHANGE_CONFIG_DATA:
399         case FSF_QTCB_EXCHANGE_PORT_DATA:
400         case FSF_QTCB_DOWNLOAD_CONTROL_FILE:
401         case FSF_QTCB_UPLOAD_CONTROL_FILE:
402                 break;
403         }
404 }
405
406 static void zfcp_hba_dbf_view_status(char **p,
407                                      struct zfcp_hba_dbf_record_status *r)
408 {
409         zfcp_dbf_out(p, "failed", "0x%02x", r->failed);
410         zfcp_dbf_out(p, "status_type", "0x%08x", r->status_type);
411         zfcp_dbf_out(p, "status_subtype", "0x%08x", r->status_subtype);
412         zfcp_dbf_outd(p, "queue_designator", (char *)&r->queue_designator,
413                       sizeof(struct fsf_queue_designator), 0,
414                       sizeof(struct fsf_queue_designator));
415         zfcp_dbf_outd(p, "payload", (char *)&r->payload, r->payload_size, 0,
416                       r->payload_size);
417 }
418
419 static void zfcp_hba_dbf_view_qdio(char **p, struct zfcp_hba_dbf_record_qdio *r)
420 {
421         zfcp_dbf_out(p, "qdio_error", "0x%08x", r->qdio_error);
422         zfcp_dbf_out(p, "sbal_index", "0x%02x", r->sbal_index);
423         zfcp_dbf_out(p, "sbal_count", "0x%02x", r->sbal_count);
424 }
425
426 static void zfcp_hba_dbf_view_berr(char **p, struct fsf_bit_error_payload *r)
427 {
428         zfcp_dbf_out(p, "link_failures", "%d", r->link_failure_error_count);
429         zfcp_dbf_out(p, "loss_of_sync_err", "%d", r->loss_of_sync_error_count);
430         zfcp_dbf_out(p, "loss_of_sig_err", "%d", r->loss_of_signal_error_count);
431         zfcp_dbf_out(p, "prim_seq_err", "%d",
432                      r->primitive_sequence_error_count);
433         zfcp_dbf_out(p, "inval_trans_word_err", "%d",
434                      r->invalid_transmission_word_error_count);
435         zfcp_dbf_out(p, "CRC_errors", "%d", r->crc_error_count);
436         zfcp_dbf_out(p, "prim_seq_event_to", "%d",
437                      r->primitive_sequence_event_timeout_count);
438         zfcp_dbf_out(p, "elast_buf_overrun_err", "%d",
439                      r->elastic_buffer_overrun_error_count);
440         zfcp_dbf_out(p, "adv_rec_buf2buf_cred", "%d",
441                      r->advertised_receive_b2b_credit);
442         zfcp_dbf_out(p, "curr_rec_buf2buf_cred", "%d",
443                      r->current_receive_b2b_credit);
444         zfcp_dbf_out(p, "adv_trans_buf2buf_cred", "%d",
445                      r->advertised_transmit_b2b_credit);
446         zfcp_dbf_out(p, "curr_trans_buf2buf_cred", "%d",
447                      r->current_transmit_b2b_credit);
448 }
449
450 static int zfcp_hba_dbf_view_format(debug_info_t *id, struct debug_view *view,
451                                     char *out_buf, const char *in_buf)
452 {
453         struct zfcp_hba_dbf_record *r = (struct zfcp_hba_dbf_record *)in_buf;
454         char *p = out_buf;
455
456         if (strncmp(r->tag, "dump", ZFCP_DBF_TAG_SIZE) == 0)
457                 return 0;
458
459         zfcp_dbf_tag(&p, "tag", r->tag);
460         if (isalpha(r->tag2[0]))
461                 zfcp_dbf_tag(&p, "tag2", r->tag2);
462
463         if (strncmp(r->tag, "resp", ZFCP_DBF_TAG_SIZE) == 0)
464                 zfcp_hba_dbf_view_response(&p, &r->u.response);
465         else if (strncmp(r->tag, "stat", ZFCP_DBF_TAG_SIZE) == 0)
466                 zfcp_hba_dbf_view_status(&p, &r->u.status);
467         else if (strncmp(r->tag, "qdio", ZFCP_DBF_TAG_SIZE) == 0)
468                 zfcp_hba_dbf_view_qdio(&p, &r->u.qdio);
469         else if (strncmp(r->tag, "berr", ZFCP_DBF_TAG_SIZE) == 0)
470                 zfcp_hba_dbf_view_berr(&p, &r->u.berr);
471
472         if (strncmp(r->tag, "resp", ZFCP_DBF_TAG_SIZE) != 0)
473                 p += sprintf(p, "\n");
474         return p - out_buf;
475 }
476
477 static struct debug_view zfcp_hba_dbf_view = {
478         "structured",
479         NULL,
480         &zfcp_dbf_view_header,
481         &zfcp_hba_dbf_view_format,
482         NULL,
483         NULL
484 };
485
486 static const char *zfcp_rec_dbf_tags[] = {
487         [ZFCP_REC_DBF_ID_THREAD] = "thread",
488         [ZFCP_REC_DBF_ID_TARGET] = "target",
489         [ZFCP_REC_DBF_ID_TRIGGER] = "trigger",
490         [ZFCP_REC_DBF_ID_ACTION] = "action",
491 };
492
493 static int zfcp_rec_dbf_view_format(debug_info_t *id, struct debug_view *view,
494                                     char *buf, const char *_rec)
495 {
496         struct zfcp_rec_dbf_record *r = (struct zfcp_rec_dbf_record *)_rec;
497         char *p = buf;
498         char hint[ZFCP_DBF_ID_SIZE + 1];
499
500         memcpy(hint, r->id2, ZFCP_DBF_ID_SIZE);
501         hint[ZFCP_DBF_ID_SIZE] = 0;
502         zfcp_dbf_outs(&p, "tag", zfcp_rec_dbf_tags[r->id]);
503         zfcp_dbf_outs(&p, "hint", hint);
504         switch (r->id) {
505         case ZFCP_REC_DBF_ID_THREAD:
506                 zfcp_dbf_out(&p, "total", "%d", r->u.thread.total);
507                 zfcp_dbf_out(&p, "ready", "%d", r->u.thread.ready);
508                 zfcp_dbf_out(&p, "running", "%d", r->u.thread.running);
509                 break;
510         case ZFCP_REC_DBF_ID_TARGET:
511                 zfcp_dbf_out(&p, "reference", "0x%016Lx", r->u.target.ref);
512                 zfcp_dbf_out(&p, "status", "0x%08x", r->u.target.status);
513                 zfcp_dbf_out(&p, "erp_count", "%d", r->u.target.erp_count);
514                 zfcp_dbf_out(&p, "d_id", "0x%06x", r->u.target.d_id);
515                 zfcp_dbf_out(&p, "wwpn", "0x%016Lx", r->u.target.wwpn);
516                 zfcp_dbf_out(&p, "fcp_lun", "0x%016Lx", r->u.target.fcp_lun);
517                 break;
518         case ZFCP_REC_DBF_ID_TRIGGER:
519                 zfcp_dbf_out(&p, "reference", "0x%016Lx", r->u.trigger.ref);
520                 zfcp_dbf_out(&p, "erp_action", "0x%016Lx", r->u.trigger.action);
521                 zfcp_dbf_out(&p, "requested", "%d", r->u.trigger.want);
522                 zfcp_dbf_out(&p, "executed", "%d", r->u.trigger.need);
523                 zfcp_dbf_out(&p, "wwpn", "0x%016Lx", r->u.trigger.wwpn);
524                 zfcp_dbf_out(&p, "fcp_lun", "0x%016Lx", r->u.trigger.fcp_lun);
525                 zfcp_dbf_out(&p, "adapter_status", "0x%08x", r->u.trigger.as);
526                 zfcp_dbf_out(&p, "port_status", "0x%08x", r->u.trigger.ps);
527                 zfcp_dbf_out(&p, "unit_status", "0x%08x", r->u.trigger.us);
528                 break;
529         case ZFCP_REC_DBF_ID_ACTION:
530                 zfcp_dbf_out(&p, "erp_action", "0x%016Lx", r->u.action.action);
531                 zfcp_dbf_out(&p, "fsf_req", "0x%016Lx", r->u.action.fsf_req);
532                 zfcp_dbf_out(&p, "status", "0x%08Lx", r->u.action.status);
533                 zfcp_dbf_out(&p, "step", "0x%08Lx", r->u.action.step);
534                 break;
535         }
536         p += sprintf(p, "\n");
537         return p - buf;
538 }
539
540 static struct debug_view zfcp_rec_dbf_view = {
541         "structured",
542         NULL,
543         &zfcp_dbf_view_header,
544         &zfcp_rec_dbf_view_format,
545         NULL,
546         NULL
547 };
548
549 /**
550  * zfcp_rec_dbf_event_thread - trace event related to recovery thread operation
551  * @id2: identifier for event
552  * @adapter: adapter
553  * This function assumes that the caller is holding erp_lock.
554  */
555 void zfcp_rec_dbf_event_thread(char *id2, struct zfcp_adapter *adapter)
556 {
557         struct zfcp_rec_dbf_record *r = &adapter->rec_dbf_buf;
558         unsigned long flags = 0;
559         struct list_head *entry;
560         unsigned ready = 0, running = 0, total;
561
562         list_for_each(entry, &adapter->erp_ready_head)
563                 ready++;
564         list_for_each(entry, &adapter->erp_running_head)
565                 running++;
566         total = adapter->erp_total_count;
567
568         spin_lock_irqsave(&adapter->rec_dbf_lock, flags);
569         memset(r, 0, sizeof(*r));
570         r->id = ZFCP_REC_DBF_ID_THREAD;
571         memcpy(r->id2, id2, ZFCP_DBF_ID_SIZE);
572         r->u.thread.total = total;
573         r->u.thread.ready = ready;
574         r->u.thread.running = running;
575         debug_event(adapter->rec_dbf, 6, r, sizeof(*r));
576         spin_unlock_irqrestore(&adapter->rec_dbf_lock, flags);
577 }
578
579 /**
580  * zfcp_rec_dbf_event_thread - trace event related to recovery thread operation
581  * @id2: identifier for event
582  * @adapter: adapter
583  * This function assumes that the caller does not hold erp_lock.
584  */
585 void zfcp_rec_dbf_event_thread_lock(char *id2, struct zfcp_adapter *adapter)
586 {
587         unsigned long flags;
588
589         read_lock_irqsave(&adapter->erp_lock, flags);
590         zfcp_rec_dbf_event_thread(id2, adapter);
591         read_unlock_irqrestore(&adapter->erp_lock, flags);
592 }
593
594 static void zfcp_rec_dbf_event_target(char *id2, void *ref,
595                                       struct zfcp_adapter *adapter,
596                                       atomic_t *status, atomic_t *erp_count,
597                                       u64 wwpn, u32 d_id, u64 fcp_lun)
598 {
599         struct zfcp_rec_dbf_record *r = &adapter->rec_dbf_buf;
600         unsigned long flags;
601
602         spin_lock_irqsave(&adapter->rec_dbf_lock, flags);
603         memset(r, 0, sizeof(*r));
604         r->id = ZFCP_REC_DBF_ID_TARGET;
605         memcpy(r->id2, id2, ZFCP_DBF_ID_SIZE);
606         r->u.target.ref = (unsigned long)ref;
607         r->u.target.status = atomic_read(status);
608         r->u.target.wwpn = wwpn;
609         r->u.target.d_id = d_id;
610         r->u.target.fcp_lun = fcp_lun;
611         r->u.target.erp_count = atomic_read(erp_count);
612         debug_event(adapter->rec_dbf, 3, r, sizeof(*r));
613         spin_unlock_irqrestore(&adapter->rec_dbf_lock, flags);
614 }
615
616 /**
617  * zfcp_rec_dbf_event_adapter - trace event for adapter state change
618  * @id: identifier for trigger of state change
619  * @ref: additional reference (e.g. request)
620  * @adapter: adapter
621  */
622 void zfcp_rec_dbf_event_adapter(char *id, void *ref,
623                                 struct zfcp_adapter *adapter)
624 {
625         zfcp_rec_dbf_event_target(id, ref, adapter, &adapter->status,
626                                   &adapter->erp_counter, 0, 0, 0);
627 }
628
629 /**
630  * zfcp_rec_dbf_event_port - trace event for port state change
631  * @id: identifier for trigger of state change
632  * @ref: additional reference (e.g. request)
633  * @port: port
634  */
635 void zfcp_rec_dbf_event_port(char *id, void *ref, struct zfcp_port *port)
636 {
637         struct zfcp_adapter *adapter = port->adapter;
638
639         zfcp_rec_dbf_event_target(id, ref, adapter, &port->status,
640                                   &port->erp_counter, port->wwpn, port->d_id,
641                                   0);
642 }
643
644 /**
645  * zfcp_rec_dbf_event_unit - trace event for unit state change
646  * @id: identifier for trigger of state change
647  * @ref: additional reference (e.g. request)
648  * @unit: unit
649  */
650 void zfcp_rec_dbf_event_unit(char *id, void *ref, struct zfcp_unit *unit)
651 {
652         struct zfcp_port *port = unit->port;
653         struct zfcp_adapter *adapter = port->adapter;
654
655         zfcp_rec_dbf_event_target(id, ref, adapter, &unit->status,
656                                   &unit->erp_counter, port->wwpn, port->d_id,
657                                   unit->fcp_lun);
658 }
659
660 /**
661  * zfcp_rec_dbf_event_trigger - trace event for triggered error recovery
662  * @id2: identifier for error recovery trigger
663  * @ref: additional reference (e.g. request)
664  * @want: originally requested error recovery action
665  * @need: error recovery action actually initiated
666  * @action: address of error recovery action struct
667  * @adapter: adapter
668  * @port: port
669  * @unit: unit
670  */
671 void zfcp_rec_dbf_event_trigger(char *id2, void *ref, u8 want, u8 need,
672                                 void *action, struct zfcp_adapter *adapter,
673                                 struct zfcp_port *port, struct zfcp_unit *unit)
674 {
675         struct zfcp_rec_dbf_record *r = &adapter->rec_dbf_buf;
676         unsigned long flags;
677
678         spin_lock_irqsave(&adapter->rec_dbf_lock, flags);
679         memset(r, 0, sizeof(*r));
680         r->id = ZFCP_REC_DBF_ID_TRIGGER;
681         memcpy(r->id2, id2, ZFCP_DBF_ID_SIZE);
682         r->u.trigger.ref = (unsigned long)ref;
683         r->u.trigger.want = want;
684         r->u.trigger.need = need;
685         r->u.trigger.action = (unsigned long)action;
686         r->u.trigger.as = atomic_read(&adapter->status);
687         if (port) {
688                 r->u.trigger.ps = atomic_read(&port->status);
689                 r->u.trigger.wwpn = port->wwpn;
690         }
691         if (unit) {
692                 r->u.trigger.us = atomic_read(&unit->status);
693                 r->u.trigger.fcp_lun = unit->fcp_lun;
694         }
695         debug_event(adapter->rec_dbf, action ? 1 : 4, r, sizeof(*r));
696         spin_unlock_irqrestore(&adapter->rec_dbf_lock, flags);
697 }
698
699 /**
700  * zfcp_rec_dbf_event_action - trace event showing progress of recovery action
701  * @id2: identifier
702  * @erp_action: error recovery action struct pointer
703  */
704 void zfcp_rec_dbf_event_action(char *id2, struct zfcp_erp_action *erp_action)
705 {
706         struct zfcp_adapter *adapter = erp_action->adapter;
707         struct zfcp_rec_dbf_record *r = &adapter->rec_dbf_buf;
708         unsigned long flags;
709
710         spin_lock_irqsave(&adapter->rec_dbf_lock, flags);
711         memset(r, 0, sizeof(*r));
712         r->id = ZFCP_REC_DBF_ID_ACTION;
713         memcpy(r->id2, id2, ZFCP_DBF_ID_SIZE);
714         r->u.action.action = (unsigned long)erp_action;
715         r->u.action.status = erp_action->status;
716         r->u.action.step = erp_action->step;
717         r->u.action.fsf_req = (unsigned long)erp_action->fsf_req;
718         debug_event(adapter->rec_dbf, 5, r, sizeof(*r));
719         spin_unlock_irqrestore(&adapter->rec_dbf_lock, flags);
720 }
721
722 /**
723  * zfcp_san_dbf_event_ct_request - trace event for issued CT request
724  * @fsf_req: request containing issued CT data
725  */
726 void zfcp_san_dbf_event_ct_request(struct zfcp_fsf_req *fsf_req)
727 {
728         struct zfcp_send_ct *ct = (struct zfcp_send_ct *)fsf_req->data;
729         struct zfcp_wka_port *wka_port = ct->wka_port;
730         struct zfcp_adapter *adapter = wka_port->adapter;
731         struct ct_hdr *hdr = sg_virt(ct->req);
732         struct zfcp_san_dbf_record *r = &adapter->san_dbf_buf;
733         struct zfcp_san_dbf_record_ct_request *oct = &r->u.ct_req;
734         int level = 3;
735         unsigned long flags;
736
737         spin_lock_irqsave(&adapter->san_dbf_lock, flags);
738         memset(r, 0, sizeof(*r));
739         strncpy(r->tag, "octc", ZFCP_DBF_TAG_SIZE);
740         r->fsf_reqid = (unsigned long)fsf_req;
741         r->fsf_seqno = fsf_req->seq_no;
742         r->s_id = fc_host_port_id(adapter->scsi_host);
743         r->d_id = wka_port->d_id;
744         oct->cmd_req_code = hdr->cmd_rsp_code;
745         oct->revision = hdr->revision;
746         oct->gs_type = hdr->gs_type;
747         oct->gs_subtype = hdr->gs_subtype;
748         oct->options = hdr->options;
749         oct->max_res_size = hdr->max_res_size;
750         oct->len = min((int)ct->req->length - (int)sizeof(struct ct_hdr),
751                        ZFCP_DBF_SAN_MAX_PAYLOAD);
752         debug_event(adapter->san_dbf, level, r, sizeof(*r));
753         zfcp_dbf_hexdump(adapter->san_dbf, r, sizeof(*r), level,
754                          (void *)hdr + sizeof(struct ct_hdr), oct->len);
755         spin_unlock_irqrestore(&adapter->san_dbf_lock, flags);
756 }
757
758 /**
759  * zfcp_san_dbf_event_ct_response - trace event for completion of CT request
760  * @fsf_req: request containing CT response
761  */
762 void zfcp_san_dbf_event_ct_response(struct zfcp_fsf_req *fsf_req)
763 {
764         struct zfcp_send_ct *ct = (struct zfcp_send_ct *)fsf_req->data;
765         struct zfcp_wka_port *wka_port = ct->wka_port;
766         struct zfcp_adapter *adapter = wka_port->adapter;
767         struct ct_hdr *hdr = sg_virt(ct->resp);
768         struct zfcp_san_dbf_record *r = &adapter->san_dbf_buf;
769         struct zfcp_san_dbf_record_ct_response *rct = &r->u.ct_resp;
770         int level = 3;
771         unsigned long flags;
772
773         spin_lock_irqsave(&adapter->san_dbf_lock, flags);
774         memset(r, 0, sizeof(*r));
775         strncpy(r->tag, "rctc", ZFCP_DBF_TAG_SIZE);
776         r->fsf_reqid = (unsigned long)fsf_req;
777         r->fsf_seqno = fsf_req->seq_no;
778         r->s_id = wka_port->d_id;
779         r->d_id = fc_host_port_id(adapter->scsi_host);
780         rct->cmd_rsp_code = hdr->cmd_rsp_code;
781         rct->revision = hdr->revision;
782         rct->reason_code = hdr->reason_code;
783         rct->expl = hdr->reason_code_expl;
784         rct->vendor_unique = hdr->vendor_unique;
785         rct->max_res_size = hdr->max_res_size;
786         rct->len = min((int)ct->resp->length - (int)sizeof(struct ct_hdr),
787                        ZFCP_DBF_SAN_MAX_PAYLOAD);
788         debug_event(adapter->san_dbf, level, r, sizeof(*r));
789         zfcp_dbf_hexdump(adapter->san_dbf, r, sizeof(*r), level,
790                          (void *)hdr + sizeof(struct ct_hdr), rct->len);
791         spin_unlock_irqrestore(&adapter->san_dbf_lock, flags);
792 }
793
794 static void zfcp_san_dbf_event_els(const char *tag, int level,
795                                    struct zfcp_fsf_req *fsf_req, u32 s_id,
796                                    u32 d_id, u8 ls_code, void *buffer,
797                                    int buflen)
798 {
799         struct zfcp_adapter *adapter = fsf_req->adapter;
800         struct zfcp_san_dbf_record *rec = &adapter->san_dbf_buf;
801         unsigned long flags;
802
803         spin_lock_irqsave(&adapter->san_dbf_lock, flags);
804         memset(rec, 0, sizeof(*rec));
805         strncpy(rec->tag, tag, ZFCP_DBF_TAG_SIZE);
806         rec->fsf_reqid = (unsigned long)fsf_req;
807         rec->fsf_seqno = fsf_req->seq_no;
808         rec->s_id = s_id;
809         rec->d_id = d_id;
810         rec->u.els.ls_code = ls_code;
811         debug_event(adapter->san_dbf, level, rec, sizeof(*rec));
812         zfcp_dbf_hexdump(adapter->san_dbf, rec, sizeof(*rec), level,
813                          buffer, min(buflen, ZFCP_DBF_SAN_MAX_PAYLOAD));
814         spin_unlock_irqrestore(&adapter->san_dbf_lock, flags);
815 }
816
817 /**
818  * zfcp_san_dbf_event_els_request - trace event for issued ELS
819  * @fsf_req: request containing issued ELS
820  */
821 void zfcp_san_dbf_event_els_request(struct zfcp_fsf_req *fsf_req)
822 {
823         struct zfcp_send_els *els = (struct zfcp_send_els *)fsf_req->data;
824
825         zfcp_san_dbf_event_els("oels", 2, fsf_req,
826                                fc_host_port_id(els->adapter->scsi_host),
827                                els->d_id, *(u8 *) sg_virt(els->req),
828                                sg_virt(els->req), els->req->length);
829 }
830
831 /**
832  * zfcp_san_dbf_event_els_response - trace event for completed ELS
833  * @fsf_req: request containing ELS response
834  */
835 void zfcp_san_dbf_event_els_response(struct zfcp_fsf_req *fsf_req)
836 {
837         struct zfcp_send_els *els = (struct zfcp_send_els *)fsf_req->data;
838
839         zfcp_san_dbf_event_els("rels", 2, fsf_req, els->d_id,
840                                fc_host_port_id(els->adapter->scsi_host),
841                                *(u8 *)sg_virt(els->req), sg_virt(els->resp),
842                                els->resp->length);
843 }
844
845 /**
846  * zfcp_san_dbf_event_incoming_els - trace event for incomig ELS
847  * @fsf_req: request containing unsolicited status buffer with incoming ELS
848  */
849 void zfcp_san_dbf_event_incoming_els(struct zfcp_fsf_req *fsf_req)
850 {
851         struct zfcp_adapter *adapter = fsf_req->adapter;
852         struct fsf_status_read_buffer *buf =
853                         (struct fsf_status_read_buffer *)fsf_req->data;
854         int length = (int)buf->length -
855                      (int)((void *)&buf->payload - (void *)buf);
856
857         zfcp_san_dbf_event_els("iels", 1, fsf_req, buf->d_id,
858                                fc_host_port_id(adapter->scsi_host),
859                                buf->payload.data[0], (void *)buf->payload.data,
860                                length);
861 }
862
863 static int zfcp_san_dbf_view_format(debug_info_t *id, struct debug_view *view,
864                                     char *out_buf, const char *in_buf)
865 {
866         struct zfcp_san_dbf_record *r = (struct zfcp_san_dbf_record *)in_buf;
867         char *p = out_buf;
868
869         if (strncmp(r->tag, "dump", ZFCP_DBF_TAG_SIZE) == 0)
870                 return 0;
871
872         zfcp_dbf_tag(&p, "tag", r->tag);
873         zfcp_dbf_out(&p, "fsf_reqid", "0x%0Lx", r->fsf_reqid);
874         zfcp_dbf_out(&p, "fsf_seqno", "0x%08x", r->fsf_seqno);
875         zfcp_dbf_out(&p, "s_id", "0x%06x", r->s_id);
876         zfcp_dbf_out(&p, "d_id", "0x%06x", r->d_id);
877
878         if (strncmp(r->tag, "octc", ZFCP_DBF_TAG_SIZE) == 0) {
879                 struct zfcp_san_dbf_record_ct_request *ct = &r->u.ct_req;
880                 zfcp_dbf_out(&p, "cmd_req_code", "0x%04x", ct->cmd_req_code);
881                 zfcp_dbf_out(&p, "revision", "0x%02x", ct->revision);
882                 zfcp_dbf_out(&p, "gs_type", "0x%02x", ct->gs_type);
883                 zfcp_dbf_out(&p, "gs_subtype", "0x%02x", ct->gs_subtype);
884                 zfcp_dbf_out(&p, "options", "0x%02x", ct->options);
885                 zfcp_dbf_out(&p, "max_res_size", "0x%04x", ct->max_res_size);
886         } else if (strncmp(r->tag, "rctc", ZFCP_DBF_TAG_SIZE) == 0) {
887                 struct zfcp_san_dbf_record_ct_response *ct = &r->u.ct_resp;
888                 zfcp_dbf_out(&p, "cmd_rsp_code", "0x%04x", ct->cmd_rsp_code);
889                 zfcp_dbf_out(&p, "revision", "0x%02x", ct->revision);
890                 zfcp_dbf_out(&p, "reason_code", "0x%02x", ct->reason_code);
891                 zfcp_dbf_out(&p, "reason_code_expl", "0x%02x", ct->expl);
892                 zfcp_dbf_out(&p, "vendor_unique", "0x%02x", ct->vendor_unique);
893                 zfcp_dbf_out(&p, "max_res_size", "0x%04x", ct->max_res_size);
894         } else if (strncmp(r->tag, "oels", ZFCP_DBF_TAG_SIZE) == 0 ||
895                    strncmp(r->tag, "rels", ZFCP_DBF_TAG_SIZE) == 0 ||
896                    strncmp(r->tag, "iels", ZFCP_DBF_TAG_SIZE) == 0) {
897                 struct zfcp_san_dbf_record_els *els = &r->u.els;
898                 zfcp_dbf_out(&p, "ls_code", "0x%02x", els->ls_code);
899         }
900         return p - out_buf;
901 }
902
903 static struct debug_view zfcp_san_dbf_view = {
904         "structured",
905         NULL,
906         &zfcp_dbf_view_header,
907         &zfcp_san_dbf_view_format,
908         NULL,
909         NULL
910 };
911
912 static void zfcp_scsi_dbf_event(const char *tag, const char *tag2, int level,
913                                 struct zfcp_adapter *adapter,
914                                 struct scsi_cmnd *scsi_cmnd,
915                                 struct zfcp_fsf_req *fsf_req,
916                                 unsigned long old_req_id)
917 {
918         struct zfcp_scsi_dbf_record *rec = &adapter->scsi_dbf_buf;
919         struct zfcp_dbf_dump *dump = (struct zfcp_dbf_dump *)rec;
920         unsigned long flags;
921         struct fcp_rsp_iu *fcp_rsp;
922         char *fcp_rsp_info = NULL, *fcp_sns_info = NULL;
923         int offset = 0, buflen = 0;
924
925         spin_lock_irqsave(&adapter->scsi_dbf_lock, flags);
926         do {
927                 memset(rec, 0, sizeof(*rec));
928                 if (offset == 0) {
929                         strncpy(rec->tag, tag, ZFCP_DBF_TAG_SIZE);
930                         strncpy(rec->tag2, tag2, ZFCP_DBF_TAG_SIZE);
931                         if (scsi_cmnd != NULL) {
932                                 if (scsi_cmnd->device) {
933                                         rec->scsi_id = scsi_cmnd->device->id;
934                                         rec->scsi_lun = scsi_cmnd->device->lun;
935                                 }
936                                 rec->scsi_result = scsi_cmnd->result;
937                                 rec->scsi_cmnd = (unsigned long)scsi_cmnd;
938                                 rec->scsi_serial = scsi_cmnd->serial_number;
939                                 memcpy(rec->scsi_opcode, scsi_cmnd->cmnd,
940                                         min((int)scsi_cmnd->cmd_len,
941                                                 ZFCP_DBF_SCSI_OPCODE));
942                                 rec->scsi_retries = scsi_cmnd->retries;
943                                 rec->scsi_allowed = scsi_cmnd->allowed;
944                         }
945                         if (fsf_req != NULL) {
946                                 fcp_rsp = (struct fcp_rsp_iu *)
947                                     &(fsf_req->qtcb->bottom.io.fcp_rsp);
948                                 fcp_rsp_info = (unsigned char *) &fcp_rsp[1];
949                                 fcp_sns_info =
950                                     zfcp_get_fcp_sns_info_ptr(fcp_rsp);
951
952                                 rec->rsp_validity = fcp_rsp->validity.value;
953                                 rec->rsp_scsi_status = fcp_rsp->scsi_status;
954                                 rec->rsp_resid = fcp_rsp->fcp_resid;
955                                 if (fcp_rsp->validity.bits.fcp_rsp_len_valid)
956                                         rec->rsp_code = *(fcp_rsp_info + 3);
957                                 if (fcp_rsp->validity.bits.fcp_sns_len_valid) {
958                                         buflen = min((int)fcp_rsp->fcp_sns_len,
959                                                      ZFCP_DBF_SCSI_MAX_FCP_SNS_INFO);
960                                         rec->sns_info_len = buflen;
961                                         memcpy(rec->sns_info, fcp_sns_info,
962                                                min(buflen,
963                                                    ZFCP_DBF_SCSI_FCP_SNS_INFO));
964                                         offset += min(buflen,
965                                                       ZFCP_DBF_SCSI_FCP_SNS_INFO);
966                                 }
967
968                                 rec->fsf_reqid = (unsigned long)fsf_req;
969                                 rec->fsf_seqno = fsf_req->seq_no;
970                                 rec->fsf_issued = fsf_req->issued;
971                         }
972                         rec->old_fsf_reqid = old_req_id;
973                 } else {
974                         strncpy(dump->tag, "dump", ZFCP_DBF_TAG_SIZE);
975                         dump->total_size = buflen;
976                         dump->offset = offset;
977                         dump->size = min(buflen - offset,
978                                          (int)sizeof(struct
979                                                      zfcp_scsi_dbf_record) -
980                                          (int)sizeof(struct zfcp_dbf_dump));
981                         memcpy(dump->data, fcp_sns_info + offset, dump->size);
982                         offset += dump->size;
983                 }
984                 debug_event(adapter->scsi_dbf, level, rec, sizeof(*rec));
985         } while (offset < buflen);
986         spin_unlock_irqrestore(&adapter->scsi_dbf_lock, flags);
987 }
988
989 /**
990  * zfcp_scsi_dbf_event_result - trace event for SCSI command completion
991  * @tag: tag indicating success or failure of SCSI command
992  * @level: trace level applicable for this event
993  * @adapter: adapter that has been used to issue the SCSI command
994  * @scsi_cmnd: SCSI command pointer
995  * @fsf_req: request used to issue SCSI command (might be NULL)
996  */
997 void zfcp_scsi_dbf_event_result(const char *tag, int level,
998                                 struct zfcp_adapter *adapter,
999                                 struct scsi_cmnd *scsi_cmnd,
1000                                 struct zfcp_fsf_req *fsf_req)
1001 {
1002         zfcp_scsi_dbf_event("rslt", tag, level, adapter, scsi_cmnd, fsf_req, 0);
1003 }
1004
1005 /**
1006  * zfcp_scsi_dbf_event_abort - trace event for SCSI command abort
1007  * @tag: tag indicating success or failure of abort operation
1008  * @adapter: adapter thas has been used to issue SCSI command to be aborted
1009  * @scsi_cmnd: SCSI command to be aborted
1010  * @new_fsf_req: request containing abort (might be NULL)
1011  * @old_req_id: identifier of request containg SCSI command to be aborted
1012  */
1013 void zfcp_scsi_dbf_event_abort(const char *tag, struct zfcp_adapter *adapter,
1014                                struct scsi_cmnd *scsi_cmnd,
1015                                struct zfcp_fsf_req *new_fsf_req,
1016                                unsigned long old_req_id)
1017 {
1018         zfcp_scsi_dbf_event("abrt", tag, 1, adapter, scsi_cmnd, new_fsf_req,
1019                             old_req_id);
1020 }
1021
1022 /**
1023  * zfcp_scsi_dbf_event_devreset - trace event for Logical Unit or Target Reset
1024  * @tag: tag indicating success or failure of reset operation
1025  * @flag: indicates type of reset (Target Reset, Logical Unit Reset)
1026  * @unit: unit that needs reset
1027  * @scsi_cmnd: SCSI command which caused this error recovery
1028  */
1029 void zfcp_scsi_dbf_event_devreset(const char *tag, u8 flag,
1030                                   struct zfcp_unit *unit,
1031                                   struct scsi_cmnd *scsi_cmnd)
1032 {
1033         zfcp_scsi_dbf_event(flag == FCP_TARGET_RESET ? "trst" : "lrst", tag, 1,
1034                             unit->port->adapter, scsi_cmnd, NULL, 0);
1035 }
1036
1037 static int zfcp_scsi_dbf_view_format(debug_info_t *id, struct debug_view *view,
1038                                      char *out_buf, const char *in_buf)
1039 {
1040         struct zfcp_scsi_dbf_record *r = (struct zfcp_scsi_dbf_record *)in_buf;
1041         struct timespec t;
1042         char *p = out_buf;
1043
1044         if (strncmp(r->tag, "dump", ZFCP_DBF_TAG_SIZE) == 0)
1045                 return 0;
1046
1047         zfcp_dbf_tag(&p, "tag", r->tag);
1048         zfcp_dbf_tag(&p, "tag2", r->tag2);
1049         zfcp_dbf_out(&p, "scsi_id", "0x%08x", r->scsi_id);
1050         zfcp_dbf_out(&p, "scsi_lun", "0x%08x", r->scsi_lun);
1051         zfcp_dbf_out(&p, "scsi_result", "0x%08x", r->scsi_result);
1052         zfcp_dbf_out(&p, "scsi_cmnd", "0x%0Lx", r->scsi_cmnd);
1053         zfcp_dbf_out(&p, "scsi_serial", "0x%016Lx", r->scsi_serial);
1054         zfcp_dbf_outd(&p, "scsi_opcode", r->scsi_opcode, ZFCP_DBF_SCSI_OPCODE,
1055                       0, ZFCP_DBF_SCSI_OPCODE);
1056         zfcp_dbf_out(&p, "scsi_retries", "0x%02x", r->scsi_retries);
1057         zfcp_dbf_out(&p, "scsi_allowed", "0x%02x", r->scsi_allowed);
1058         if (strncmp(r->tag, "abrt", ZFCP_DBF_TAG_SIZE) == 0)
1059                 zfcp_dbf_out(&p, "old_fsf_reqid", "0x%0Lx", r->old_fsf_reqid);
1060         zfcp_dbf_out(&p, "fsf_reqid", "0x%0Lx", r->fsf_reqid);
1061         zfcp_dbf_out(&p, "fsf_seqno", "0x%08x", r->fsf_seqno);
1062         zfcp_dbf_timestamp(r->fsf_issued, &t);
1063         zfcp_dbf_out(&p, "fsf_issued", "%011lu:%06lu", t.tv_sec, t.tv_nsec);
1064
1065         if (strncmp(r->tag, "rslt", ZFCP_DBF_TAG_SIZE) == 0) {
1066                 zfcp_dbf_out(&p, "fcp_rsp_validity", "0x%02x", r->rsp_validity);
1067                 zfcp_dbf_out(&p, "fcp_rsp_scsi_status", "0x%02x",
1068                              r->rsp_scsi_status);
1069                 zfcp_dbf_out(&p, "fcp_rsp_resid", "0x%08x", r->rsp_resid);
1070                 zfcp_dbf_out(&p, "fcp_rsp_code", "0x%08x", r->rsp_code);
1071                 zfcp_dbf_out(&p, "fcp_sns_info_len", "0x%08x", r->sns_info_len);
1072                 zfcp_dbf_outd(&p, "fcp_sns_info", r->sns_info,
1073                               min((int)r->sns_info_len,
1074                               ZFCP_DBF_SCSI_FCP_SNS_INFO), 0,
1075                               r->sns_info_len);
1076         }
1077         p += sprintf(p, "\n");
1078         return p - out_buf;
1079 }
1080
1081 static struct debug_view zfcp_scsi_dbf_view = {
1082         "structured",
1083         NULL,
1084         &zfcp_dbf_view_header,
1085         &zfcp_scsi_dbf_view_format,
1086         NULL,
1087         NULL
1088 };
1089
1090 /**
1091  * zfcp_adapter_debug_register - registers debug feature for an adapter
1092  * @adapter: pointer to adapter for which debug features should be registered
1093  * return: -ENOMEM on error, 0 otherwise
1094  */
1095 int zfcp_adapter_debug_register(struct zfcp_adapter *adapter)
1096 {
1097         char dbf_name[DEBUG_MAX_NAME_LEN];
1098
1099         /* debug feature area which records recovery activity */
1100         sprintf(dbf_name, "zfcp_%s_rec", dev_name(&adapter->ccw_device->dev));
1101         adapter->rec_dbf = debug_register(dbf_name, dbfsize, 1,
1102                                           sizeof(struct zfcp_rec_dbf_record));
1103         if (!adapter->rec_dbf)
1104                 goto failed;
1105         debug_register_view(adapter->rec_dbf, &debug_hex_ascii_view);
1106         debug_register_view(adapter->rec_dbf, &zfcp_rec_dbf_view);
1107         debug_set_level(adapter->rec_dbf, 3);
1108
1109         /* debug feature area which records HBA (FSF and QDIO) conditions */
1110         sprintf(dbf_name, "zfcp_%s_hba", dev_name(&adapter->ccw_device->dev));
1111         adapter->hba_dbf = debug_register(dbf_name, dbfsize, 1,
1112                                           sizeof(struct zfcp_hba_dbf_record));
1113         if (!adapter->hba_dbf)
1114                 goto failed;
1115         debug_register_view(adapter->hba_dbf, &debug_hex_ascii_view);
1116         debug_register_view(adapter->hba_dbf, &zfcp_hba_dbf_view);
1117         debug_set_level(adapter->hba_dbf, 3);
1118
1119         /* debug feature area which records SAN command failures and recovery */
1120         sprintf(dbf_name, "zfcp_%s_san", dev_name(&adapter->ccw_device->dev));
1121         adapter->san_dbf = debug_register(dbf_name, dbfsize, 1,
1122                                           sizeof(struct zfcp_san_dbf_record));
1123         if (!adapter->san_dbf)
1124                 goto failed;
1125         debug_register_view(adapter->san_dbf, &debug_hex_ascii_view);
1126         debug_register_view(adapter->san_dbf, &zfcp_san_dbf_view);
1127         debug_set_level(adapter->san_dbf, 6);
1128
1129         /* debug feature area which records SCSI command failures and recovery */
1130         sprintf(dbf_name, "zfcp_%s_scsi", dev_name(&adapter->ccw_device->dev));
1131         adapter->scsi_dbf = debug_register(dbf_name, dbfsize, 1,
1132                                            sizeof(struct zfcp_scsi_dbf_record));
1133         if (!adapter->scsi_dbf)
1134                 goto failed;
1135         debug_register_view(adapter->scsi_dbf, &debug_hex_ascii_view);
1136         debug_register_view(adapter->scsi_dbf, &zfcp_scsi_dbf_view);
1137         debug_set_level(adapter->scsi_dbf, 3);
1138
1139         return 0;
1140
1141  failed:
1142         zfcp_adapter_debug_unregister(adapter);
1143
1144         return -ENOMEM;
1145 }
1146
1147 /**
1148  * zfcp_adapter_debug_unregister - unregisters debug feature for an adapter
1149  * @adapter: pointer to adapter for which debug features should be unregistered
1150  */
1151 void zfcp_adapter_debug_unregister(struct zfcp_adapter *adapter)
1152 {
1153         debug_unregister(adapter->scsi_dbf);
1154         debug_unregister(adapter->san_dbf);
1155         debug_unregister(adapter->hba_dbf);
1156         debug_unregister(adapter->rec_dbf);
1157         adapter->scsi_dbf = NULL;
1158         adapter->san_dbf = NULL;
1159         adapter->hba_dbf = NULL;
1160         adapter->rec_dbf = NULL;
1161 }