IBM Support

IJ50999: LOGASSERTFAILED: INODELK.GET_LOCK_STATE() != 0 || INODEFLUSHHOLD

Subscribe to this APAR

By subscribing, you receive periodic emails alerting you to the status of the APAR, along with a link to the fix after it becomes available. You can track this item individually or track all items by product.

Notify me when this APAR changes.

Notify me when an APAR for this component changes.

 

APAR status

  • Closed as program error.

Error description

  • 12863 2024-04-23_11:18:31.389+0800: [X] logAssertFailed:
    inodeLk.get_lock_state() != 0 || inodeFlushHolder ==
    ((cxiThreadId)(((Int32)(IntPtr)(pthread_getspecific(pid_k
    ey)))))
    12864 2024-04-23_11:18:31.389+0800: [X] return code 0,
    reason code 0, log record tag 0
    12865 2024-04-23_11:18:31.389+0800: [I] Freezing
    overwrite mode tracing to preserve failure data
    12866 2024-04-23_11:18:32.029+0800: [X] *** Assert
    exp(inodeLk.get_lock_state() != 0 || inodeFlushHolder ==
    ((cxiThreadId)(((Int32)(IntPtr)(pthread_getspecific(pid_k
    ey)))))) in line 3389 of file /projááááá
    ect/sprelgpfs517/build/rgpfs517s001e/src/avs/fs/mmfs/ts/c
    fgmgr/quotarpc.C
    12867 2024-04-23_11:18:32.029+0800: [E] *** Traceback:
    12868 2024-04-23_11:18:32.029+0800: [E]áááááááá
    2:0x55FA55EF30EA logAssertFailed + 0x3AA at ??:0
    12869 2024-04-23_11:18:32.029+0800: [E]áááááááá
    3:0x55FA56042224 OpenFile::FixShadowTableBlockCount(long
    long, unsigned int, unsigned int, unsigned int, unsigned
    int) + 0x1E4 at ??:0
    12870 2024-04-23_11:18:32.029+0800: [E]áááááááá
    4:0x55FA55CFD660 StripeGroup::deallocBlock(fsDiskAddr
    const&, OpenFile*, long long, int, unsigned int) + 0x580
    at ??:0
    12871 2024-04-23_11:18:32.029+0800: [E]áááááááá
    5:0x55FA55A93EA5 BufferDesc::deallocSurplus() + 0x2C5 at
    ??:0
    12872 2024-04-23_11:18:32.029+0800: [E]áááááááá
    6:0x55FA55AA3A28 BufferDesc::discardBuffer() + 0x398 at
    ??:0
    12873 2024-04-23_11:18:32.029+0800: [E]áááááááá
    7:0x55FA55AA3E9F BufferDesc::flushBuffer(int, long long*,
    FSOperation*) + 0x3AF at ??:0
    12874 2024-04-23_11:18:32.029+0800: [E]áááááááá
    8:0x55FA55CA1E6C GlobalFS::LookForCleanToDo() + 0x38C at
    ??:0
    12875 2024-04-23_11:18:32.029+0800: [E]áááááááá
    9:0x55FA55CA1F96 BufferCleanerBody(void*) + 0x76 at ??:0
    12876 2024-04-23_11:18:32.029+0800: [E]áááááááá
    10:0x55FA559B3582 Thread::callBody(Thread*) + 0x42 at
    ??:0
    12877 2024-04-23_11:18:32.029+0800: [E]áááááááá
    11:0x55FA559A05A0 Thread::callBodyWrapper(Thread*) + 0xA0
    at ??:0
    12878 2024-04-23_11:18:32.029+0800: [E]áááááááá
    12:0x7F9EE2546609 start_thread + 0xD9 at ??:0
    12879 2024-04-23_11:18:32.029+0800: [E]áááááááá
    13:0x7F9EE1EF9353 _fini + 0x29A48B3A1AB7 at ??:0
    12880 mmfsd:
    /project/sprelgpfs517/build/rgpfs517s001e/src/avs/fs/mmfs
    /ts/cfgmgr/quotarpc.C:3389: void logAssertFailed(UInt32,
    const char*, UInt32, Int32, Int32, UInt32, const char*,
    const char*): Assááááá ertion 'inodeLk.get_lock_state()
    != 0 || inodeFlushHolder ==
    ((cxiThreadId)(((Int32)(IntPtr)(pthread_getspecific(pid_k
    ey)))))' failed.
    12881 2024-04-23_11:18:32.030+0800: [E] Signal 6 at
    location 0x7F9EE1E1D00B in process 5936, link reg
    0xFFFFFFFFFFFFFFFF.
    12882 2024-04-23_11:18:32.030+0800: [I] raxááá
    0x0000000000000000á rbxááá 0x00007F90E30E1700
    12883 2024-04-23_11:18:32.030+0800: [I] rcxááá
    0x00007F9EE1E1D00Bá rdxááá 0x0000000000000000
    12884 2024-04-23_11:18:32.030+0800: [I] rspááá
    0x00007F90E30DE4D0á rbpááá 0x00007F9EE1F92588
    12885 2024-04-23_11:18:32.030+0800: [I] rsiááá
    0x00007F90E30DE4D0á rdiááá 0x0000000000000002
    12886 2024-04-23_11:18:32.030+0800: [I] r8áááá
    0x0000000000000000á r9áááá 0x00007F90E30DE4D0
    12887 2024-04-23_11:18:32.030+0800: [I] r10ááá
    0x0000000000000008á r11ááá 0x0000000000000246
    12888 2024-04-23_11:18:32.030+0800: [I] r12ááá
    0x000055FA56C3DEA8á r13ááá 0x0000000000000D3D
    12889 2024-04-23_11:18:32.030+0800: [I] r14ááá
    0x000055FA56C3DFE0á r15ááá 0x00007F9EE1FC7780
    12890 2024-04-23_11:18:32.030+0800: [I] ripááá
    0x00007F9EE1E1D00Bá eflags 0x0000000000000246
    12891 2024-04-23_11:18:32.030+0800: [I] csgsfs
    0x002B000000000033á errááá 0x0000000000000000
    12892 2024-04-23_11:18:32.030+0800: [I] trapno
    0x0000000000000000á oldmsk 0x0000000010017807
    12893 2024-04-23_11:18:32.030+0800: [I] cr2ááá
    0x0000000000000000
    12894 2024-04-23_11:18:32.621+0800: [D] Traceback:
    12895 2024-04-23_11:18:32.621+0800: [D] #0:
    0x00007F9EE1E1D00B _fini + 0x29A48B2C576F at ??:0
    12896 2024-04-23_11:18:32.621+0800: [D] #1:
    0x00007F9EE1DFC859 _fini + 0x29A48B2A4FBD at ??:0
    12897 2024-04-23_11:18:32.621+0800: [D] #2:
    0x00007F9EE1DFC729 _fini + 0x29A48B2A4E8D at ??:0
    12898 2024-04-23_11:18:32.621+0800: [D] #3:
    0x00007F9EE1E0DFD6 _fini + 0x29A48B2B673A at ??:0
    12899 2024-04-23_11:18:32.622+0800: [D] #4:
    0x000055FA55EF310B logAssertFailed + 0x3CB at ??:0
    12900 2024-04-23_11:18:32.622+0800: [D] #5:
    0x000055FA56042224
    OpenFile::FixShadowTableBlockCount(long long, unsigned
    int, unsigned int, unsigned int, unsigned int) + 0x1E4 at
    ??:0
    12901 2024-04-23_11:18:32.622+0800: [D] #6:
    0x000055FA55CFD660 StripeGroup::deallocBlock(fsDiskAddr
    const&, OpenFile*, long long, int, unsigned int) + 0x580
    at ??:0
    12902 2024-04-23_11:18:32.622+0800: [D] #7:
    0x000055FA55A93EA5 BufferDesc::deallocSurplus() + 0x2C5
    at ??:0
    12903 2024-04-23_11:18:32.623+0800: [D] #8:
    0x000055FA55AA3A28 BufferDesc::discardBuffer() + 0x398 at
    ??:0
    12904 2024-04-23_11:18:32.623+0800: [D] #9:
    0x000055FA55AA3E9F BufferDesc::flushBuffer(int, long
    long*, FSOperation*) + 0x3AF at ??:0
    12905 2024-04-23_11:18:32.623+0800: [D] #10:
    0x000055FA55CA1E6C GlobalFS::LookForCleanToDo() + 0x38C
    at ??:0
    12906 2024-04-23_11:18:32.623+0800: [D] #11:
    0x000055FA55CA1F96 BufferCleanerBody(void*) + 0x76 at
    ??:0
    12907 2024-04-23_11:18:32.623+0800: [D] #12:
    0x000055FA559B3582 Thread::callBody(Thread*) + 0x42 at
    ??:0
    12908 2024-04-23_11:18:32.623+0800: [D] #13:
    0x000055FA559A05A0 Thread::callBodyWrapper(Thread*) +
    0xA0 at ??:0
    12909 2024-04-23_11:18:32.623+0800: [D] #14:
    0x00007F9EE2546609 start_thread + 0xD9 at ??:0
    12910 2024-04-23_11:18:32.623+0800: [D] #15:
    0x00007F9EE1EF9353 _fini + 0x29A48B3A1AB7 at ??:0
    

