Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.08.2021 04:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16018708 kB
MemAvailable 31005140 kB
Buffers 20 kB
Cached 15637012 kB
SwapCached 2592 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-15 04:37:07
DTS_END                         2021-08-15 04:37:07.2680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57722
TRN_ID                          242047
ATT_ID                          683
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9039.13 18987 1084695
customer order: creation 176.28 358 21153
customer order: refuse 14.35 310 1722
order to supplier: creation 46.84 1307 5621
order to supplier: removal 3.40 92098 408
invoice (draft): creation 46.28 3357 5554
invoice (draft): removal 51.29 822 6155
invoice accept: apply 43.08 18079 5169
invoice accept: cancel 45.63 788 5475
customer reserve: creation 3875.62 183 465074
customer reserve: removal 4436.55 55 532386
realization accept: apply 85.43 200 10252
realization accept: cancel 83.60 112 10032
payment from customer: creation 59.68 120 7161
payment from customer: removal 5.37 80 644
payment to supplier: creation 50.94 141 6113
payment to supplier: removal 3.88 119 465
service: total inventory turnovers 3.73 3386 447
service: total monetary turnovers 6.19 405 743
service: aggregate perf. data 0.98 12577 117
service: refresh index statistics 0.03 245244 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-08-2021 04:39:28 to 15-08-2021 04:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4577
TEST_TIME 2 2772
TEST_TIME 3 4616
TEST_TIME 4 5396
TEST_TIME 5 5469
TEST_TIME 6 5999
TEST_TIME 7 6625
TEST_TIME 8 6444
TEST_TIME 9 6639
TEST_TIME 10 6628
TEST_TIME 11 6945
TEST_TIME 12 7077
TEST_TIME 13 6991
TEST_TIME 14 7205
TEST_TIME 15 7353
TEST_TIME 16 7712
TEST_TIME 17 8032
TEST_TIME 18 8378
TEST_TIME 19 8195
TEST_TIME 20 8608
TEST_TIME 21 9146
TEST_TIME 22 9468
TEST_TIME 23 9567
TEST_TIME 24 9687
TEST_TIME 25 9547
TEST_TIME 26 9655
TEST_TIME 27 9820
TEST_TIME 28 10009
TEST_TIME 29 10423
TEST_TIME 30 10260
TEST_TIME 31 10243
TEST_TIME 32 10120
TEST_TIME 33 10034
TEST_TIME 34 10199
TEST_TIME 35 10153
TEST_TIME 36 10134
TEST_TIME 37 10205
TEST_TIME 38 10248
TEST_TIME 39 10232
TEST_TIME 40 10101
TEST_TIME 41 9937
TEST_TIME 42 9828
TEST_TIME 43 9928
TEST_TIME 44 9915
TEST_TIME 45 9894
TEST_TIME 46 9831
TEST_TIME 47 9843
TEST_TIME 48 9815
TEST_TIME 49 9805
TEST_TIME 50 9727
TEST_TIME 51 9754
TEST_TIME 52 9648
TEST_TIME 53 9575
TEST_TIME 54 9576
TEST_TIME 55 9507
TEST_TIME 56 9568
TEST_TIME 57 9602
TEST_TIME 58 9605
TEST_TIME 59 9572
TEST_TIME 60 9570
TEST_TIME 61 9630
TEST_TIME 62 9615
TEST_TIME 63 9616
TEST_TIME 64 9587
TEST_TIME 65 9591
TEST_TIME 66 9618
TEST_TIME 67 9546
TEST_TIME 68 9649
TEST_TIME 69 9728
TEST_TIME 70 9610
TEST_TIME 71 9509
TEST_TIME 72 9528
TEST_TIME 73 9470
TEST_TIME 74 9412
TEST_TIME 75 9336
TEST_TIME 76 9316
TEST_TIME 77 9270
TEST_TIME 78 9178
TEST_TIME 79 9295
TEST_TIME 80 9323
TEST_TIME 81 9306
TEST_TIME 82 9301
TEST_TIME 83 9321
TEST_TIME 84 9324
TEST_TIME 85 9363
TEST_TIME 86 9359
TEST_TIME 87 9370
TEST_TIME 88 9374
TEST_TIME 89 9436
TEST_TIME 90 9431
TEST_TIME 91 9464
TEST_TIME 92 9402
TEST_TIME 93 9351
TEST_TIME 94 9312
TEST_TIME 95 9292
TEST_TIME 96 9247
TEST_TIME 97 9210
TEST_TIME 98 9183
TEST_TIME 99 9210
TEST_TIME 100 9176
TEST_TIME 101 9180
TEST_TIME 102 9159
TEST_TIME 103 9157
TEST_TIME 104 9213
TEST_TIME 105 9180
TEST_TIME 106 9209
TEST_TIME 107 9260
TEST_TIME 108 9246
TEST_TIME 109 9285
TEST_TIME 110 9315
TEST_TIME 111 9285
TEST_TIME 112 9294
TEST_TIME 113 9323
TEST_TIME 114 9251
TEST_TIME 115 9218
TEST_TIME 116 9196
TEST_TIME 117 9200
TEST_TIME 118 9146
TEST_TIME 119 9108
TEST_TIME 120 9044

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-08-2021 04:39:32 to 15-08-2021 04:39:35.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1514814 1514814 0 0.00 0 36647 7 0 0
doc_list_biud 1514813 1514813 0 0.00 0 62609 26 0 0
sp_add_invoice_to_stock 5304 5169 135 2.54 35 196561 18080 0 135
sp_cancel_adding_invoice 5475 5475 0 0.00 4 74774 789 0 0
sp_cancel_client_order 1722 1722 0 0.00 5 9206 311 0 0
sp_cancel_customer_reserve 532386 532386 0 0.00 1 62612 55 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 3 6385 80 0 0
sp_cancel_pay_to_supplier 465 465 0 0.00 3 8934 120 0 0
sp_cancel_supplier_invoice 6164 6155 9 0.14 2 36274 823 0 9
sp_cancel_supplier_order 408 408 0 0.00 10 798662 92099 0 0
sp_cancel_write_off 10032 10032 0 0.00 1 17391 112 0 0
sp_client_order 21153 21153 0 0.00 4 20686 358 0 0
sp_customer_reserve 465074 465074 0 0.00 0 144945 184 0 0
sp_fill_shopping_cart 32307 32307 0 0.00 1 14903 39 0 0
sp_fill_shopping_cart_clo_res 465064 465064 0 0.00 0 18247 16 0 0
sp_get_clo_for_invoice 5159 5159 0 0.00 0 34079 341 0 0
sp_kill_cost_storno 16616 16616 0 0.00 0 13284 52 0 0
sp_kill_qstorno_ret_qs2qd 1081367 1081367 0 0.00 0 62604 30 0 0
sp_kill_qty_storno 571589 571589 0 0.00 0 62609 67 0 0
sp_lock_selected_doc 21271 21271 0 0.00 0 8945 5 0 0
sp_make_cost_storno 28685 28685 0 0.00 0 22133 58 0 0
sp_multiply_rows_for_pdistr 28685 28685 0 0.00 0 14980 35 0 0
sp_multiply_rows_for_qdistr 21153 21153 0 0.00 0 20160 175 0 0
sp_pay_from_customer 7161 7161 0 0.00 4 15421 120 0 0
sp_pay_to_supplier 6113 6113 0 0.00 5 15664 142 0 0
sp_payment_common 13274 13274 0 0.00 1 14148 31 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 9195 266 0 0
sp_qd_handle_on_invoice_upd_sts 10634 10634 0 0.00 0 44896 345 0 0
sp_qd_handle_on_reserve_upd_sts 10252 10252 0 0.00 0 21836 73 0 0
sp_reserve_write_off 10252 10252 0 0.00 6 21852 201 0 0
sp_supplier_invoice 5554 5554 0 0.00 8 106082 3357 0 0
sp_supplier_order 5621 5621 0 0.00 5 36103 1307 0 0
srv_aggregate_perf_data 117 117 0 0.00 2467 84792 12578 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2503 135032 46378 0 0
srv_find_qd_qs_mism 1036020 1036020 0 0.00 0 11306 6 0 0
srv_make_invnt_saldo 447 447 0 0.00 49 93679 3386 0 0
srv_make_money_saldo 743 743 0 0.00 1 15755 406 0 0
srv_recalc_idx_stat 4 4 0 0.00 213166 297457 245245 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5169 5169 0 0.00 0 8842 140 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 8883 117 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 4305 18 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 3258 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 3761 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 465 465 0 0.00 0 1681 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 1101 20 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 20386 1275 0 0
t$perf-norm:sp_cancel_write_off 1063 1063 0 0.00 0 1415 6 0 0
t$perf-norm:sp_client_order 21153 21153 0 0.00 0 11834 18 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 1481 6 0 0
t$perf-norm:sp_pay_from_customer 7161 7161 0 0.00 0 7047 11 0 0
t$perf-norm:sp_pay_to_supplier 6113 6113 0 0.00 0 4037 7 0 0
t$perf-norm:sp_reserve_write_off 10252 10252 0 0.00 0 11813 14 0 0
t$perf-norm:sp_supplier_invoice 5554 5554 0 0.00 0 7912 18 0 0
t$perf-norm:sp_supplier_order 5621 5621 0 0.00 0 6670 16 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 144 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 94 3 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 3037 19 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 2628 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10584 10314 270 2.55 0 14277 20 0 270
v_all_customers 38332 38332 0 0.00 0 27 1 0 0
v_all_suppliers 30788 30788 0 0.00 0 34 1 0 0
v_all_wares 42304 42304 0 0.00 0 72 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 2043 9 0 0
v_cancel_client_order 3444 3444 0 0.00 0 3718 6 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 2490 9 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 6158 10 0 0
v_cancel_payment_to_supplier 930 930 0 0.00 0 1714 13 0 0
v_cancel_supplier_invoice 872 854 18 2.06 0 3668 17 0 18
v_cancel_supplier_order 816 816 0 0.00 0 2278 8 0 0
v_cancel_write_off 2126 2126 0 0.00 0 6560 18 0 0
v_max_id_clo_ord 5609 5609 0 0.00 0 381 1 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 7042 19 0 0
v_max_id_ord_sup 5545 5545 0 0.00 0 6316 4 0 0
v_max_non_paid_invoice 1873 1873 0 0.00 0 344 2 0 0
v_max_non_paid_realizn 3883 3883 0 0.00 0 1089 3 0 0
v_min_id_clo_ord 5609 5609 0 0.00 0 8202 7 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 5884 18 0 0
v_min_id_ord_sup 5545 5545 0 0.00 0 6023 10 0 0
v_min_non_paid_invoice 6113 6113 0 0.00 0 14139 21 0 0
v_min_non_paid_realizn 7161 7161 0 0.00 0 14131 25 0 0
v_our_firm 10528 10528 0 0.00 0 20 1 0 0
v_reserve_write_off 20502 20502 0 0.00 0 5773 7 0 0
x_lock_depdocs_on_canc_invoice 11203 11203 0 0.00 0 13824 101 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 18841 398 0 0
x_make_qstorno_1000_1200 5621 5621 0 0.00 2 36090 1237 0 0
x_make_qstorno_1200_2000 5554 5554 0 0.00 3 106070 3201 0 0
x_make_qstorno_2100_3300 465074 465074 0 0.00 0 144942 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-08-2021 04:39:36 to 15-08-2021 04:39:40.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:11 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 29908 8192000000 9742598016 10047156224 823804816 9.137 100 87 174 0 851778000 8252256 19720928
02:09:48 17643 8192000000 9782326912 10091634688 853875408 9.439 100 88 176 0 891766080 17283760 20606912
02:11:14 16490 8192000000 9822634704 10146304000 886225840 9.762 100 83 165 1 927733296 23369616 18137840
02:12:35 9022 8192000000 9878560096 10210951168 923532672 10.131 100 87 174 0 991215056 48530032 19152352
02:13:47 9696 8192000000 9924254128 10267328512 955479616 10.445 100 81 162 0 1047663680 75497056 16687008
02:15:02 4271 8192000000 9975728448 10328244224 991500160 10.796 100 87 170 0 1089213600 79928080 17785360
02:16:08 1518 8192000000 10010818864 10368458752 1020593040 11.078 100 90 177 1 1123157440 84785648 17778752
02:17:12 1734 8192000000 10024297136 10389987328 1045282592 11.315 100 91 183 1 1134259152 70215136 18761424
02:18:15 1290 8192000000 10039487648 10415960064 1068104352 11.534 100 85 170 1 1155587824 69502288 17981184
02:19:16 19188 8192000000 9979288000 10383998976 1063778624 11.493 100 33 66 1 1112700304 42201680 6720000
02:20:36 5700 8192000000 10008468480 10406563840 1077189440 11.621 100 53 103 2 1131155568 42812960 11153168
02:21:42 8108 8192000000 9993513216 10395426816 1088055888 11.724 100 31 63 0 1108006784 13157840 6793056
02:22:52 4098 8192000000 10016118432 10414702592 1090815328 11.750 100 51 99 2 1131670160 29644080 11210752
02:23:56 52915 8192000000 10013452592 10424168448 1094473440 11.785 100 47 95 1 1117520048 12715952 10330656
02:25:53 5646 8192000000 10012153536 10409648128 1085262352 11.698 99 44 89 2 1119452160 24786848 9402960
02:27:00 5378 8192000000 10023146384 10429726720 1079642256 11.644 99 29 58 0 1115280544 29973280 5665008
02:28:10 10976 8192000000 9953651168 10340397056 1024940400 11.120 97 23 45 5 1066041392 36277152 4823840
02:29:28 13350 8192000000 9938935712 10331451392 1007349008 10.950 95 34 68 1 1029479184 13630768 8499408
02:30:44 5899 8192000000 9955320128 10334367744 1002892912 10.907 98 50 97 4 1061476816 48645200 9938704
02:31:50 6268 8192000000 9944827104 10335465472 1033285216 11.200 100 52 103 4 1067006016 21750864 11969936
02:33:00 66389 8192000000 9948354128 10353283072 1025288736 11.123 98 20 39 1 1060514480 30893328 4332416
02:35:07 6626 8192000000 9987797184 10379415552 1057927040 11.437 100 39 78 2 1103861008 37128976 8804992
02:36:14 7440 8192000000 9995932272 10392424448 1071377440 11.565 100 35 70 1 1092679520 13642464 7659616
02:37:28 4960 8192000000 10025284416 10411974656 1093029168 11.771 100 54 109 2 1128750464 24386960 11334336
02:38:39 135032 8192000000 10064396000 10485448704 1107099792 11.905 100 43 86 1 1182748624 66493232 9155600
02:41:58 8991 8192000000 10102735280 10509688832 1127623200 12.099 100 54 106 1 1205903584 66775728 11504656
02:43:12 13045 8192000000 10142352704 10549710848 1132554272 12.145 100 56 110 2 1237112656 92924656 11633728
02:44:31 8889 8192000000 10135909456 10550108160 1149327152 12.303 100 59 116 1 1246647152 84427696 12892304
02:45:42 4692 8192000000 10171634432 10581782528 1154741344 12.354 100 61 122 0 1276662096 107985200 13935552
02:46:51 5792 8192000000 10201794816 10611769344 1163118688 12.432 100 60 118 0 1307980928 131661488 13200752
02:48:00 2502 8192000000 10233545728 10642894848 1162575664 12.427 100 59 118 1 1340548720 164225264 13747792
02:49:07 6268 8192000000 10214316960 10647433216 1167559232 12.474 100 51 101 2 1317855296 138660672 11635392
02:50:18 5432 8192000000 10292541824 10712035328 1166407632 12.463 100 56 110 1 1390746816 211517040 12822144
02:51:27 10234 8192000000 10266662688 10700353536 1155971344 12.366 100 63 126 1 1368735792 198472304 14292144
02:52:47 5849 8192000000 10287344448 10696007680 1165460352 12.454 100 73 144 1 1385512592 203029552 17022688
02:54:02 5754 8192000000 10289706784 10705129472 1171933040 12.515 100 66 132 0 1393499552 205975376 15591136
02:55:12 16148 8192000000 10257370832 10709196800 1153189696 12.339 100 30 60 0 1346480560 185572944 7717920
02:56:53 4491 8192000000 10252155568 10666672128 1111317984 11.945 98 69 138 0 1335935728 209255568 15362176
02:57:58 11695 8192000000 10222825232 10660806656 1115297408 11.983 100 28 56 1 1334029488 211448816 7283264
02:59:13 16467 8192000000 10124519360 10542133248 1093660784 11.777 99 32 65 1 1212157776 110373104 8123888
03:00:35 18575 8192000000 10148912000 10576564224 1101412048 11.851 99 16 32 0 1233339920 127917920 4009952
03:02:19 56127 8192000000 10201565840 10626449408 1114348752 11.974 99 37 74 1 1281122944 158049040 8725152
03:04:34 13865 8192000000 10194223152 10689576960 1131005280 12.131 100 15 30 0 1285226336 150381792 3839264
03:06:36 29232 8192000000 10053079552 10481664000 1115393200 11.983 99 38 70 2 1151553712 29291808 6868704
03:08:15 35418 8192000000 10057872640 10504425472 1130802560 12.129 100 33 67 1 1178436304 41182448 6451296
03:09:53 50930 8192000000 10111103008 10559815680 1121203536 12.038 99 16 32 1 1202870496 78340624 3326336
03:11:44 62658 8192000000 10049788528 10480623616 1107459488 11.908 98 23 45 2 1131672784 20042496 4170800
03:13:49 61117 8192000000 10117570976 10585067520 1136698240 12.184 100 11 22 0 1185926192 46836656 2391296
03:15:51 106367 8192000000 10134106928 10578042880 1119122464 12.019 98 8 16 0 1152581840 32075344 1384032
03:19:33 40550 8192000000 10152307024 10605498368 1132782992 12.148 99 50 99 0 1250359248 106312560 11263696
03:21:18 117287 8192000000 10133356272 10563858432 1128695984 12.109 98 38 76 3 1175523904 38846880 7981040
03:24:16 46582 8192000000 10144662160 10627305472 1160133536 12.405 100 36 68 3 1253485728 85020096 8332096
03:26:43 52010 8192000000 10099674656 10582876160 1115638928 11.986 97 14 28 1 1186577056 67554432 3383696
03:28:39 18346 8192000000 10053339872 10532085760 1124809776 12.072 98 10 21 1 1145214576 18569824 1834976
03:29:58 23011 8192000000 10087219408 10530521088 1138827840 12.205 99 43 86 1 1182107248 34247712 9031696
03:31:34 82916 8192000000 10096144816 10558586880 1099783280 11.836 96 6 12 0 1173015776 71950672 1281824
03:34:41 48265 8192000000 10089168304 10536742912 1117866544 12.007 98 20 40 0 1175847712 54203488 3777680
03:36:36 44132 8192000000 10138068048 10600857600 1140713296 12.222 99 38 76 1 1212391584 63639424 8038864
03:38:26 54848 8192000000 10113682240 10556768256 1085536128 11.700 95 33 66 2 1133978528 41672976 6769424
03:40:25 45686 8192000000 10070506352 10508087296 1072926064 11.580 95 20 40 0 1110688912 33883008 3879840
03:42:11 112164 8192000000 10039048608 10516615168 1046928816 11.331 94 16 32 0 1116568656 66310784 3329056
03:45:03 37263 8192000000 10099296144 10582732800 1096493408 11.804 99 20 40 0 1187501312 86740576 4267328
03:46:47 105352 8192000000 10069110880 10487762944 1078666944 11.635 97 22 44 0 1136116896 53139248 4310704
03:49:33 59221 8192000000 10056459440 10528092160 1102063648 11.857 99 30 60 2 1142815616 34283696 6468272
03:51:34 91496 8192000000 10132334016 10580795392 1107116128 11.905 98 14 28 0 1188864640 78712224 3036288
03:54:11 51145 8192000000 10135131984 10575839232 1081445136 11.661 96 18 36 2 1196691664 111117392 4129136
03:56:14 61975 8192000000 10080842976 10557267968 1106844448 11.903 99 11 20 1 1191493344 82158992 2489904
03:58:22 38182 8192000000 10054033408 10482135040 1096831568 11.808 98 32 64 0 1144904768 41094048 6979152
04:00:02 42317 8192000000 10074755680 10514169856 1078960128 11.638 96 29 58 0 1133438624 48662608 5815888
04:01:46 46573 8192000000 10093126944 10524176384 1116637408 11.995 99 21 42 0 1155496176 34796368 4062400
04:03:33 35951 8192000000 10087756224 10533953536 1121286112 12.039 99 35 70 2 1196150448 67772560 7091776
04:05:31 61268 8192000000 10107214912 10573828096 1143679248 12.250 100 11 22 0 1199234976 53171776 2383952
04:07:37 47033 8192000000 10082816608 10576220160 1141243408 12.227 99 16 32 0 1230749776 86077040 3429328
04:09:32 106514 8192000000 10108118800 10576556032 1140831920 12.223 98 18 36 1 1202353616 57959024 3562672
04:12:27 39856 8192000000 10127264480 10606837760 1147928448 12.290 98 15 30 0 1231006816 79899584 3178784
04:14:31 64982 8192000000 10133302672 10611220480 1153331968 12.341 99 11 22 1 1255588224 99747312 2508944
04:17:08 72662 8192000000 10078529696 10578075648 1143883664 12.252 99 7 14 0 1215947248 70571712 1491872
04:19:23 75613 8192000000 10136068944 10613665792 1154216960 12.349 99 19 38 0 1211591040 53311360 4062720
04:21:40 52825 8192000000 10108638128 10550870016 1089090704 11.734 94 30 60 0 1118271232 23176048 6004480
04:23:35 57187 8192000000 10114317168 10591789056 1106779392 11.902 97 21 42 1 1193448256 82362768 4306096
04:25:38 71553 8192000000 10137252640 10617135104 1115708960 11.986 97 33 66 0 1191605088 69351712 6544416
04:27:54 54544 8192000000 10072463120 10511114240 1081566768 11.662 95 20 40 1 1109632208 24159744 3905696
04:29:51 93556 8192000000 10038519968 10491342848 1061930896 11.475 94 18 36 1 1097524352 31760144 3833312
04:32:32 62888 8192000000 10053757744 10515951616 1092078368 11.762 97 22 44 0 1129536912 33434208 4024336
04:34:45 56760 8192000000 10047038128 10537500672 1110492416 11.937 100 7 15 1 1151897392 40217728 1187248
04:36:46 48691 8192000000 9433270032 9800331264 556293632 6.358 50 9 18 0 597459632 39007664 2158336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-08-2021 04:39:42 to 15-08-2021 04:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-08-2021 04:39:58 to 15-08-2021 04:40:00.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242328
MON$OLDEST_ACTIVE               242329
MON$OLDEST_SNAPSHOT             242329
MON$NEXT_TRANSACTION            242329
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2064096
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             817
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

15.08.21 04:40:01. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-08-15 04:40:03 No severe PSQL-related problems occured
15.08.21 04:40:04. End of report.