2388707 - spinlocks and timeslice errors for long sql statement allocations - SAP ASE

The ASE database server falls victim to statement cache spinlocks (SSQLCACHE_SPIN) and timeslice errors. The errors reference the following functions: ubfallocf(), ssql_save_SQL(), sqt_put_in_stmtcache().
Since the failing spid is holding a spinlock when it timeslices out, ASE brings itself down in order to protect the integrity of it's memory structures.

The typical stacktrace in this case is as follows:

00:0011:00000:00640:2016/11/09 16:35:55.73 kernel  timeslice error: spid 640 exhausted its 'time slice' of 100 milliseconds and additional 'cpu grace time' of 1000 ticks (100000 milliseconds). It has been marked for termination.
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  Spinlocks held by  kpid 714277676
 
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  Spinlock SSQLCACHE_SPIN at address 0x0000000143efe900 owned by 714277676
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  End of spinlock display.
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x0000000001632c2b pcstkwalk+0x3a3()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x0000000001632703 ucstkgentrace+0x263()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x000000000162e66e ucbacktrace+0x50()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x000000000078bab9 terminate_process+0x1439()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x00000000016824ea kitermproc+0x1bc()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x00007ffff68f4850 (null)+0x7ffff68f4850()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x000000000161e12d ubfallocf+0x1d()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x0000000001221cf6 ssql_save_SQL+0x465()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x0000000001225b09 sqt_put_in_stmtcache+0x108()00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x00000000011b44c1 s_compile+0x23a1()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  [Handler pc: 0x0x0000000001276380 hdl_stack installed by the following function:-]
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  [Handler pc: 0x0x000000000120b9a0 s_handle installed by the following function:-]
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x000000000120ef88 sequencer+0xf28()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x00000000011d9ab2 execproc+0x762()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x00000000011ccde2 s_execute+0x33c2()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  [Handler pc: 0x0x0000000001276380 hdl_stack installed by the following function:-]
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  [Handler pc: 0x0x000000000120b9a0 s_handle installed by the following function:-]
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x000000000120f52a sequencer+0x14ca()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x00000000007d3897 tdsrecv__dynexec+0x1af()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  pc: 0x00000000007d1e27 tdsrecv_dynamic+0x35a()
00:0011:00000:00640:2016/11/09 16:35:55.74 kernel  [Handler pc: 0x0x0000000001587e60 ut_handle installed by the following function:-]
00:0011:00000:00640:2016/11/09 16:35:55.77 kernel  pc: 0x00000000007f1c56 conn_hdlr+0x2f76()
00:0011:00000:00640:2016/11/09 16:35:55.77 kernel  end of stack trace, spid 640, kpid 714277676, suid 6
00:0011:00000:00640:2016/11/09 16:35:55.77 kernel  ueshutdown: exiting

On ASE 157 SP138 and ASE 160 SP02 PL05, you may encounter additional stacktraces referencing the functions: ssql__rmplans(), ssql_droptext()

• SAP Adaptive Server Enterprise (ASE) 15.7
• SAP Adaptive Server Enterprise (ASE) 16.0
• SAP Adaptive Server Enterprise (ASE) 15.7 for Business Suite
• SAP Adaptive Server Enterprise (ASE) 16.0 for Business Suite

timeslice, spinlocks, SSQLCACHE_SPIN, ubfallocf(), ssql_save_SQL(), statement cache, 799907, 806726 , KBA , BC-DB-SYB , Business Suite on Adaptive Server Enterprise , BC-SYB-ASE , Sybase ASE Database Platform (non Business Suite) , Problem