Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.08.2022 13: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 15667468 kB
MemAvailable 30941724 kB
Buffers 0 kB
Cached 16068268 kB
SwapCached 2516 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-08-13 13:37:21
DTS_END                         2022-08-13 13:37:21.8240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34364
TRN_ID                          202521
ATT_ID                          625
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: 8519.08 28393 1022289
customer order: creation 150.14 510 18017
customer order: refuse 13.25 502 1590
order to supplier: creation 39.98 1466 4797
order to supplier: removal 3.09 222702 371
invoice (draft): creation 40.46 3695 4855
invoice (draft): removal 49.34 1203 5921
invoice accept: apply 37.59 27652 4511
invoice accept: cancel 44.38 1416 5325
customer reserve: creation 3527.59 256 423311
customer reserve: removal 4350.18 122 522022
realization accept: apply 73.23 340 8788
realization accept: cancel 75.79 202 9095
payment from customer: creation 51.56 200 6187
payment from customer: removal 5.01 192 601
payment to supplier: creation 42.90 215 5148
payment to supplier: removal 2.92 217 350
service: total inventory turnovers 3.36 5127 403
service: total monetary turnovers 7.55 366 906
service: aggregate perf. data 0.73 19040 87
service: refresh index statistics 0.03 282444 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-08-2022 13:39:31 to 13-08-2022 13:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7640
TEST_TIME 2 5869
TEST_TIME 3 4907
TEST_TIME 4 5042
TEST_TIME 5 5521
TEST_TIME 6 5373
TEST_TIME 7 5362
TEST_TIME 8 5797
TEST_TIME 9 6147
TEST_TIME 10 6321
TEST_TIME 11 6256
TEST_TIME 12 6425
TEST_TIME 13 6347
TEST_TIME 14 6504
TEST_TIME 15 6899
TEST_TIME 16 6878
TEST_TIME 17 6962
TEST_TIME 18 6908
TEST_TIME 19 7061
TEST_TIME 20 7154
TEST_TIME 21 7283
TEST_TIME 22 7529
TEST_TIME 23 7478
TEST_TIME 24 7554
TEST_TIME 25 7574
TEST_TIME 26 7541
TEST_TIME 27 7490
TEST_TIME 28 7828
TEST_TIME 29 8523
TEST_TIME 30 8683
TEST_TIME 31 8504
TEST_TIME 32 8827
TEST_TIME 33 9132
TEST_TIME 34 9701
TEST_TIME 35 10212
TEST_TIME 36 10629
TEST_TIME 37 10584
TEST_TIME 38 10413
TEST_TIME 39 10225
TEST_TIME 40 10388
TEST_TIME 41 10259
TEST_TIME 42 10108
TEST_TIME 43 9979
TEST_TIME 44 9968
TEST_TIME 45 9875
TEST_TIME 46 9772
TEST_TIME 47 9728
TEST_TIME 48 9613
TEST_TIME 49 9626
TEST_TIME 50 9494
TEST_TIME 51 9400
TEST_TIME 52 9270
TEST_TIME 53 9332
TEST_TIME 54 9235
TEST_TIME 55 9206
TEST_TIME 56 9115
TEST_TIME 57 9092
TEST_TIME 58 9047
TEST_TIME 59 8986
TEST_TIME 60 9034
TEST_TIME 61 8945
TEST_TIME 62 8854
TEST_TIME 63 8796
TEST_TIME 64 8783
TEST_TIME 65 8812
TEST_TIME 66 8847
TEST_TIME 67 8811
TEST_TIME 68 8823
TEST_TIME 69 8762
TEST_TIME 70 8820
TEST_TIME 71 8769
TEST_TIME 72 8815
TEST_TIME 73 8919
TEST_TIME 74 8892
TEST_TIME 75 8885
TEST_TIME 76 9011
TEST_TIME 77 9020
TEST_TIME 78 8975
TEST_TIME 79 8917
TEST_TIME 80 8854
TEST_TIME 81 8798
TEST_TIME 82 8839
TEST_TIME 83 8801
TEST_TIME 84 8756
TEST_TIME 85 8721
TEST_TIME 86 8680
TEST_TIME 87 8611
TEST_TIME 88 8607
TEST_TIME 89 8636
TEST_TIME 90 8637
TEST_TIME 91 8659
TEST_TIME 92 8640
TEST_TIME 93 8631
TEST_TIME 94 8607
TEST_TIME 95 8651
TEST_TIME 96 8643
TEST_TIME 97 8672
TEST_TIME 98 8660
TEST_TIME 99 8736
TEST_TIME 100 8798
TEST_TIME 101 8883
TEST_TIME 102 8932
TEST_TIME 103 8868
TEST_TIME 104 8857
TEST_TIME 105 8857
TEST_TIME 106 8824
TEST_TIME 107 8780
TEST_TIME 108 8752
TEST_TIME 109 8725
TEST_TIME 110 8689
TEST_TIME 111 8692
TEST_TIME 112 8665
TEST_TIME 113 8669
TEST_TIME 114 8633
TEST_TIME 115 8606
TEST_TIME 116 8606
TEST_TIME 117 8560
TEST_TIME 118 8500
TEST_TIME 119 8533
TEST_TIME 120 8550

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-08-2022 13:39:35 to 13-08-2022 13:39:38.

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 1412285 1412285 0 0.00 0 23751 14 0 0
doc_list_biud 1412266 1412266 0 0.00 0 44883 51 0 0
sp_add_invoice_to_stock 4588 4511 77 1.67 79 242446 27653 0 77
sp_cancel_adding_invoice 5325 5325 0 0.00 6 105144 1416 0 0
sp_cancel_client_order 1590 1590 0 0.00 6 16114 503 0 0
sp_cancel_customer_reserve 522022 522022 0 0.00 1 30220 123 0 0
sp_cancel_pay_from_customer 601 601 0 0.00 4 19469 193 0 0
sp_cancel_pay_to_supplier 350 350 0 0.00 4 13535 217 0 0
sp_cancel_supplier_invoice 5925 5921 4 0.06 2 44889 1204 0 4
sp_cancel_supplier_order 371 371 0 0.00 19 1632504 222702 0 0
sp_cancel_write_off 9095 9095 0 0.00 2 14155 202 0 0
sp_client_order 18017 18017 0 0.00 5 64148 511 0 0
sp_customer_reserve 423311 423311 0 0.00 0 138683 257 0 0
sp_fill_shopping_cart 27659 27659 0 0.00 1 19024 93 0 0
sp_fill_shopping_cart_clo_res 423262 423262 0 0.00 0 22166 24 0 0
sp_get_clo_for_invoice 4456 4456 0 0.00 0 28579 420 0 0
sp_kill_cost_storno 15371 15371 0 0.00 0 10926 98 0 0
sp_kill_qstorno_ret_qs2qd 1059431 1059431 0 0.00 0 44881 53 0 0
sp_kill_qty_storno 557567 557567 0 0.00 0 44883 121 0 0
sp_lock_selected_doc 18311 18311 0 0.00 0 8793 8 0 0
sp_make_cost_storno 24579 24579 0 0.00 0 17420 91 0 0
sp_multiply_rows_for_pdistr 24579 24579 0 0.00 0 9686 45 0 0
sp_multiply_rows_for_qdistr 18017 18017 0 0.00 0 61232 185 0 0
sp_pay_from_customer 6187 6187 0 0.00 5 17446 201 0 0
sp_pay_to_supplier 5148 5148 0 0.00 6 19507 216 0 0
sp_payment_common 11334 11334 0 0.00 2 13086 53 0 0
sp_qd_handle_on_cancel_clo 1590 1590 0 0.00 0 16102 425 0 0
sp_qd_handle_on_invoice_upd_sts 9780 9780 0 0.00 0 29585 649 0 0
sp_qd_handle_on_reserve_upd_sts 8788 8788 0 0.00 0 26444 89 0 0
sp_reserve_write_off 8788 8788 0 0.00 8 27177 341 0 0
sp_supplier_invoice 4855 4855 0 0.00 9 78048 3695 0 0
sp_supplier_order 4797 4797 0 0.00 7 30900 1466 0 0
srv_aggregate_perf_data 87 87 0 0.00 2942 139760 19041 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 742 132892 37710 0 0
srv_find_qd_qs_mism 978798 978798 0 0.00 0 23751 10 0 0
srv_make_invnt_saldo 403 403 0 0.00 48 94867 5127 0 0
srv_make_money_saldo 906 906 0 0.00 1 16835 367 0 0
srv_recalc_idx_stat 4 4 0 0.00 115947 400989 282444 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4516 4516 0 0.00 1 31820 1071 0 0
t$perf-norm:sp_cancel_adding_invoice 324 324 0 0.00 2 12014 574 0 0
t$perf-norm:sp_cancel_client_order 1590 1590 0 0.00 0 15194 52 0 0
t$perf-norm:sp_cancel_customer_reserve 615 615 0 0.00 0 7670 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 601 601 0 0.00 0 2607 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 350 350 0 0.00 0 316 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 340 340 0 0.00 0 1163 50 0 0
t$perf-norm:sp_cancel_supplier_order 371 371 0 0.00 0 112302 9902 0 0
t$perf-norm:sp_cancel_write_off 882 882 0 0.00 0 6854 32 0 0
t$perf-norm:sp_client_order 18017 18017 0 0.00 0 28138 51 0 0
t$perf-norm:sp_customer_reserve 865 865 0 0.00 0 962 17 0 0
t$perf-norm:sp_pay_from_customer 6187 6187 0 0.00 0 31312 29 0 0
t$perf-norm:sp_pay_to_supplier 5148 5148 0 0.00 0 7775 24 0 0
t$perf-norm:sp_reserve_write_off 8788 8788 0 0.00 0 6911 30 0 0
t$perf-norm:sp_supplier_invoice 4855 4855 0 0.00 0 29346 51 0 0
t$perf-norm:sp_supplier_order 4797 4797 0 0.00 0 6251 36 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 10554 145 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 4336 69 0 0
t$perf-norm:srv_make_invnt_saldo 403 403 0 0.00 0 509 22 0 0
t$perf-norm:srv_make_money_saldo 906 906 0 0.00 0 685 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 9 247 80 0 0
v_add_invoice_to_stock 9056 8902 154 1.70 0 19802 30 0 154
v_all_customers 33078 33078 0 0.00 0 205 4 0 0
v_all_suppliers 27194 27194 0 0.00 0 205 5 0 0
v_all_wares 36030 36030 0 0.00 0 85 4 0 0
v_cancel_adding_invoice 648 648 0 0.00 0 518 14 0 0
v_cancel_client_order 3180 3180 0 0.00 0 2650 10 0 0
v_cancel_customer_prepayment 1202 1202 0 0.00 0 19420 34 0 0
v_cancel_customer_reserve 1230 1230 0 0.00 1 605 11 0 0
v_cancel_payment_to_supplier 700 700 0 0.00 0 13468 37 0 0
v_cancel_supplier_invoice 688 680 8 1.16 0 8418 38 0 8
v_cancel_supplier_order 742 742 0 0.00 0 5850 19 0 0
v_cancel_write_off 1764 1764 0 0.00 0 6130 30 0 0
v_max_id_clo_ord 4792 4792 0 0.00 0 653 5 0 0
v_max_id_clo_res 865 865 0 0.00 0 2264 12 0 0
v_max_id_ord_sup 4849 4849 0 0.00 0 5501 7 0 0
v_max_non_paid_invoice 1192 1192 0 0.00 0 91 9 0 0
v_max_non_paid_realizn 3152 3152 0 0.00 0 8896 13 0 0
v_min_id_clo_ord 4792 4792 0 0.00 0 3643 10 0 0
v_min_id_clo_res 865 865 0 0.00 0 2258 13 0 0
v_min_id_ord_sup 4849 4849 0 0.00 0 4264 8 0 0
v_min_non_paid_invoice 5148 5148 0 0.00 0 7373 17 0 0
v_min_non_paid_realizn 6186 6186 0 0.00 0 7446 22 0 0
v_our_firm 9020 9020 0 0.00 0 64 5 0 0
v_reserve_write_off 17572 17572 0 0.00 0 13120 14 0 0
x_lock_depdocs_on_canc_invoice 10906 10906 0 0.00 0 16575 145 0 0
x_lock_depdocs_on_canc_sup_ord 371 371 0 0.00 0 6396 479 0 0
x_make_qstorno_1000_1200 4797 4797 0 0.00 3 30106 1282 0 0
x_make_qstorno_1200_2000 4855 4855 0 0.00 4 77973 3438 0 0
x_make_qstorno_2100_3300 423311 423311 0 0.00 0 138663 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-08-2022 13:39:39 to 13-08-2022 13:39:43.

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
11:07:24 7 8192000000 8801167584 8813142016 6188384 0.075 3 1 2 0 6289584 65424 35776
11:08:25 35849 8192000000 9747802896 10052153344 818748368 9.086 100 85 170 0 846747104 7699248 20299488
11:10:03 17336 8192000000 9776267440 10088849408 852576176 9.426 100 92 184 0 888810144 14356064 21877904
11:11:28 12407 8192000000 9813083232 10139508736 883318688 9.733 100 79 158 0 922215312 21476816 17419808
11:12:44 9251 8192000000 9884668096 10213376000 912614832 10.023 100 88 172 0 990243264 58582784 19045648
11:13:57 5511 8192000000 9915861328 10255306752 936563952 10.259 100 88 174 0 1029318736 73699536 19055248
11:15:04 2753 8192000000 9940314000 10295660544 979617040 10.680 100 83 164 0 1055120048 58495888 17007120
11:16:11 2117 8192000000 9979530096 10341818368 1007118832 10.947 100 82 160 0 1102302272 78892304 16291136
11:17:16 2146 8192000000 9989752240 10353844224 1028895040 11.158 100 92 177 0 1112269712 65614048 17760624
11:18:20 1008 8192000000 10019650672 10394230784 1052034544 11.380 100 97 186 0 1142078336 71057184 18986608
11:19:22 1019 8192000000 10045652160 10424688640 1069919632 11.551 100 96 181 1 1165178528 76654720 18604176
11:20:24 1221 8192000000 10037940928 10422218752 1077739344 11.626 100 90 173 3 1160564768 63802704 19022720
11:21:27 965 8192000000 10053902496 10437529600 1084661264 11.692 100 89 169 0 1173061744 71558288 16842192
11:22:29 5455 8192000000 10046496352 10434953216 1091488976 11.757 100 81 146 1 1173704768 68419712 13796080
11:23:36 1000 8192000000 10067602352 10454798336 1090618416 11.749 100 92 176 2 1186342496 78247456 17476624
11:24:38 992 8192000000 10052654528 10440331264 1097710336 11.816 100 88 175 0 1173382544 58369024 17303184
11:25:40 986 8192000000 10070702976 10460848128 1101890640 11.856 100 91 168 0 1188368176 69235424 17242112
11:26:42 1497 8192000000 10037202160 10432217088 1089487600 11.738 99 87 171 0 1165655440 59546064 16621776
11:27:45 1699 8192000000 10071759984 10467942400 1097401824 11.813 100 79 148 2 1195193616 82019648 15772144
11:28:52 873 8192000000 10034907104 10422779904 1094498256 11.785 100 93 177 1 1158416000 45646528 18271216
11:29:54 1580 8192000000 10054804672 10442780672 1084470992 11.690 100 79 144 3 1172872464 74014688 14386784
11:30:58 1129 8192000000 10013302512 10392461312 1055267664 11.411 100 93 170 2 1133736928 60621408 17847856
11:32:01 4569 8192000000 9998035792 10389946368 1067459728 11.528 100 70 138 1 1122308320 41275456 13573136
11:33:08 1145 8192000000 10030249040 10412572672 1059051792 11.447 100 76 143 0 1153056272 78743200 15261280
11:34:10 1376 8192000000 9978229680 10350993408 1033677424 11.204 100 95 177 1 1101149616 48615168 18857024
11:35:13 1133 8192000000 9968052336 10339819520 1031987088 11.188 100 98 179 3 1090974048 40092912 18894048
11:36:15 814 8192000000 10003681360 10378231808 1038086736 11.246 100 87 171 1 1123431648 67749216 17595696
11:37:17 1068 8192000000 10016328688 10393853952 1058440704 11.442 100 90 172 2 1141137824 65062096 17635024
11:38:23 20502 8192000000 10037616288 10429898752 1071542352 11.567 100 59 118 1 1148692800 65449168 11701280
11:39:58 20181 8192000000 10046056320 10445209600 1099864320 11.836 100 61 118 2 1165458576 52801136 12793120
11:41:30 4444 8192000000 10087252304 10495795200 1104614656 11.881 100 55 107 0 1214957424 98300112 12042656
11:42:38 1212 8192000000 10130078672 10519187456 1115955760 11.989 100 90 169 1 1250195088 116865648 17373680
11:43:45 10375 8192000000 10166183856 10567757824 1118172992 12.010 100 63 110 2 1276573904 146350560 12050352
11:45:04 805 8192000000 10214590128 10608054272 1135335216 12.172 100 94 183 0 1331022320 176355056 19332048
11:46:09 2135 8192000000 10215707600 10635907072 1143635360 12.250 100 98 187 0 1328632192 165078512 19918320
11:47:15 1043 8192000000 10229122688 10625888256 1148859744 12.299 99 93 180 1 1347343520 179102912 19380864
11:48:19 3025 8192000000 10271024224 10675785728 1165453008 12.454 100 82 155 3 1384211808 200811184 17947616
11:49:26 821 8192000000 10291129184 10696552448 1178405760 12.575 100 85 169 5 1402260832 203575856 20279216
11:50:32 1936 8192000000 10330664640 10747998208 1176906864 12.561 100 84 163 1 1443349936 247985104 18457968
11:51:37 843 8192000000 10371415056 10782441472 1186668336 12.652 100 97 185 0 1480711616 272917360 21125920
11:52:41 1027 8192000000 10379344112 10798161920 1186956912 12.655 100 92 184 0 1488932224 280844368 21130944
11:53:45 1393 8192000000 10425575760 10829312000 1196886288 12.747 100 96 185 0 1540008576 320349712 22772576
11:54:49 2087 8192000000 10452315280 10872545280 1206063616 12.833 100 96 192 1 1575717936 346894176 22760144
11:55:59 1819 8192000000 10495317776 10918383616 1206195872 12.834 100 91 174 1 1604631168 378216736 20218560
11:57:05 910 8192000000 10528132800 10958327808 1208348224 12.854 100 99 195 2 1631296432 398741520 24206688
11:58:10 825 8192000000 10530815104 10983575552 1210634112 12.875 100 97 187 1 1636206464 403096288 22476064
11:59:13 1388 8192000000 10565303760 11002781696 1217380000 12.937 100 95 184 1 1670245952 431411248 21454704
12:00:20 748 8192000000 10600193952 11010916352 1218757312 12.950 100 97 191 0 1707013232 465156368 23099552
12:01:25 1254 8192000000 10628086896 11048869888 1207672240 12.848 99 91 179 0 1724802288 495342416 21787632
12:02:27 1074 8192000000 10682911392 11117514752 1205552944 12.828 100 98 196 0 1786406784 556921920 23931920
12:03:35 747 8192000000 10706864480 11130470400 1205047744 12.823 100 94 182 1 1808672064 580800704 22823616
12:04:40 732 8192000000 10686351776 11179327488 1197528640 12.753 100 97 189 1 1787806320 565435328 24842352
12:05:47 4955 8192000000 10623902832 11168755712 1195573376 12.735 100 74 149 1 1732025824 517336736 19115712
12:06:58 7591 8192000000 10633657392 11077300224 1202388544 12.799 100 55 110 1 1736841728 520484016 13969168
12:08:23 6744 8192000000 10642295888 11081650176 1194557264 12.726 100 46 92 1 1740922976 534031232 12334480
12:09:44 22741 8192000000 10556342896 11013124096 1149620432 12.306 98 39 77 0 1675069712 514897312 10551968
12:11:12 35027 8192000000 10345085264 10869370880 1148787776 12.298 99 29 56 0 1482219232 326004240 7427216
12:12:49 41855 8192000000 10154581104 10650759168 1139511472 12.211 99 25 48 0 1267143200 121476800 6154928
12:14:31 73009 8192000000 10133677968 10575093760 1106677904 11.901 96 40 79 0 1205125888 88793904 9654080
12:17:00 21869 8192000000 10093175904 10571915264 1103951024 11.875 99 27 51 0 1187520928 78114832 5455072
12:19:23 69091 8192000000 10076878000 10517479424 1092113344 11.763 97 17 34 1 1138786288 42887712 3785232
12:21:42 132889 8192000000 10068237776 10521747456 1078703456 11.635 96 18 36 0 1133712016 50858416 4150144
12:25:06 97944 8192000000 10053340256 10533285888 1079399456 11.642 96 21 41 0 1152907536 69125936 4382144
12:27:47 116453 8192000000 10106634720 10586214400 1126112128 12.085 100 24 46 0 1196340256 65003200 5224928
12:30:58 53976 8192000000 10099518864 10577121280 1127506064 12.098 100 28 54 0 1210604528 77573792 5524672
12:32:59 35037 8192000000 10079139872 10520252416 1101846736 11.855 97 48 93 1 1146159856 34610416 9702704
12:34:43 33943 8192000000 10091794544 10538401792 1114804496 11.978 98 34 66 1 1183222832 61466320 6952016
12:36:43 130286 8192000000 10071315264 10519429120 1124589664 12.070 99 12 22 0 1186276176 59329952 2356560
12:39:54 101998 8192000000 10123824560 10603085824 1138870944 12.205 99 21 41 0 1229519152 86307376 4340832
12:42:41 92056 8192000000 10055041264 10515447808 1097960080 11.818 96 14 24 0 1139956192 39726704 2269408
12:45:14 56383 8192000000 10067358032 10519126016 1086294112 11.707 95 28 57 0 1130189632 37694224 6201296
12:47:17 71897 8192000000 10119736816 10560929792 1109920096 11.932 98 18 36 1 1198809104 85016512 3872496
12:49:33 95000 8192000000 10100157360 10623127552 1106593216 11.900 97 18 36 0 1213594864 103400928 3600720
12:52:14 55301 8192000000 10189325680 10638749696 1139676592 12.212 99 33 66 0 1246122832 99743456 6702784
12:54:18 52470 8192000000 10083292928 10554834944 1115031056 11.980 98 37 74 0 1159328768 36715504 7582208
12:56:11 5572 8192000000 10122018960 10543816704 1141133216 12.226 100 59 114 1 1215996768 63379888 11483664
12:57:18 65078 8192000000 10126310880 10566623232 1130917568 12.130 100 42 84 1 1234042288 93643616 9481104
12:59:35 20323 8192000000 10068667136 10554847232 1113564208 11.966 99 25 48 0 1162537680 44383440 4590032
13:00:56 72820 8192000000 10052631664 10516946944 1121964928 12.046 99 16 32 0 1171835680 45883136 3987616
13:03:14 51964 8192000000 10092238480 10540068864 1137306720 12.190 100 19 36 0 1194192640 52210128 4675792
13:05:43 97210 8192000000 10075363088 10561122304 1124796160 12.072 99 14 28 0 1193768448 66375648 2596640
13:08:27 47506 8192000000 10085083296 10522750976 1115769104 11.987 99 15 30 1 1152585872 33835872 2980896
13:10:20 20062 8192000000 10125883744 10564100096 1112614592 11.957 98 44 88 0 1202215248 79787104 9813552
13:11:46 10327 8192000000 10166196896 10593271808 1126494592 12.088 100 45 90 0 1263262112 126823792 9943728
13:13:03 33953 8192000000 10187804864 10622074880 1137084688 12.188 100 46 92 0 1280793712 133608048 10100976
13:14:37 36257 8192000000 10208912656 10675740672 1142621200 12.240 100 46 92 1 1318083840 165448816 10013824
13:16:29 49823 8192000000 10204709856 10644942848 1134750960 12.166 100 20 40 0 1313340784 173884400 4705424
13:18:39 97988 8192000000 10096891120 10574311424 1050638080 11.367 93 16 30 0 1136682160 82870336 3173744
13:22:27 54150 8192000000 10040132080 10488487936 1121348784 12.040 100 10 21 0 1153008224 29865024 1794416
13:24:33 56058 8192000000 10057240256 10529705984 1108097904 11.914 99 31 58 1 1160187744 45020320 7069520
13:26:35 119867 8192000000 10071081344 10534264832 1069799712 11.550 95 24 46 0 1129320912 55217520 4303680
13:29:36 57271 8192000000 10072140336 10523926528 1114483136 11.975 99 20 40 0 1165133600 46505872 4144592
13:31:33 2750 8192000000 10053966768 10498359296 1115340256 11.983 99 59 103 1 1156020576 29736960 10943360
13:32:38 38819 8192000000 10100765536 10553810944 1128550896 12.108 100 35 70 0 1211896112 76201344 7143872
13:34:18 96671 8192000000 10158295168 10609758208 1139787040 12.214 100 28 53 0 1214349360 68221344 6340976
13:37:16 39640 8192000000 9424335648 9872781312 525050576 6.023 47 4 8 0 595149184 69409536 689072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-08-2022 13:39:45 to 13-08-2022 13:40:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 77 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-08-2022 13:40:02 to 13-08-2022 13:40:04.

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          202489
MON$OLDEST_ACTIVE               202762
MON$OLDEST_SNAPSHOT             202762
MON$NEXT_TRANSACTION            202762
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                       2067424
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             747
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:220: 
:221: mscs-test-161.budzdorov.ru    Sat Aug 13 13:38:00 2022
:222:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:223: 
:224: 
:225: mscs-test-161.budzdorov.ru    Sat Aug 13 13:38:02 2022
:226:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:227: 
--- end of diff output ---

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

13.08.22 13:40:05. 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-08-13 13:40:07 No severe PSQL-related problems occured
13.08.22 13:40:09. End of report.