Local fix

Problem summary

  • During mmcheckquota running, a failed user data copy from user
    space to kernel space, leading to some cleanup works, and
    assertion goes off because one mutex related flag is missed
    acquired when fix quota accounting value.
    

Problem conclusion

  • Avoid daemon assertion
    

Temporary fix

Comments

APAR Information

  • APAR number

    IJ50999

  • Reported component name

    SPEC SCALE DME

  • Reported component ID

    5737F34AP

  • Reported release

    517

  • Status

    CLOSED PER

  • PE

    NoPE

  • HIPER

    NoHIPER

  • Special Attention

    NoSpecatt / Xsystem

  • Submitted date

    2024-04-25

  • Closed date

    2024-08-07

  • Last modified date

    2024-08-07

  • APAR is sysrouted FROM one or more of the following:

  • APAR is sysrouted TO one or more of the following:

Fix information

  • Fixed component name

    SPEC SCALE DME

  • Fixed component ID

    5737F34AP

Applicable component levels

[{"Business Unit":{"code":"BU048","label":"IBM Software"},"Product":{"code":"STXKQY"},"Platform":[{"code":"PF025","label":"Platform Independent"}],"Version":"517","Line of Business":{"code":"LOB69","label":"Storage TPS"}}]

Document Information

Modified date:
07 August 2024