Symptom
- After a refresh of a SAP Netweaver client's HANA DB system with the data backup from another HANA System, the Client copy export fails when attempting to export the client system to target.
SCC export and transport logs show the following :
ERROR: No entry for SBPKT00009 in E070 or error during access.
ERROR: RFC function TRINT_PROGRESS_INDICATOR error: Error DBSQL_SQL_ERROR occured. P1=RFCCBWHITELIST P2=SQL code: 4 P3=SQL message: cannot allocate enough memory: Out of memory on query open P4=SQL dbsl rc: 99
ERROR SBPKT00009 SBP E 0012 20230405090334 BASISSUPPORT BASISSUPPORT sbx
ERROR: RFC function TRINT_PROGRESS_INDICATOR returned RFC_ILLEGAL_STATE
1 ETP199 ######################################
1 ETP199X######################################
1 ETP150 MAIN EXPORT
1 ETP101 transport order : "SBPKT00009"
1 ETP102 system : "SID"
1 ETP108 tp path : "tp"
1 ETP109 version and release : "381.24.14" "753"
1 ETP198
4 ETW000 R3trans version 6.26 (release 753 - 20.09.22 - 12:12:00 with rjh730).
4 ETW000 unicode enabled version
4 ETW000 ===============================================
4 ETW000
4 ETW000 date&time : 05.04.2023 - 09:01:42
4 ETW000 control file: /usr/sap/trans/tmp/SBPKKT00009.SID
4 ETW000 > #pid 38865 on sbx (sbpadm)
4 ETW000 > export
4 ETW000 > file='/usr/sap/trans/data/RT00009.SID'
4 ETW000 > client=400
4 ETW000 > buffersync=yes
4 ETW000 >
4 ETW000 > use comm 'SBPKT00009'
4 ETW000 R3trans was called as follows: R3trans -u 18 -w /usr/sap/trans/tmp/SBPET00009.SID /usr/sap/trans/tmp/SBPKKT00009.SBP
4 ETW000 active unconditional modes: 18
4 ETW000 Connected to DBMS = HDB --- SERVER = '' PORT = '' --- SYSTEM = 'SID'.
4 ETW690 COMMIT "0" "0"
4 ETW000 trace at level 1 opened for a given file pointer
4 ETW000
4 ETW000 ================== STEP 1 =====================
4 ETW000 date&time : 05.04.2023 - 09:01:42
4 ETW000 function : EXPORT
4 ETW000 data file : /usr/sap/trans/data/RT00009.SID
..........
Memory allocation issues encountered :
3 ETW678Xstart export of "R3TRTABU/DSD/SL_TGTYP" ...
4 ETW000 [dbsdbsql.cpp,00000] *** ERROR => prepare() of C_0128, rc=1, rcSQL=4
4 ETW000 [dbslsdb.cpp ,00000] *** ERROR => PREPARE C_0128 on connection 0, rc=4
4 ETW000 [ dev trc,00000] SQLCODE : 4
4 ETW000 [ dev trc,00000] SQLERRTEXT : cannot allocate enough memory: Out of memory on query compilation
4 ETW000 [ dev trc,00000] sc_p=7fc823483c20,no=128,idc_p=7fc823438760,con=0,act=1,slen=80,smax=256,#vars=1,stmt=55afb21cd810,table=/DSD/SL_TGTYP
4 ETW000 [ dev trc,00000] prepared=0,lastPrepareTime=,nativeSQL=0,ignoreStmtId=0
4 ETW000 [ dev trc,00000] stmtid = <3//DSD/SL_TGTYP /1619001408/20141020140645>
4 ETW000 [ dev trc,00000] SELECT * FROM "/DSD/SL_TGTYP" WHERE "CLIENT" = ? ORDER BY "CLIENT" , "TOLTYP_ID";
4 ETW000 [ dbtrtab ,00000] ***LOG BY4=>sql error 4 performing PRE on table /DSD/SL_TGTYP
4 ETW000 [ dbtrtab ,00000] ***LOG BY0=>cannot allocate enough memory: Out of memory on query compilation
4 ETW000 [ dev trc,00000] db_con_test_and_open: bad cursor delta, reset open cursors to 0
4 ETW000 [ dbcrtab ,00000] ***LOG BY7=>invalid cursor 65535 specified for database operation
2EETW125 SQL error "4" during "/DSD/SL_TGTYP" access: "cannot allocate enough memory: Out of memory on query compilation"
4 ETW000 0 entries from /DSD/SL_TGTYP exported (400).
4 ETW000 [dbsdbsql.cpp,00000] *** ERROR => execute() of C_0026, #rec=0, rc=1, rcSQL=129
4 ETW000 [dbslsdb.cpp ,00000] *** ERROR => EXECUTE C_0026 on connection 0, rc=129
4 ETW000 [ dev trc,00000] SQLCODE : 129
4 ETW000 [ dev trc,00000] SQLERRTEXT : transaction rolled back by an internal error: exception: no. 6946 ( \
4 ETW000 [ dev trc,00000] AttributeEngine/AttributeStoreFile.h: 395) TID: 7140. No further info because of\
4 ETW000 [ dev trc,00000] bad allocation in exception object.
4 ETW000 exception: no. 6946 ( Attribu\
4 ETW000 [ dev trc,00000] teEngine/AttributeStoreFile.h: 395) TID: 7140. No further info because of bad allo\
4 ETW000 [ dev trc,00000] cation in exception object.
4 ETW000 [ dev trc,00000] sc_p=7fc82347ff90,no=26,idc_p=7fc823434c00,con=0,act=1,slen=104,smax=256,#vars=2,stmt=55afb04a0f40,table=DD02L
4 ETW000 [ dev trc,00000] prepared=0,lastPrepareTime=20230405090143,nativeSQL=0,ignoreStmtId=0
4 ETW000 [ dev trc,00000] stmtid = <3/DD02L /1627390048/20160515192653>
4 ETW000 [ dev trc,00000] SELECT * FROM "DD02L" WHERE "TABNAME" = ? AND "AS4LOCAL" = ? ORDER BY "TABNAME" , "AS4LOCAL" , "AS4V\
4 ETW000 [ dev trc,00000] ERS";
4 ETW000 [ dbtrtab ,00000] ***LOG BY4=>sql error 129 performing SEL on table DD02L
4 ETW000 [ dbtrtab ,00000] ***LOG BY0=>transaction rolled back by an internal error: exception: no. 6946 ( AttributeEngine/AttributeStoreFile.h: 395) TID: 7140. No further info because of bad allocation in exception object.
4 ETW000 exception: no. 6946 ( AttributeEngine/AttributeStoreFile.h: 395) TID: 7140. No further info because of bad allocation in exception object.
2EETW000 sap_dext called with msgnr "1":
2EETW000 ---- db call info ----
2EETW000 function: db_setget
2EETW000 fcode: RT_SETB (65535)
2EETW000 tabname: DD02L
2EETW000 len (char): 31
2EETW000 key: /DSD/SL_TGTYP A
2EETW000 retcode: 1
2EETW000 sql error: 129 transaction rolled back by an internal error: exception: no. 6946 ( AttributeEngine/AttributeStoreFile.h: 395) TID: 7140. No further info becau
4 ETW000 last stringcontext was reset by twexport.c :1527
2EETW125 SQL error "129" during "" access: "transaction rolled back by an internal error: exception: no. 6946 ( AttributeEngine/AttributeStoreFile.h: 395) TID: 7140. No further info because of bad allocation in exception object."
4 ETW690 ROLLBACK "0" "0"
2EETP200 Export with errors, request will not be imported
1 ETP150 MAIN EXPORT
1 ETP110 end date and time : "2023-04-05-09:03:11"
1 ETP111 exit code : "12"
1 ETP199 ######################################
- The HANA service traces reveal that the backup catalog heap allocators are causing consistent resource container shrinks and even OOMs on the Nameserver and Indexserver :
Reason for shrink: IPMM (other process). Request ID= 6523871
ShrinkCaller
1: 0x00007f2d0f18d586 in ResourceManager::ShrinkInformation::updateShrinkInformation(char const*, unsigned long, bool)+0xb2 at ResourceContainerShrink.cpp:725 (libhdbbasis.so)
2: 0x00007f2d0f186b94 in ResourceManager::ResourceContainerImpl::genericShrink(unsigned long, unsigned long, char const*, unsigned long, MemoryManager::ReclaimMemoryTaskType)+0x640 at ResourceContainerShrink.cpp:2491 (libhdbbasis.so)
3: 0x00007f2d0f2a4176 in MemoryManager::CompactionThread::compactCompactors(unsigned long, bool&, char const*, unsigned long, MemoryManager::ReclaimMemoryTaskType)+0x582 at mmCompactionTrigger.cpp:955 (libhdbbasis.so)
4: 0x00007f2d0f29f4d6 in MemoryManager::CompactionThread::doCompactionIntern(MemoryManager::Precharge&, unsigned long, unsigned long, unsigned long, unsigned long&, char const*, unsigned long, MemoryManager::ReclaimMemoryTaskType, bool)+0xfc2 at mmCompactionTrigger.cpp:1116 (libhdbbasis.so)
5: 0x00007f2d0f2a04f5 in MemoryManager::CompactionThread::doIpmmCompactionIntern(unsigned long, int, unsigned long, unsigned long, unsigned long, MemoryManager::ProcessSlot::RequestedTask)+0x1b1 at mmCompactionTrigger.cpp:1704 (libhdbbasis.so)
6: 0x00007f2d0f2a143c in MemoryManager::CompactionThread::run(void*&)+0x188 at mmCompactionTrigger.cpp:1659 (libhdbbasis.so)
7: 0x00007f2d0f40ccf5 in Execution::Thread::staticMainImp(Execution::Thread*)+0x531 at Thread.cpp:522 (libhdbbasis.so)
8: 0x00007f2d0f3fc978 in Execution::pthreadFunctionWrapper(Execution::Thread*)+0x1e4 at ThreadInterposition.cpp:639 (libhdbbasis.so)
9: 0x00007f2d0ea58539 in start_thread+0xd5 (libpthread.so.0)
10: 0x00007f2d0d0daecf in __GI___clone+0x3b (libc.so.6)
IPMM short info:
GLOBAL_ALLOCATION_LIMIT (GAL) = 292.84gb (314438582272b), SHARED_MEMORY = 19.55gb (20996517888b), CODE_SIZE = 2.88gb (3100405760b), OOM_RESERVATION_ALLOCATOR_SIZE = 96.14mb (100810752b)
PID=48854 (hdbnameserver), PAL=483.78gb (519464042496b), AB=153.61gb (164941623296b), UA=0, U=145.08gb (155783798981b), FSL=0b, FMC=0b
PID=49509 (hdbcompileserver), PAL=483.78gb (519464042496b), AB=447.98mb (469745664b), UA=0, U=115.51mb (121128841b), FSL=0b, FMC=0b, Blocked=796.86mb (835575808b)
PID=49512 (hdbpreprocessor), PAL=483.78gb (519464042496b), AB=217.05mb (227594240b), UA=0, U=121.94mb (127865865b), FSL=0b, FMC=0b
PID=49565 (hdbxsengine), PAL=483.78gb (519464042496b), AB=846.22mb (887328768b), UA=0, U=667.26mb (699678136b), FSL=0b, FMC=0b
PID=49562 (hdbindexserver), PAL=483.78gb (519464042496b), AB=115.26gb (123764838400b), UA=0, U=104.27gb (111960169124b), FSL=0b, FMC=0b
PID=50706 (hdbwebdispatcher), PAL=483.78gb (519464042496b), AB=408.50mb (428347392b), UA=0, U=325.82mb (341649832b), FSL=0b, FMC=0b
Total allocated memory= 293.19gb (314816401408b)
Total used memory = 272.99gb (293131214427b)
Sum AB = 290719477760
Sum Used = 269034290779
Heap memory fragmentation: 6
Top "M_HEAP_MEMORY" allocators (component, name, size). Ordered descending by exclusive_size_in_use.
1: Monitoring & Statistical Data: Pool/RowEngine/MonitorView/StatisticsMonitors/M_DEV_BACKUP_CATALOG_LOG 59.16gb (63528320512b) (256mb (268435456b) precharged, 1.48kb (1520b) in preparation)
2: Monitoring & Statistical Data: Pool/RowEngine/MonitorView/StatisticsMonitors/M_DEV_BACKUP_CATALOG_LOG_FILES 35.92gb (38577895936b) (256mb (268435456b) precharged, 1.21kb (1240b) in preparation)
3: Monitoring & Statistical Data: Pool/Statistics 33.15gb (35605021424b)
4: System: Pool/BackupRecoveryAllocator 10.08gb (10831460384b)
5: System: AllocateOnlyAllocator-unlimited/FLA-UL<120,256>/BigBlockInfoAllocator 2.96gb (3186451560b)
6: System: AllocateOnlyAllocator-unlimited/FLA-UL<48,128>/FreeBigBlockInfoAllocator 1.18gb (1274580624b)
7: System: AllocateOnlyAllocator-unlimited/FLA-UL<24592,1>/MemoryMapLevel3Nodes 1.17gb (1261569600b)
8: Column Store Tables: Pool/SerializedObject 384.61mb (403298224b)
9: System: Pool/RowEngine/QueryExecution/SearchAlloc 263.49mb (276298680b)
10: System: Pool/malloc/libhdbns.so 256.12mb (268568384b) (256mb (268435456b) precharged, 1.37kb (1408b) in preparation)
11: Statement Execution & Intermediate Results: Pool/JOB_EXECUTOR/Op 256.03mb (268468256b) (256mb (268435456b) precharged, 1.12kb (1152b) in preparation)
12: Statement Execution & Intermediate Results: StackAllocator 235.75mb (247201792b)
13: Column Store Tables: Pool/Text/AEText/split_positional_index 107.20mb (112415248b)
14: System: Pool/loadhistory 90.02mb (94403184b)
15: Statement Execution & Intermediate Results: Pool/SQLScript/Compile/Preprocessor 79.59mb (83464295b)
16: System: Pool/RowEngine/ContainerManager 46.75mb (49021008b)
17: Monitoring & Statistical Data: Pool/M_DELTA_MERGE_STATISTICS 42.72mb (44800448b)
18: System: Pool/malloc/libhdbrskernel.so 41.22mb (43226754b)
19: System: Pool/ResourceContainer 32.91mb (34511096b)
20: System: Pool/Metadata 31.33mb (32854760b)
[MEMORY_OOM] Information about current out of memory situation: (2023-04-06 17:24:12 847 Local)
OUT OF MEMORY occurred.
Host: <>
Executable: hdbnameserver
PID: 48854
Failed to allocate 47.18kb (48320b).
Allocation failure type: GLOBAL_ALLOCATION_LIMIT (early exit)
Current callstack:
1: 0x00007f2d0f212515 in MemoryManager::PoolAllocator::notifyOOMImpl(unsigned long, unsigned long, bool, ltt::allocation_failure_type, bool)+0x1b1 at mmPoolAllocator.cpp:2276 (libhdbbasis.so)
2: 0x00007f2d0f0c7add in ltt::allocator::notifyOOM(unsigned long, unsigned long, bool, ltt::allocation_failure_type, bool) [clone .constprop.0]+0x59 at memory.cpp:127 (libhdbbasis.so)
3: 0x00007f2d0f2196d0 in MemoryManager::PoolAllocator::oomHandling(unsigned long, unsigned long, bool)+0x60 at mmPoolAllocator.cpp:1220 (libhdbbasis.so)
4: 0x00007f2d0f212a73 in MemoryManager::PoolAllocator::allocateAlignedNoThrowImpl(unsigned long, unsigned long, void const*)+0xa0 at mmPoolAllocator.cpp:1812 (libhdbbasis.so)
5: 0x00007f2d143639f9 in ltt::allocator::allocateAligned(unsigned long, unsigned long)+0x25 at memory.cpp:298 (libhdbbasement.so)
6: 0x00007f2d147ec85c in TrexThreads::ThreadManager::getStatistics(ltt_adp::vector<TrexThreads::ThreadInfo, ltt::integral_constant<bool, true> >*, bool, bool, bool, bool, ltt_adp::map<unsigned long, ltt_adp::vector<unsigned long, ltt::integral_constant<bool, true> >, ltt::less<unsigned long>, ltt::integral_constant<bool, true> >*, bool)+0x1108 at memory.hpp:1212 (libhdbbasement.so)
7: 0x00007f2d147f71bf in Execution::ServiceThreadSamplerThread::createThreadSamples()+0x17b at ServiceThreadSamples.cpp:738 (libhdbbasement.so)
8: 0x00007f2d14802885 in Execution::ServiceThreadSamplerThread::run(void*&)+0x211 at ServiceThreadSamples.cpp:700 (libhdbbasement.so)
9: 0x00007f2d0f40ccf5 in Execution::Thread::staticMainImp(Execution::Thread*)+0x531 at Thread.cpp:522 (libhdbbasis.so)
10: 0x00007f2d0f3fc978 in Execution::pthreadFunctionWrapper(Execution::Thread*)+0x1e4 at ThreadInterposition.cpp:639 (libhdbbasis.so)
11: 0x00007f2d0ea58539 in start_thread+0xd5 (libpthread.so.0)
12: 0x00007f2d0d0daecf in __GI___clone+0x3b (libc.so.6)
Memory consumption information of last failing ProvideMemory, PM-INX=2652335:
IPMM short info:
GLOBAL_ALLOCATION_LIMIT (GAL) = 292.84gb (314438582272b), SHARED_MEMORY = 19.55gb (20996517888b), CODE_SIZE = 2.88gb (3100405760b), OOM_RESERVATION_ALLOCATOR_SIZE = 96.14mb (100810752b)
PID=48854 (hdbnameserver), PAL=483.78gb (519464042496b), AB=158.45gb (170141044736b), UA=0, U=149.78gb (160834945085b), FSL=0b, FMC=0b
PID=49509 (hdbcompileserver), PAL=483.78gb (519464042496b), AB=703.34mb (737509376b), UA=0, U=115.43mb (121042313b), FSL=0b, FMC=0b, Blocked=796.86mb (835575808b)
PID=49512 (hdbpreprocessor), PAL=483.78gb (519464042496b), AB=217.11mb (227659776b), UA=0, U=121.92mb (127845417b), FSL=0b, FMC=0b
PID=49565 (hdbxsengine), PAL=483.78gb (519464042496b), AB=842.01mb (882913280b), UA=0, U=663.59mb (695830176b), FSL=0b, FMC=0b
PID=49562 (hdbindexserver), PAL=483.78gb (519464042496b), AB=109.86gb (117965447168b), UA=0, U=98.99gb (106292438504b), FSL=0b, FMC=0b
PID=50706 (hdbwebdispatcher), PAL=483.78gb (519464042496b), AB=403.62mb (423235584b), UA=0, U=320.03mb (335585128b), FSL=0b, FMC=0b
Total allocated memory= 292.87gb (314474733568b)
Total used memory = 272.41gb (292504610271b)
Sum AB = 290377809920
Sum Used = 268407686623
Heap memory fragmentation: 6
Top "M_HEAP_MEMORY" allocators (component, name, size). Ordered descending by exclusive_size_in_use.
1: Monitoring & Statistical Data: Pool/RowEngine/MonitorView/StatisticsMonitors/M_DEV_BACKUP_CATALOG_LOG 82.97gb (89089765888b) (256mb (268435456b) precharged, 1.48kb (1520b) in preparation)
2: Monitoring & Statistical Data: Pool/Statistics 33.15gb (35604689104b)
3: Monitoring & Statistical Data: Pool/RowEngine/MonitorView/StatisticsMonitors/M_DEV_BACKUP_CATALOG_LOG_FILES 17.09gb (18354745856b) (256mb (268435456b) precharged, 1.21kb (1240b) in preparation)
4: System: Pool/BackupRecoveryAllocator 10.08gb (10831460384b)
5: System: AllocateOnlyAllocator-unlimited/FLA-UL<120,256>/BigBlockInfoAllocator 2.75gb (2957311320b)
Read more...
Environment
SAP HANA Platform Edition 2.0
Product
SAP HANA, platform edition 2.0
Keywords
KBA , HAN-DB , SAP HANA Database , BC-CTS-CCO , Client Copy (For ByD issues select BC-TLM-CP) , HAN-DB-BAC , SAP HANA Backup & Recovery , Problem
About this page
This is a preview of a SAP Knowledge Base Article. Click more to access the full version on SAP for Me (Login required).Search for additional results
Visit SAP Support Portal's SAP Notes and KBA Search.