Hi, for a while now, we see NFS problems quite often we've not seen before, here between an oi_151a9 client and a recent 2024:12:12 illumos-b7fe974ee3 server; starts with a file <null string>, then mixed up seqids, loosing track for several file openings:
Mar 5 10:08:34 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth11][Mntpt: /home/Processor/Work_22]Operation open for file <null string> (rnode_pt 0x0), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 0 for operation . Mar 5 10:08:38 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth11][Mntpt: /home/Processor/Work_22]Operation open for file ./CEDRO_2_ES/SF6:X_262.0/bout/create_coarse_inp_30300.log (rnode_pt 0xfffffeed90af63f8), pid 0 using seqid 2 got NFS4ERR_BAD_SEQID. Last good seqid was 1 for operation open. Mar 5 10:08:40 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth11][Mntpt: /home/Processor/Work_22]Operation open for file ./CEDRO_2_ES/SF6:X_262.0/bout/create_coarse_inp_30300.log (rnode_pt 0xfffffeed90af63f8), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 1 for operation open.
Mar 5 10:08:52 imksuns11 last message repeated 2 timesMar 5 10:09:08 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth11][Mntpt: /home/Processor/Work_22]Operation open for file <null string> (rnode_pt 0x0), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 0 for operation .
Mar 5 10:09:16 imksuns11 last message repeated 8 timesMar 5 10:09:17 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth11][Mntpt: /home/Processor/Work_22]Operation open for file ./CEDRO_2_ES/SF6:X_262.0/bout/create_coarse_inp_30304.log (rnode_pt 0xfffffef451fe7490), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 0 for operation . Mar 5 10:09:57 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth11][Mntpt: /home/Processor/Work_22]Operation open for file ./CEDRO_2_ES/SF6:X_262.0/bout/create_coarse_inp_30312.log (rnode_pt 0xfffffef399794c30), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 0 for operation . Mar 5 10:09:57 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth11][Mntpt: /home/Processor/Work_22]Operation open for file ./CEDRO_2_ES/SF6:X_262.0/bout/create_coarse_inp_30304.log (rnode_pt 0xfffffef451fe7490), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 0 for operation . Mar 5 10:09:58 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth11][Mntpt: /home/Processor/Work_22]Operation open for file ./CEDRO_2_ES/SF6:X_262.0/bout/create_coarse_inp_30303.log (rnode_pt 0xfffffeecd8cd4430), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 0 for operation . Mar 5 10:10:00 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth11][Mntpt: /home/Processor/Work_22]Operation open for file ./CEDRO_2_ES/SF6:X_262.0/bout/create_coarse_inp_30301.log (rnode_pt 0xfffffee4033730a0), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 0 for operation . Mar 5 10:10:04 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth11][Mntpt: /home/Processor/Work_22]Operation open for file ./CEDRO_2_ES/SF6:X_262.0/bout/create_coarse_inp_30310.log (rnode_pt 0xfffffed46c83cbc0), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 0 for operation . Mar 5 11:18:35 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth12][Mntpt: /home/Processor/Work_36]Operation open for file <null string> (rnode_pt 0x0), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 0 for operation .
Mar 5 11:18:39 imksuns11 last message repeated 4 timesMar 5 11:19:48 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth12][Mntpt: /home/Processor/Work_36]Operation open for file ./DRAGO_ES/SF6:X_262.0/bout/create_coarse_inp_37892.log (rnode_pt 0xfffffed675a5aba8), pid 0 using seqid 2 got NFS4ERR_BAD_SEQID. Last good seqid was 1 for operation open. Mar 5 11:19:50 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth12][Mntpt: /home/Processor/Work_36]Operation open for file ./DRAGO_ES/SF6:X_262.0/bout/create_coarse_inp_37892.log (rnode_pt 0xfffffed675a5aba8), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 1 for operation open.
Mar 5 11:19:54 imksuns11 last message repeated 1 timeMar 5 11:20:00 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth12][Mntpt: /home/Processor/Work_36]Operation open for file ./DRAGO_ES/SF6:X_262.0/bout/create_coarse_inp_37894.log (rnode_pt 0xfffffee05cbce480), pid 0 using seqid 2 got NFS4ERR_BAD_SEQID. Last good seqid was 1 for operation open. Mar 5 11:20:01 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth12][Mntpt: /home/Processor/Work_36]Operation open for file ./DRAGO_ES/SF6:X_262.0/bout/create_coarse_inp_37895.log (rnode_pt 0xfffffee05cf8bb78), pid 0 using seqid 2 got NFS4ERR_BAD_SEQID. Last good seqid was 1 for operation open. Mar 5 11:20:02 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth12][Mntpt: /home/Processor/Work_36]Operation open for file ./DRAGO_ES/SF6:X_262.0/bout/create_coarse_inp_37892.log (rnode_pt 0xfffffed675a5aba8), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 1 for operation open. Mar 5 11:20:03 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth12][Mntpt: /home/Processor/Work_36]Operation open for file ./DRAGO_ES/SF6:X_262.0/bout/create_coarse_inp_37893.log (rnode_pt 0xfffffee4eee22830), pid 0 using seqid 2 got NFS4ERR_BAD_SEQID. Last good seqid was 1 for operation open. Mar 5 11:20:11 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth12][Mntpt: /home/Processor/Work_36]Operation open for file ./DRAGO_ES/SF6:X_262.0/bout/create_coarse_inp_37891.log (rnode_pt 0xfffffee7aed1fbe8), pid 0 using seqid 2 got NFS4ERR_BAD_SEQID. Last good seqid was 1 for operation open. Mar 5 11:22:23 imksuns11 nfs: [ID 435015 kern.info] NOTICE: [NFS4][Server: imksunth12][Mntpt: /home/Processor/Work_36]Operation open for file <null string> (rnode_pt 0x0), pid 0 using seqid 1 got NFS4ERR_BAD_SEQID. Last good seqid was 0 for operation .
This probably also has happened with new clients, but I'm yet not sure about that. It's happening often enough that it is significantly disturbing operations here. -- Dr.Udo Grabowski Inst.of Meteorology & Climate Research IMKASF-SAT https://www.imk-asf.kit.edu/english/sat.php KIT - Karlsruhe Institute of Technology https://www.kit.edu Postfach 3640,76021 Karlsruhe,Germany T:(+49)721 608-26026 F:-926026
smime.p7s
Description: S/MIME Cryptographic Signature
------------------------------------------ illumos: illumos-discuss Permalink: https://illumos.topicbox.com/groups/discuss/T939fcf899d5526b0-M026c1668963c2da2e1f55fe6 Delivery options: https://illumos.topicbox.com/groups/discuss/subscription
