Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.05.2022 10: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16559548 kB
MemAvailable 31505928 kB
Buffers 0 kB
Cached 15186548 kB
SwapCached 2604 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.2
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 2022-05-12 10:37:17
DTS_END                         2022-05-12 10:37:17.0160
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/36568
TRN_ID                          213107
ATT_ID                          602
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: 8474.67 24398 1016960
customer order: creation 168.46 483 20215
customer order: refuse 14.33 442 1720
order to supplier: creation 45.09 1469 5411
order to supplier: removal 3.33 166995 399
invoice (draft): creation 44.16 3375 5299
invoice (draft): removal 45.98 1194 5517
invoice accept: apply 40.23 29738 4827
invoice accept: cancel 40.99 1259 4919
customer reserve: creation 3765.96 282 451915
customer reserve: removal 4019.93 106 482392
realization accept: apply 82.94 280 9953
realization accept: cancel 76.73 178 9208
payment from customer: creation 58.49 185 7019
payment from customer: removal 5.52 121 662
payment to supplier: creation 47.24 205 5669
payment to supplier: removal 3.38 145 406
service: total inventory turnovers 3.77 4051 452
service: total monetary turnovers 7.17 407 860
service: aggregate perf. data 0.93 16564 112
service: refresh index statistics 0.04 260490 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-05-2022 10:39:30 to 12-05-2022 10:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7413
TEST_TIME 2 5625
TEST_TIME 3 5665
TEST_TIME 4 5308
TEST_TIME 5 5076
TEST_TIME 6 5125
TEST_TIME 7 5070
TEST_TIME 8 4969
TEST_TIME 9 5351
TEST_TIME 10 5553
TEST_TIME 11 5591
TEST_TIME 12 5894
TEST_TIME 13 6027
TEST_TIME 14 6117
TEST_TIME 15 6259
TEST_TIME 16 6341
TEST_TIME 17 6664
TEST_TIME 18 6673
TEST_TIME 19 6983
TEST_TIME 20 7506
TEST_TIME 21 7518
TEST_TIME 22 7549
TEST_TIME 23 8058
TEST_TIME 24 8335
TEST_TIME 25 8455
TEST_TIME 26 8648
TEST_TIME 27 8766
TEST_TIME 28 9035
TEST_TIME 29 9254
TEST_TIME 30 9473
TEST_TIME 31 9487
TEST_TIME 32 9619
TEST_TIME 33 9874
TEST_TIME 34 10362
TEST_TIME 35 10215
TEST_TIME 36 10118
TEST_TIME 37 10204
TEST_TIME 38 10094
TEST_TIME 39 9994
TEST_TIME 40 9856
TEST_TIME 41 9855
TEST_TIME 42 9701
TEST_TIME 43 9626
TEST_TIME 44 9547
TEST_TIME 45 9438
TEST_TIME 46 9339
TEST_TIME 47 9337
TEST_TIME 48 9247
TEST_TIME 49 9101
TEST_TIME 50 9049
TEST_TIME 51 8966
TEST_TIME 52 8920
TEST_TIME 53 8925
TEST_TIME 54 8838
TEST_TIME 55 8772
TEST_TIME 56 8660
TEST_TIME 57 8623
TEST_TIME 58 8830
TEST_TIME 59 8878
TEST_TIME 60 8811
TEST_TIME 61 8774
TEST_TIME 62 8841
TEST_TIME 63 8918
TEST_TIME 64 8875
TEST_TIME 65 8888
TEST_TIME 66 8937
TEST_TIME 67 8918
TEST_TIME 68 8924
TEST_TIME 69 8882
TEST_TIME 70 8983
TEST_TIME 71 8987
TEST_TIME 72 9042
TEST_TIME 73 8979
TEST_TIME 74 8883
TEST_TIME 75 8862
TEST_TIME 76 8768
TEST_TIME 77 8754
TEST_TIME 78 8755
TEST_TIME 79 8725
TEST_TIME 80 8784
TEST_TIME 81 8722
TEST_TIME 82 8666
TEST_TIME 83 8661
TEST_TIME 84 8679
TEST_TIME 85 8641
TEST_TIME 86 8621
TEST_TIME 87 8635
TEST_TIME 88 8609
TEST_TIME 89 8588
TEST_TIME 90 8616
TEST_TIME 91 8648
TEST_TIME 92 8645
TEST_TIME 93 8639
TEST_TIME 94 8680
TEST_TIME 95 8876
TEST_TIME 96 8820
TEST_TIME 97 8737
TEST_TIME 98 8777
TEST_TIME 99 8739
TEST_TIME 100 8693
TEST_TIME 101 8702
TEST_TIME 102 8676
TEST_TIME 103 8640
TEST_TIME 104 8645
TEST_TIME 105 8691
TEST_TIME 106 8665
TEST_TIME 107 8674
TEST_TIME 108 8664
TEST_TIME 109 8661
TEST_TIME 110 8690
TEST_TIME 111 8694
TEST_TIME 112 8714
TEST_TIME 113 8700
TEST_TIME 114 8692
TEST_TIME 115 8650
TEST_TIME 116 8613
TEST_TIME 117 8611
TEST_TIME 118 8593
TEST_TIME 119 8571
TEST_TIME 120 8504

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-05-2022 10:39:34 to 12-05-2022 10:39:37.

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 1433354 1433354 0 0.00 0 25237 15 0 0
doc_list_biud 1433340 1433340 0 0.00 0 36336 43 0 0
sp_add_invoice_to_stock 4943 4827 116 2.34 23 243203 29739 0 116
sp_cancel_adding_invoice 4919 4919 0 0.00 5 79478 1259 0 0
sp_cancel_client_order 1720 1720 0 0.00 5 10389 443 0 0
sp_cancel_customer_reserve 482392 482392 0 0.00 1 22072 106 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 4 2137 122 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 4 8145 146 0 0
sp_cancel_supplier_invoice 5528 5517 11 0.19 3 31620 1195 0 11
sp_cancel_supplier_order 399 399 0 0.00 21 1182642 166996 0 0
sp_cancel_write_off 9208 9208 0 0.00 2 8030 179 0 0
sp_client_order 20215 20215 0 0.00 6 27928 484 0 0
sp_customer_reserve 451915 451915 0 0.00 1 139318 283 0 0
sp_fill_shopping_cart 30911 30911 0 0.00 1 20902 86 0 0
sp_fill_shopping_cart_clo_res 451884 451884 0 0.00 0 20624 21 0 0
sp_get_clo_for_invoice 4778 4778 0 0.00 0 28426 351 0 0
sp_kill_cost_storno 15195 15195 0 0.00 0 16494 84 0 0
sp_kill_qstorno_ret_qs2qd 979908 979908 0 0.00 0 31613 48 0 0
sp_kill_qty_storno 518884 518884 0 0.00 0 36336 109 0 0
sp_lock_selected_doc 20394 20394 0 0.00 0 9062 6 0 0
sp_make_cost_storno 27416 27416 0 0.00 0 16681 84 0 0
sp_multiply_rows_for_pdistr 27416 27416 0 0.00 0 9442 41 0 0
sp_multiply_rows_for_qdistr 20214 20214 0 0.00 0 23271 168 0 0
sp_pay_from_customer 7019 7019 0 0.00 5 11990 186 0 0
sp_pay_to_supplier 5669 5669 0 0.00 6 15439 206 0 0
sp_payment_common 12684 12684 0 0.00 2 10239 41 0 0
sp_qd_handle_on_cancel_clo 1720 1720 0 0.00 0 10366 363 0 0
sp_qd_handle_on_invoice_upd_sts 9696 9696 0 0.00 0 36335 570 0 0
sp_qd_handle_on_reserve_upd_sts 9952 9952 0 0.00 0 19725 63 0 0
sp_reserve_write_off 9953 9953 0 0.00 8 19884 280 0 0
sp_supplier_invoice 5299 5299 0 0.00 9 83768 3375 0 0
sp_supplier_order 5411 5411 0 0.00 7 32142 1470 0 0
srv_aggregate_perf_data 112 112 0 0.00 2625 114639 16565 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 762 170924 40761 0 0
srv_find_qd_qs_mism 969253 969253 0 0.00 0 25232 12 0 0
srv_make_invnt_saldo 452 452 0 0.00 70 84258 4052 0 0
srv_make_money_saldo 860 860 0 0.00 1 10856 407 0 0
srv_recalc_idx_stat 5 5 0 0.00 77775 409549 260490 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4832 4832 0 0.00 1 31457 960 0 0
t$perf-norm:sp_cancel_adding_invoice 368 368 0 0.00 1 5863 435 0 0
t$perf-norm:sp_cancel_client_order 1720 1720 0 0.00 0 5698 30 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 2519 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 1311 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 1121 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 5291 51 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 101079 7927 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 2973 24 0 0
t$perf-norm:sp_client_order 20215 20215 0 0.00 0 18725 41 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 1225 15 0 0
t$perf-norm:sp_pay_from_customer 7019 7019 0 0.00 0 8939 20 0 0
t$perf-norm:sp_pay_to_supplier 5669 5669 0 0.00 0 4751 21 0 0
t$perf-norm:sp_reserve_write_off 9953 9953 0 0.00 0 9360 26 0 0
t$perf-norm:sp_supplier_invoice 5299 5299 0 0.00 0 7624 34 0 0
t$perf-norm:sp_supplier_order 5411 5411 0 0.00 0 5443 31 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 411 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 533 15 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 2798 29 0 0
t$perf-norm:srv_make_money_saldo 860 860 0 0.00 0 354 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 238 72 0 0
v_add_invoice_to_stock 9778 9546 232 2.37 0 18619 27 0 232
v_all_customers 37318 37318 0 0.00 0 59 4 0 0
v_all_suppliers 29906 29906 0 0.00 0 158 4 0 0
v_all_wares 40424 40424 0 0.00 0 157 4 0 0
v_cancel_adding_invoice 736 736 0 0.00 0 4794 20 0 0
v_cancel_client_order 3440 3440 0 0.00 0 8144 15 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 1616 14 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 2321 14 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1578 15 0 0
v_cancel_supplier_invoice 802 780 22 2.74 0 2165 26 0 22
v_cancel_supplier_order 798 798 0 0.00 0 1646 14 0 0
v_cancel_write_off 1952 1952 0 0.00 0 3241 21 0 0
v_max_id_clo_ord 5410 5410 0 0.00 0 569 5 0 0
v_max_id_clo_res 976 976 0 0.00 0 3726 23 0 0
v_max_id_ord_sup 5289 5289 0 0.00 0 5152 6 0 0
v_max_non_paid_invoice 1413 1413 0 0.00 0 100 7 0 0
v_max_non_paid_realizn 3527 3527 0 0.00 0 565 6 0 0
v_min_id_clo_ord 5410 5410 0 0.00 0 14705 9 0 0
v_min_id_clo_res 976 976 0 0.00 0 12075 25 0 0
v_min_id_ord_sup 5289 5289 0 0.00 0 4942 10 0 0
v_min_non_paid_invoice 5668 5668 0 0.00 0 5522 14 0 0
v_min_non_paid_realizn 7016 7016 0 0.00 0 7508 16 0 0
v_our_firm 10079 10079 0 0.00 0 56 4 0 0
v_reserve_write_off 19902 19902 0 0.00 0 4789 12 0 0
x_lock_depdocs_on_canc_invoice 10046 10046 0 0.00 0 11378 128 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 3221 326 0 0
x_make_qstorno_1000_1200 5411 5411 0 0.00 2 32052 1287 0 0
x_make_qstorno_1200_2000 5299 5299 0 0.00 5 78063 3137 0 0
x_make_qstorno_2100_3300 451914 451914 0 0.00 0 139315 249 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-05-2022 10:39:38 to 12-05-2022 10:39:42.

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
08:07:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:22 36081 8192000000 9732671520 10036654080 803200192 8.929 100 83 166 0 829898976 7394448 19304336
08:10:07 20604 8192000000 9774010800 10084511744 846470656 9.365 100 84 168 2 878807056 12451216 19885184
08:11:32 16515 8192000000 9804563344 10123882496 870515056 9.605 100 84 168 0 911342992 21543872 19284064
08:12:58 9134 8192000000 9875597744 10207932416 919635584 10.092 100 85 170 0 980017456 41866352 18515520
08:14:17 4297 8192000000 9923742592 10266669056 955397504 10.444 100 89 175 0 1042712368 69244864 18070000
08:15:24 2674 8192000000 9974535456 10326900736 991993216 10.801 100 89 175 1 1085725760 75728176 18004368
08:16:31 2011 8192000000 9989056592 10351251456 1014508128 11.019 100 90 167 1 1108096464 76526400 17061936
08:17:35 1174 8192000000 10018403072 10385743872 1040994224 11.274 100 92 173 3 1135394976 75992800 18407952
08:18:38 1279 8192000000 10024396272 10400665600 1052363872 11.383 100 93 186 1 1146521008 75141248 19015888
08:19:43 1695 8192000000 10013886704 10385559552 1055858240 11.417 100 90 163 1 1130928784 59042160 16028384
08:20:47 934 8192000000 10054665024 10441555968 1068372720 11.537 100 98 185 2 1176937584 89905504 18659360
08:21:51 1060 8192000000 10054362736 10434236416 1072134768 11.572 100 98 192 0 1174268832 81534000 20600064
08:22:56 1365 8192000000 10035076800 10417876992 1074922000 11.599 100 91 174 0 1159652960 66124736 18606224
08:23:59 969 8192000000 10050890016 10435735552 1079019856 11.638 100 96 181 0 1174379664 76050704 19309104
08:25:04 1320 8192000000 10038753296 10422648832 1082512640 11.671 100 95 178 0 1159765136 58572912 18679584
08:26:07 3609 8192000000 10032372992 10416300032 1088182912 11.725 100 87 163 2 1153897248 50520752 15193584
08:27:12 1156 8192000000 10051772576 10437201920 1097039904 11.810 100 93 179 0 1175323040 59374960 18908176
08:28:15 863 8192000000 10076444560 10458759168 1093786400 11.779 100 99 195 1 1194181184 81119632 19275152
08:29:20 1222 8192000000 10059974880 10442813440 1091671376 11.759 100 94 180 2 1181906000 72527024 17707600
08:30:24 1129 8192000000 10049023552 10438529024 1087515184 11.719 100 95 182 1 1167135264 59723664 19896416
08:31:26 1179 8192000000 10050508304 10437537792 1086617008 11.710 100 92 177 3 1169305552 63525456 19163088
08:32:28 1218 8192000000 10020889152 10404618240 1073972544 11.590 100 76 150 1 1142056752 53466912 14617296
08:33:31 977 8192000000 10008004240 10387099648 1055330304 11.412 100 87 162 0 1131296112 57881072 18084736
08:34:34 1176 8192000000 10029336896 10408763392 1065449776 11.509 100 97 186 0 1151605120 66770208 19385136
08:35:36 4734 8192000000 9983407120 10357379072 1023570400 11.106 99 86 168 0 1109624720 68275216 17779104
08:36:42 1308 8192000000 10016781168 10390622208 1040222144 11.267 100 88 168 0 1141186992 82333952 18630896
08:37:48 21529 8192000000 10006280448 10390061056 1054373792 11.403 100 70 129 1 1130075360 63163760 12537808
08:39:18 23270 8192000000 10034533616 10423164928 1073210192 11.583 100 66 128 0 1165553440 79794288 12548960
08:40:47 2202 8192000000 10079342384 10465304576 1100091552 11.839 100 88 165 2 1203733152 84417904 19223696
08:41:53 5576 8192000000 10086754720 10475302912 1102351360 11.860 100 70 136 0 1209584816 92391872 14841584
08:43:05 1160 8192000000 10130680240 10517835776 1115994160 11.989 100 77 143 0 1254204960 123102336 15108464
08:44:12 14717 8192000000 10197041552 10600861696 1132054640 12.141 100 73 144 0 1301456528 153782464 15619424
08:45:34 6683 8192000000 10230673744 10621018112 1137052832 12.188 99 79 150 0 1347099776 193674064 16372880
08:46:46 1921 8192000000 10226863056 10625982464 1157594320 12.381 100 92 174 1 1341257024 165233888 18428816
08:47:53 3705 8192000000 10258534800 10655137792 1158673264 12.391 100 80 157 2 1377039488 200373728 17992496
08:49:03 1033 8192000000 10293967072 10697080832 1165943728 12.459 100 95 188 1 1401296112 215259440 20092944
08:50:09 1954 8192000000 10346443104 10743201792 1179844912 12.589 100 91 181 0 1457277024 257481856 19950256
08:51:17 1759 8192000000 10370424544 10771730432 1187278848 12.658 100 93 182 0 1482380000 273221536 21879616
08:52:23 1311 8192000000 10409878976 10811633664 1197182224 12.750 100 97 192 1 1521218336 302290288 21745824
08:53:29 1074 8192000000 10453837456 10878984192 1196693056 12.746 100 93 181 0 1558345792 340265936 21386800
08:54:36 759 8192000000 10459871120 10880208896 1198040704 12.758 100 87 170 2 1565991136 347807040 20143392
08:55:40 972 8192000000 10486644048 10933682176 1198375120 12.761 100 94 179 1 1595107136 375286480 21445536
08:56:43 1499 8192000000 10463346576 10923466752 1200020112 12.777 100 97 187 2 1565446144 344151712 21274320
08:57:47 4071 8192000000 10466744448 10891595776 1188602560 12.670 100 61 122 0 1571391952 367630016 15159376
08:59:10 1392 8192000000 10502851664 10932944896 1188323312 12.668 100 89 174 2 1609241104 397921568 22996224
09:00:23 6799 8192000000 10445590976 10946658304 1194719648 12.727 100 61 120 0 1559388704 349275168 15393888
09:01:36 12399 8192000000 10408483840 10864693248 1190279088 12.686 100 42 84 0 1513989152 313344016 10366048
09:02:59 24087 8192000000 10378330768 10830798848 1149705008 12.307 97 31 62 0 1459482080 301519104 8257968
09:04:25 21275 8192000000 10360005888 10834771968 1170929600 12.506 100 45 90 2 1488223808 306583328 10710880
09:05:53 10721 8192000000 10332662304 10803257344 1165429936 12.454 100 47 93 0 1437081776 260619856 11031984
09:07:11 9464 8192000000 10334509856 10806575104 1156859280 12.374 99 48 96 0 1416790656 248369968 11561408
09:08:34 29191 8192000000 10286261152 10759229440 1142346544 12.238 98 26 52 2 1393587328 244529504 6711280
09:10:13 5760 8192000000 10239361056 10721607680 1147191552 12.283 100 49 91 2 1339073296 181315904 10565840
09:11:22 3121 8192000000 10183294016 10622259200 1160674192 12.410 100 82 157 7 1282631760 106261936 15695632
09:12:27 74040 8192000000 10150554576 10599063552 1134474160 12.164 98 35 69 0 1263466944 121383040 7609744
09:14:42 50934 8192000000 10074414096 10534621184 1071823472 11.569 95 13 26 1 1148096752 73462544 2810736
09:16:35 67990 8192000000 10094390752 10533060608 1130097088 12.122 100 31 63 1 1210939360 73543968 7298304
09:18:44 138494 8192000000 10059763824 10514382848 1099328656 11.831 97 28 56 0 1139898784 35076912 5493216
09:22:03 41925 8192000000 10055378160 10494623744 1121994944 12.046 99 46 91 2 1152312176 21084000 9233232
09:23:49 91100 8192000000 10070641840 10513432576 1139637920 12.212 100 32 63 3 1183607216 36976192 6993104
09:26:23 31940 8192000000 10078268800 10511892480 1137569936 12.193 100 42 74 3 1172912016 26653744 8688336
09:27:56 38698 8192000000 10121630256 10561257472 1125681488 12.081 98 39 74 3 1212248560 77306720 9260352
09:29:44 170923 8192000000 10146196016 10611474432 1062501504 11.480 93 27 54 0 1170025136 101355696 6167936
09:33:44 86659 8192000000 10172072752 10627653632 1126830848 12.091 100 20 40 0 1293616832 162407280 4378704
09:36:15 67705 8192000000 10073083216 10530009088 1094154432 11.782 97 24 48 1 1128828160 29621200 5052528
09:38:23 43966 8192000000 10152412432 10597085184 1142885328 12.243 100 35 70 2 1226451504 76366144 7200032
09:41:00 62586 8192000000 10100736480 10527125504 1094830528 11.789 95 36 68 1 1132804512 30758544 7215440
09:43:08 90246 8192000000 10082706112 10515562496 1101508896 11.852 96 23 46 3 1166581328 60389728 4682704
09:45:39 56582 8192000000 10139127856 10560913408 1122978192 12.055 98 47 85 1 1215249184 82912144 9358848
09:47:41 120975 8192000000 10077100096 10504826880 1096261104 11.802 96 13 26 0 1159819360 60952272 2605984
09:50:44 81938 8192000000 10072966272 10513465344 1117962128 12.008 99 45 88 0 1166981648 38512848 10506672
09:53:07 55118 8192000000 10076361312 10501656576 1127174000 12.095 100 51 100 0 1187454528 49932160 10348368
09:55:04 65534 8192000000 10092008672 10549088256 1114430912 11.974 98 20 40 0 1197999168 79357376 4210880
09:57:18 39805 8192000000 10049190864 10551119872 1104906512 11.884 97 14 28 0 1186449632 77924208 3618912
09:59:01 69369 8192000000 10056773648 10518155264 1107256272 11.906 98 17 34 1 1144543920 33630528 3657120
10:01:11 37777 8192000000 10042380352 10470793216 1083178368 11.678 97 23 35 0 1105089568 19312656 2598544
10:02:49 14419 8192000000 10078114656 10499751936 1100750128 11.845 98 59 116 0 1154903248 42012720 12140400
10:04:06 23997 8192000000 10078371888 10524020736 1110957952 11.941 99 37 72 0 1169880464 51710864 7211648
10:05:45 13346 8192000000 10127614576 10552655872 1135392656 12.172 100 41 78 1 1216133008 72200416 8539936
10:07:01 34572 8192000000 10182492400 10635603968 1144663424 12.259 100 35 68 2 1289737984 136966128 8108432
10:08:42 52081 8192000000 10167209984 10603810816 1096142944 11.801 96 27 54 1 1269207136 166512688 6551504
10:10:39 95651 8192000000 10154900160 10612801536 1094984160 11.790 97 14 28 0 1268298672 170181296 3133216
10:13:19 26170 8192000000 10090726176 10515566592 1129680272 12.118 100 41 82 0 1167610416 29930336 7999808
10:14:46 65618 8192000000 10124030912 10583633920 1125319232 12.077 100 44 89 0 1230765776 96547760 8898784
10:17:22 91534 8192000000 10059390608 10541834240 1095832768 11.798 97 12 24 0 1154910880 56326432 2751680
10:19:54 39535 8192000000 10111687408 10545422336 1114321504 11.973 99 53 106 2 1186105168 60400816 11382848
10:21:47 88397 8192000000 10051245264 10501193728 1033368832 11.201 92 20 38 1 1085325968 48374672 3582464
10:24:16 60724 8192000000 10053984064 10494078976 1096296816 11.802 99 34 67 0 1150229952 46973552 6959584
10:26:21 24270 8192000000 10121731680 10562129920 1117909456 12.007 99 45 90 0 1205541040 78580736 9050848
10:27:46 56162 8192000000 10071819120 10526687232 1100802480 11.845 98 25 50 1 1170892048 64650544 5439024
10:29:44 49554 8192000000 10085019056 10508881920 1111655376 11.948 99 33 62 0 1185227056 67340400 6231280
10:31:36 143796 8192000000 10063952112 10547019776 1091630672 11.758 98 32 64 0 1203050480 104454512 6965296
10:35:01 57290 8192000000 10090475104 10535817216 1110647248 11.939 99 23 46 0 1172862560 57171904 5043408
10:36:59 30636 8192000000 9268496160 9648558080 388796464 4.531 35 2 4 0 411916864 22983472 136928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-05-2022 10:39:44 to 12-05-2022 10:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-05-2022 10:40:01 to 12-05-2022 10:40:03.

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          212863
MON$OLDEST_ACTIVE               213447
MON$OLDEST_SNAPSHOT             213447
MON$NEXT_TRANSACTION            213447
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                       2037344
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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'

12.05.22 10:40:04. 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
2022-05-12 10:40:06 No severe PSQL-related problems occured
12.05.22 10:40:08. End of report.