From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: X-Spam-Checker-Version: SpamAssassin 3.4.0 (2014-02-07) on aws-us-west-2-korg-lkml-1.web.codeaurora.org X-Spam-Level: X-Spam-Status: No, score=-5.4 required=3.0 tests=BAYES_00,DKIMWL_WL_HIGH, DKIM_SIGNED,DKIM_VALID,DKIM_VALID_AU,HEADER_FROM_DIFFERENT_DOMAINS, MAILING_LIST_MULTI,SPF_HELO_NONE,SPF_PASS,USER_AGENT_SANE_2 autolearn=no autolearn_force=no version=3.4.0 Received: from mail.kernel.org (mail.kernel.org [198.145.29.99]) by smtp.lore.kernel.org (Postfix) with ESMTP id 8CBF5C433E2 for ; Thu, 10 Sep 2020 17:14:29 +0000 (UTC) Received: from vger.kernel.org (vger.kernel.org [23.128.96.18]) by mail.kernel.org (Postfix) with ESMTP id 270E8214F1 for ; Thu, 10 Sep 2020 17:14:29 +0000 (UTC) Authentication-Results: mail.kernel.org; dkim=pass (1024-bit key) header.d=redhat.com header.i=@redhat.com header.b="JZoLW8Qz" Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1726657AbgIJRO2 (ORCPT ); Thu, 10 Sep 2020 13:14:28 -0400 Received: from us-smtp-delivery-124.mimecast.com ([63.128.21.124]:20547 "EHLO us-smtp-delivery-124.mimecast.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1726898AbgIJQhP (ORCPT ); Thu, 10 Sep 2020 12:37:15 -0400 DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=redhat.com; s=mimecast20190719; t=1599755802; h=from:from:reply-to:subject:subject:date:date:message-id:message-id: to:to:cc:mime-version:mime-version:content-type:content-type: content-transfer-encoding:content-transfer-encoding; bh=PWATb8UObvgncjGjIO1tn8rpWv1TJOdnlFiYW4a4RQI=; b=JZoLW8Qzgim8x+8y95sy5pm9O474MsA7wyeeO41qqANcnZXwcdicNXQorm+/thH6hdsSqU rSZU05/0JKhlQrQSYqKdwaB/VZ4FDZWiNYRpRqs/U8pLRnDsLFRPVMDPbmbqTBMuMXa6Vb TVxYHossJb9hV/OFEsNZ+3pzEJiqVPI= Received: from mail-qt1-f200.google.com (mail-qt1-f200.google.com [209.85.160.200]) (Using TLS) by relay.mimecast.com with ESMTP id us-mta-192-NyWHyUloN82klFCHgm6rUQ-1; Thu, 10 Sep 2020 12:36:40 -0400 X-MC-Unique: NyWHyUloN82klFCHgm6rUQ-1 Received: by mail-qt1-f200.google.com with SMTP id c5so4525117qtd.12 for ; Thu, 10 Sep 2020 09:36:40 -0700 (PDT) X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20161025; h=x-gm-message-state:message-id:subject:from:to:date:mime-version :content-transfer-encoding; bh=PWATb8UObvgncjGjIO1tn8rpWv1TJOdnlFiYW4a4RQI=; b=R+1zDnc9aEEssBdlZNnK6yNt4pyD8uWXeeguSuQQlUCnV1TCCHbDTLbbZJs4Q2QoG4 WH3TDeUKj09YnwNx2GJHE36V8dXnqcH/vIrsoQw5Jn95beRggv8Ac6PQK1+k8Z1Sk3nS pRPCRzfMjkvM+MaDGbcbP7VYNgPpHDtQCnCW2rUwtGseCtydwAedVRL7ULoKVKKKyy0W bNGs/ZvTfzMCt/XSLvy2tWXkKc4EoCyVfFXZK3v5NbcLl6QVag+TcfOMGNE5bflDYjzh ye7j4HZS2JXrSNUhd14+6hyyvwIoNQXqdyIxThXSCLp3QS/2CRmjMlOL7tDRUU8jzFhC UqPw== X-Gm-Message-State: AOAM531MEimpV0c2y8VX/lN3l0KHzAyxjDNe777NISFEww+fwraHiVnK f1qnQ82y8MyQly9FRejLRsExb/CJc9gjFqLUxVHbIARzE5N5ajF2V+K0BR8mJiCgvQra4TIRF8Y PwzGNde26ESGHXdDYyuuoYg== X-Received: by 2002:ac8:1923:: with SMTP id t32mr7528999qtj.23.1599755799698; Thu, 10 Sep 2020 09:36:39 -0700 (PDT) X-Google-Smtp-Source: ABdhPJxMESRn9EzKHNcYJ0vzJdxoQs1Ditxx8CR3SIFO6T59oT0ttQy9GC18N7OXoAPFiizOPd4hpg== X-Received: by 2002:ac8:1923:: with SMTP id t32mr7528975qtj.23.1599755799344; Thu, 10 Sep 2020 09:36:39 -0700 (PDT) Received: from loberhel7laptop ([2600:6c64:4e80:f1:4a17:2cf9:6a8a:f150]) by smtp.gmail.com with ESMTPSA id z37sm8119355qtz.67.2020.09.10.09.36.38 for (version=TLS1_2 cipher=ECDHE-ECDSA-AES128-GCM-SHA256 bits=128/128); Thu, 10 Sep 2020 09:36:38 -0700 (PDT) Message-ID: Subject: lpfc on latest 5.9 is very chatty on modprobe From: Laurence Oberman To: linux-scsi Date: Thu, 10 Sep 2020 12:36:37 -0400 Content-Type: text/plain; charset="UTF-8" X-Mailer: Evolution 3.28.5 (3.28.5-5.el7) Mime-Version: 1.0 Content-Transfer-Encoding: 7bit Sender: linux-scsi-owner@vger.kernel.org Precedence: bulk List-ID: X-Mailing-List: linux-scsi@vger.kernel.org Hello I was testing lpfc on 5.9 and noticed a lot more logging on modprobe. Even when lpfc_log_verbose = 0 root@segstorage3 ~]# cat /sys/class/scsi_host/host*/lpfc_log_verbose 0x0 0x0 0x0 0x0 Wondered if its been there for a while and its just because I now started testing. [ 143.398194] scsi host3: Emulex LPe12000 PCIe Fibre Channel Adapter on PCI bus 0e device 00 irq 213 [ 145.598711] scsi host4: Emulex LPe12000 PCIe Fibre Channel Adapter on PCI bus 0e device 01 irq 216 [ 147.877559] lpfc 0000:0b:00.0: 2:6101 Disabling NVME support: Not supported by firmware (0 0) x3 [ 147.929264] lpfc 0000:0b:00.0: 2:2574 IO channels: hdwQ 64 IRQ 64 MRQ: 0 [ 147.974727] scsi host5: Emulex LPe16000 16Gb PCIe Fibre Channel Adapter on PCI bus 0b device 00 irq 219 PCI resettable [ 149.156406] lpfc 0000:0b:00.0: 2:3176 Port Name 0 Physical Link is functional [ 149.284537] lpfc 0000:0b:00.1: 3:6101 Disabling NVME support: Not supported by firmware (0 0) x3 [ 149.336809] lpfc 0000:0b:00.1: 3:2574 IO channels: hdwQ 64 IRQ 64 MRQ: 0 [ 149.388033] scsi host6: Emulex LPe16000 16Gb PCIe Fibre Channel Adapter on PCI bus 0b device 01 irq 284 PCI resettable [ 150.848392] lpfc 0000:0b:00.1: 3:3176 Port Name 1 Physical Link is functional [ 151.477690] lpfc 0000:0b:00.0: 2:1303 Link Up Event x1 received Data: x1 x0 x20 x0 x0 x0 0 [ 151.526732] lpfc 0000:0b:00.0: start 183 end 182 cnt 256 [ 151.559193] lpfc 0000:0b:00.0: 183: [ 148.759023] 2:2593 WQ setup: wq[35]-id=35 assoc=35, cq[35]-id=35 [ 151.617510] lpfc 0000:0b:00.0: 184: [ 148.763089] 2:(0):0356 Mailbox cmd x9b (x1/xc) Status x0 Data: x1 x44 x0 x0 x0 x10c x0 x4 x4 x24 xb8000000 x24 x1000 CQ: x0 x0 x0 x90000000 [ 151.715751] lpfc 0000:0b:00.0: 185: [ 148.763090] 2:6087 CQ setup: cq[36]-id=36, parent eq[36]-id=36 [ 151.772034] lpfc 0000:0b:00.0: 186: [ 148.767155] 2:(0):0356 Mailbox cmd x9b (xc/x1) Status x0 Data: x1 x54 x0 x0 x0 xc01 x0 x4 x4 x240024 x4008504 x0 xcf400000 CQ: x0 x0 x0 x90000000 [ 151.872722] lpfc 0000:0b:00.0: 187: [ 148.767156] 2:2593 WQ setup: wq[36]-id=36 assoc=36, cq[36]-id=36 [ 151.929396] lpfc 0000:0b:00.0: 188: [ 148.771220] 2:(0):0356 Mailbox cmd x9b (x1/xc) Status x0 Data: x1 x44 x0 x0 x0 x10c x0 x4 x4 x25 xb8000000 x25 x1000 CQ: x0 x0 x0 x90000000 [ 152.030050] lpfc 0000:0b:00.0: 189: [ 148.771221] 2:6087 CQ setup: cq[37]-id=37, parent eq[37]-id=37 [ 152.085622] lpfc 0000:0b:00.0: 190: [ 148.775285] 2:(0):0356 Mailbox cmd x9b (xc/x1) Status x0 Data: x1 x54 x0 x0 x0 xc01 x0 x4 x4 x250025 x4008504 x0 xbc7e0000 CQ: x0 x0 x0 x90000000 [ 152.185681] lpfc 0000:0b:00.0: 191: [ 148.775287] 2:2593 WQ setup: wq[37]-id=37 assoc=37, cq[37]-id=37 [ 152.243645] lpfc 0000:0b:00.0: 192: [ 148.779351] 2:(0):0356 Mailbox cmd x9b (x1/xc) Status x0 Data: x1 x44 x0 x0 x0 x10c x0 x4 x4 x26 xb8000000 x26 x1000 CQ: x0 x0 x0 x90000000 [ 152.341442] lpfc 0000:0b:00.0: 193: [ 148.779352] 2:6087 CQ setup: cq[38]-id=38, parent eq[38]-id=38 [ 152.399734] lpfc 0000:0b:00.0: 194: [ 148.783416] 2:(0):0356 Mailbox cmd x9b (xc/x1) Status x0 Data: x1 x54 x0 x0 x0 xc01 x0 x4 x4 x260026 x4008504 x0 x5ef40000 CQ: x0 x0 x0 x90000000 [ 152.501390] lpfc 0000:0b:00.0: 195: [ 148.783417] 2:2593 WQ setup: wq[38]-id=38 assoc=38, cq[38]-id=38 [ 152.557531] lpfc 0000:0b:00.0: 196: [ 148.787481] 2:(0):0356 Mailbox cmd x9b (x1/xc) Status x0 Data: x1 x44 x0 x0 x0 x10c x0 x4 x4 x27 xb8000000 x27 x1000 CQ: x0 x0 x0 x90000000 .. .. [ 190.872503] lpfc 0000:0b:00.0: start 109 end 115 cnt 6 [ 190.872506] lpfc 0000:0b:00.0: 109: [ 190.871058] 2:(0):0117 Xmit ELS response x1 to remote NPORT x20300 I/O tag: xb92, size: x8 port_state x20 rpi x10 fc_flag x800110 [ 190.872507] lpfc 0000:0b:00.0: 110: [ 190.871060] 2:(0):0129 Xmit ELS RJT xb00 response tag xb92 xri xffff, did x20300, nlp_flag x80000000, nlp_state x7, rpi x10 [ 190.872509] lpfc 0000:0b:00.0: 111: [ 190.871085] 2:(0):0110 ELS response tag xb92 completes Data: x0 x0 x0 x20300 x80000000 x7 x10 [ 190.872511] lpfc 0000:0b:00.0: 112: [ 190.872497] 2:2538 Received frame rctl:x22, type:x1, frame Data:220a0300 00010600 01290000 00000000 0048ffff 00000000 00000000 [ 190.872512] lpfc 0000:0b:00.0: 113: [ 190.872500] 2:(0):0929 FIND node DID Data: xffff896e4faf2c00 x10600 x80000000 x7001801 xc xffff896e4faf1200 [ 190.872514] lpfc 0000:0b:00.0: 114: [ 190.872501] 2:(0):0112 ELS command x14001023 received from NPORT x10600 Data: x20 x800110 xa0300 xa0300 [ 190.872515] lpfc 0000:0b:00.0: 2:(0):0115 Unknown ELS command x14001023 received from NPORT x10600 [ 190.873239] lpfc 0000:0b:00.0: start 115 end 121 cnt 6 [ 190.873241] lpfc 0000:0b:00.0: 115: [ 190.872517] 2:(0):0117 Xmit ELS response x1 to remote NPORT x10600 I/O tag: xb8f, size: x8 port_state x20 rpi xc fc_flag x800110 [ 190.873243] lpfc 0000:0b:00.0: 116: [ 190.872519] 2:(0):0129 Xmit ELS RJT xb00 response tag xb8f xri xffff, did x10600, nlp_flag x80000000, nlp_state x7, rpi xc [ 190.873244] lpfc 0000:0b:00.0: 117: [ 190.872545] 2:(0):0110 ELS response tag xb8f completes Data: x0 x0 x0 x10600 x80000000 x7 xc [ 190.873246] lpfc 0000:0b:00.0: 118: [ 190.873234] 2:2538 Received frame rctl:x22, type:x1, frame Data:220a0300 00010700 01290000 00000000 008cffff 00000000 00000000 [ 190.873249] lpfc 0000:0b:00.0: 119: [ 190.873236] 2:(0):0929 FIND node DID Data: xffff896e4faf0600 x10700 x80000000 x7001801 xd xffff896e4faf3600 I have not investogated the masking changes yet, decided to first ask. Thanks Laurence