Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.07.2022 10:37
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 15976168 kB
MemAvailable 31112680 kB
Buffers 0 kB
Cached 15704500 kB
SwapCached 2540 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-07-07 10:37:18
DTS_END                         2022-07-07 10:37:18.2540
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/46764
TRN_ID                          218271
ATT_ID                          597
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: 8972.33 30814 1076679
customer order: creation 157.88 504 18945
customer order: refuse 13.70 413 1644
order to supplier: creation 44.63 1304 5356
order to supplier: removal 3.33 181104 400
invoice (draft): creation 44.16 3065 5299
invoice (draft): removal 51.39 1056 6167
invoice accept: apply 39.99 26484 4799
invoice accept: cancel 45.88 1146 5506
customer reserve: creation 3759.53 252 451143
customer reserve: removal 4527.56 103 543307
realization accept: apply 78.30 257 9396
realization accept: cancel 81.18 175 9741
payment from customer: creation 57.00 172 6840
payment from customer: removal 5.65 116 678
payment to supplier: creation 46.81 190 5617
payment to supplier: removal 3.30 158 396
service: total inventory turnovers 4.05 3476 486
service: total monetary turnovers 7.09 425 851
service: aggregate perf. data 0.86 14077 103
service: refresh index statistics 0.04 381806 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-07-2022 10:38:17 to 07-07-2022 10:38:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6613
TEST_TIME 2 5220
TEST_TIME 3 4821
TEST_TIME 4 4551
TEST_TIME 5 5226
TEST_TIME 6 5326
TEST_TIME 7 5219
TEST_TIME 8 5552
TEST_TIME 9 5769
TEST_TIME 10 5856
TEST_TIME 11 6234
TEST_TIME 12 6410
TEST_TIME 13 6722
TEST_TIME 14 6631
TEST_TIME 15 6510
TEST_TIME 16 6440
TEST_TIME 17 6435
TEST_TIME 18 6811
TEST_TIME 19 6916
TEST_TIME 20 6879
TEST_TIME 21 7102
TEST_TIME 22 7319
TEST_TIME 23 7331
TEST_TIME 24 7467
TEST_TIME 25 7609
TEST_TIME 26 8002
TEST_TIME 27 8106
TEST_TIME 28 8606
TEST_TIME 29 9050
TEST_TIME 30 9239
TEST_TIME 31 9897
TEST_TIME 32 10598
TEST_TIME 33 10901
TEST_TIME 34 10785
TEST_TIME 35 10980
TEST_TIME 36 10921
TEST_TIME 37 10633
TEST_TIME 38 10404
TEST_TIME 39 10217
TEST_TIME 40 10135
TEST_TIME 41 10068
TEST_TIME 42 9895
TEST_TIME 43 9930
TEST_TIME 44 9765
TEST_TIME 45 9661
TEST_TIME 46 9861
TEST_TIME 47 9694
TEST_TIME 48 9559
TEST_TIME 49 9614
TEST_TIME 50 9560
TEST_TIME 51 9484
TEST_TIME 52 9478
TEST_TIME 53 9463
TEST_TIME 54 9536
TEST_TIME 55 9504
TEST_TIME 56 9426
TEST_TIME 57 9390
TEST_TIME 58 9411
TEST_TIME 59 9462
TEST_TIME 60 9713
TEST_TIME 61 9608
TEST_TIME 62 9683
TEST_TIME 63 9599
TEST_TIME 64 9480
TEST_TIME 65 9457
TEST_TIME 66 9392
TEST_TIME 67 9347
TEST_TIME 68 9268
TEST_TIME 69 9266
TEST_TIME 70 9235
TEST_TIME 71 9135
TEST_TIME 72 9096
TEST_TIME 73 9062
TEST_TIME 74 9117
TEST_TIME 75 9083
TEST_TIME 76 9047
TEST_TIME 77 9063
TEST_TIME 78 9087
TEST_TIME 79 9082
TEST_TIME 80 9086
TEST_TIME 81 9130
TEST_TIME 82 9177
TEST_TIME 83 9188
TEST_TIME 84 9236
TEST_TIME 85 9225
TEST_TIME 86 9179
TEST_TIME 87 9260
TEST_TIME 88 9170
TEST_TIME 89 9104
TEST_TIME 90 9100
TEST_TIME 91 9036
TEST_TIME 92 9020
TEST_TIME 93 8995
TEST_TIME 94 8972
TEST_TIME 95 8998
TEST_TIME 96 8962
TEST_TIME 97 8955
TEST_TIME 98 8999
TEST_TIME 99 8977
TEST_TIME 100 8984
TEST_TIME 101 9033
TEST_TIME 102 9040
TEST_TIME 103 9029
TEST_TIME 104 9013
TEST_TIME 105 8950
TEST_TIME 106 8935
TEST_TIME 107 8908
TEST_TIME 108 8887
TEST_TIME 109 8902
TEST_TIME 110 8871
TEST_TIME 111 8877
TEST_TIME 112 8854
TEST_TIME 113 8881
TEST_TIME 114 8870
TEST_TIME 115 8916
TEST_TIME 116 8923
TEST_TIME 117 8911
TEST_TIME 118 8963
TEST_TIME 119 8979
TEST_TIME 120 8991

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-07-2022 10:38:20 to 07-07-2022 10:38:23.

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 1491726 1491726 0 0.00 0 18668 13 0 0
doc_list_biud 1491722 1491722 0 0.00 0 48861 43 0 0
sp_add_invoice_to_stock 4898 4799 99 2.02 42 249950 26484 0 99
sp_cancel_adding_invoice 5506 5506 0 0.00 7 78344 1147 0 0
sp_cancel_client_order 1644 1644 0 0.00 6 12794 414 0 0
sp_cancel_customer_reserve 543307 543307 0 0.00 1 37944 104 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 4232 116 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 4 22389 159 0 0
sp_cancel_supplier_invoice 6175 6167 8 0.12 3 48951 1056 0 8
sp_cancel_supplier_order 400 400 0 0.00 18 1483722 181104 0 0
sp_cancel_write_off 9741 9741 0 0.00 2 25800 175 0 0
sp_client_order 18945 18945 0 0.00 5 41980 504 0 0
sp_customer_reserve 451143 451143 0 0.00 0 62662 253 0 0
sp_fill_shopping_cart 29593 29593 0 0.00 1 21290 76 0 0
sp_fill_shopping_cart_clo_res 451118 451118 0 0.00 0 24528 21 0 0
sp_get_clo_for_invoice 4760 4760 0 0.00 0 32906 312 0 0
sp_kill_cost_storno 16321 16321 0 0.00 0 8495 77 0 0
sp_kill_qstorno_ret_qs2qd 1102922 1102922 0 0.00 0 48851 45 0 0
sp_kill_qty_storno 580921 580921 0 0.00 0 48859 103 0 0
sp_lock_selected_doc 19611 19611 0 0.00 0 7071 7 0 0
sp_make_cost_storno 26613 26613 0 0.00 0 18659 74 0 0
sp_multiply_rows_for_pdistr 26613 26613 0 0.00 0 13722 36 0 0
sp_multiply_rows_for_qdistr 18945 18945 0 0.00 0 25068 192 0 0
sp_pay_from_customer 6840 6840 0 0.00 5 18492 172 0 0
sp_pay_to_supplier 5617 5617 0 0.00 6 35635 190 0 0
sp_payment_common 12457 12457 0 0.00 2 10332 40 0 0
sp_qd_handle_on_cancel_clo 1644 1644 0 0.00 0 12770 340 0 0
sp_qd_handle_on_invoice_upd_sts 10266 10266 0 0.00 0 20028 500 0 0
sp_qd_handle_on_reserve_upd_sts 9396 9396 0 0.00 0 11634 55 0 0
sp_reserve_write_off 9396 9396 0 0.00 8 26174 257 0 0
sp_supplier_invoice 5299 5299 0 0.00 9 103440 3066 0 0
sp_supplier_order 5356 5356 0 0.00 7 27194 1305 0 0
srv_aggregate_perf_data 103 103 0 0.00 2257 143934 14077 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 660 122367 37959 0 0
srv_find_qd_qs_mism 1029569 1029569 0 0.00 0 13056 11 0 0
srv_make_invnt_saldo 486 486 0 0.00 65 84230 3477 0 0
srv_make_money_saldo 851 851 0 0.00 1 26715 425 0 0
srv_recalc_idx_stat 5 5 0 0.00 184593 498596 381806 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4800 4800 0 0.00 0 26409 767 0 0
t$perf-norm:sp_cancel_adding_invoice 365 365 0 0.00 2 19757 503 0 0
t$perf-norm:sp_cancel_client_order 1644 1644 0 0.00 0 2136 25 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 1258 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 397 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 462 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 3503 41 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 95610 6903 0 0
t$perf-norm:sp_cancel_write_off 933 933 0 0.00 0 843 16 0 0
t$perf-norm:sp_client_order 18946 18946 0 0.00 0 21862 32 0 0
t$perf-norm:sp_customer_reserve 882 882 0 0.00 0 1495 15 0 0
t$perf-norm:sp_pay_from_customer 6840 6840 0 0.00 0 14815 19 0 0
t$perf-norm:sp_pay_to_supplier 5617 5617 0 0.00 0 3908 17 0 0
t$perf-norm:sp_reserve_write_off 9396 9396 0 0.00 0 20152 24 0 0
t$perf-norm:sp_supplier_invoice 5299 5299 0 0.00 0 3511 28 0 0
t$perf-norm:sp_supplier_order 5356 5356 0 0.00 0 6582 27 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 7175 87 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 163 9 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 504 9 0 0
t$perf-norm:srv_make_money_saldo 851 851 0 0.00 0 3284 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 162 78 0 0
v_add_invoice_to_stock 9714 9516 198 2.03 0 20187 23 0 198
v_all_customers 34852 34852 0 0.00 0 266 3 0 0
v_all_suppliers 29894 29894 0 0.00 0 261 4 0 0
v_all_wares 37886 37886 0 0.00 0 65 3 0 0
v_cancel_adding_invoice 730 730 0 0.00 0 1923 15 0 0
v_cancel_client_order 3288 3288 0 0.00 0 3204 11 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 2214 14 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 1 1686 12 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 22214 39 0 0
v_cancel_supplier_invoice 800 784 16 2.00 0 331 21 0 16
v_cancel_supplier_order 800 800 0 0.00 0 497 10 0 0
v_cancel_write_off 1866 1866 0 0.00 0 25546 42 0 0
v_max_id_clo_ord 5356 5356 0 0.00 0 3272 5 0 0
v_max_id_clo_res 882 882 0 0.00 0 10502 36 0 0
v_max_id_ord_sup 5294 5294 0 0.00 0 3477 7 0 0
v_max_non_paid_invoice 1320 1320 0 0.00 0 157 8 0 0
v_max_non_paid_realizn 3667 3667 0 0.00 0 521 7 0 0
v_min_id_clo_ord 5356 5356 0 0.00 0 1966 7 0 0
v_min_id_clo_res 882 882 0 0.00 0 8689 24 0 0
v_min_id_ord_sup 5294 5294 0 0.00 0 4475 7 0 0
v_min_non_paid_invoice 5617 5617 0 0.00 0 6458 17 0 0
v_min_non_paid_realizn 6840 6840 0 0.00 0 10321 15 0 0
v_our_firm 9380 9380 0 0.00 0 61 4 0 0
v_reserve_write_off 18792 18792 0 0.00 0 25937 14 0 0
x_lock_depdocs_on_canc_invoice 11281 11281 0 0.00 0 11956 129 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 15345 405 0 0
x_make_qstorno_1000_1200 5356 5356 0 0.00 2 27184 1157 0 0
x_make_qstorno_1200_2000 5299 5299 0 0.00 4 103427 2845 0 0
x_make_qstorno_2100_3300 451143 451143 0 0.00 0 60771 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-07-2022 10:38:24 to 07-07-2022 10:38:28.

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:22 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 22007 8192000000 9724558832 10019713024 799746800 8.894 100 86 172 0 827892848 7172736 20973312
08:09:52 14876 8192000000 9757838672 10066026496 835212016 9.252 100 87 174 0 867343776 11371344 20760416
08:11:17 12076 8192000000 9794700592 10111086592 862563664 9.526 100 86 172 0 901971600 18993120 20414816
08:12:40 10616 8192000000 9856454320 10187730944 909332720 9.991 100 74 148 0 962469344 36968000 16168624
08:13:56 4617 8192000000 9909892096 10254032896 959750944 10.487 100 81 156 1 1026253024 49740240 16761840
08:15:04 1821 8192000000 9956740944 10306600960 992274544 10.804 100 93 179 1 1073363296 63235664 17853088
08:16:09 1528 8192000000 10000416224 10359197696 1018746832 11.060 100 91 182 1 1116261056 78838352 18675872
08:17:12 1142 8192000000 10026882480 10401234944 1043853824 11.302 100 94 177 1 1155419648 93587824 17978000
08:18:14 891 8192000000 10031811728 10405191680 1060406464 11.460 100 97 183 2 1152959968 74079744 18473760
08:19:17 992 8192000000 10050107600 10426073088 1068622128 11.539 100 98 186 1 1169659088 81767808 19269152
08:20:19 1056 8192000000 10027079280 10401681408 1076261360 11.612 100 99 193 1 1143755872 48086608 19407904
08:21:22 1123 8192000000 10066444048 10448363520 1081100384 11.658 100 96 187 1 1185554048 85422144 19031520
08:22:26 1119 8192000000 10040878944 10422882304 1084995440 11.695 100 92 171 1 1156805472 55814832 15995200
08:23:29 1150 8192000000 10063653776 10450956288 1087621168 11.720 100 93 182 0 1184607248 78244416 18741664
08:24:31 1320 8192000000 10067505536 10457600000 1094111520 11.782 100 93 178 2 1189165344 75759104 19294720
08:25:33 1137 8192000000 10034388960 10423222272 1096123216 11.801 100 86 158 1 1157880000 44877104 16879680
08:26:35 957 8192000000 10053546720 10441994240 1094299712 11.784 100 94 171 4 1174531056 63671328 16560016
08:27:37 1262 8192000000 10054752176 10443878400 1093260048 11.774 100 97 179 0 1182222320 70534912 18427360
08:28:40 1141 8192000000 10067181568 10458107904 1096548704 11.805 100 97 182 0 1187463680 73174336 17740640
08:29:41 1157 8192000000 10029983104 10411630592 1071942208 11.571 100 95 173 1 1152273904 61852176 18479520
08:30:43 1112 8192000000 10071763872 10457395200 1078179040 11.630 100 99 191 2 1191748368 94285872 19283456
08:31:45 1090 8192000000 10040797056 10419875840 1066778480 11.521 100 95 187 2 1161132496 73749200 20604816
08:32:47 1212 8192000000 10033315744 10410110976 1062009808 11.476 100 92 178 1 1156816016 76555600 18250608
08:33:48 8878 8192000000 9998857936 10397945856 1052168192 11.381 100 58 112 2 1126301904 62453072 11680640
08:34:59 1063 8192000000 9985370896 10360406016 1041276448 11.277 100 86 160 0 1110157184 52085232 16795504
08:36:01 782 8192000000 10011610096 10390790144 1051921136 11.379 100 86 162 0 1135853616 66888256 17044224
08:37:02 1003 8192000000 10017920672 10398093312 1047212800 11.334 100 90 164 1 1142815776 77290288 18312688
08:38:10 2539 8192000000 9989556368 10371313664 1059009408 11.447 100 67 122 2 1109547856 37587216 12951232
08:39:20 13700 8192000000 10017003648 10405634048 1076895664 11.618 100 61 117 0 1148969904 60005664 12068576
08:40:43 14453 8192000000 10073446688 10469523456 1099046656 11.829 100 66 131 0 1186812656 74133536 13632464
08:42:01 1176 8192000000 10142695136 10524553216 1111905360 11.950 100 95 184 1 1260777504 128897488 19974656
08:43:09 5435 8192000000 10156333008 10561851392 1123610720 12.061 100 61 111 2 1275871280 139453008 12807552
08:44:20 2070 8192000000 10183899488 10583117824 1132025760 12.140 100 88 163 2 1300980208 150716880 18237568
08:45:25 2599 8192000000 10211623120 10609283072 1136149936 12.179 100 92 167 2 1331885824 176545440 19190448
08:46:31 4780 8192000000 10269179984 10663518208 1156101360 12.367 100 78 145 2 1377894448 205972496 15820592
08:47:41 1111 8192000000 10283492096 10681597952 1172024464 12.516 100 92 172 3 1397694896 206396016 19274416
08:48:44 1556 8192000000 10290649712 10693177344 1180894400 12.599 100 89 175 0 1407019680 206099888 20025392
08:49:50 724 8192000000 10314639824 10744377344 1191369904 12.696 100 94 181 0 1424278144 211404992 21503248
08:50:55 1487 8192000000 10352331120 10756460544 1189809232 12.682 100 91 181 0 1464678544 253962432 20906880
08:51:58 2387 8192000000 10405004672 10806239232 1200007216 12.776 100 89 172 1 1512751200 293218384 19525600
08:53:04 641 8192000000 10459491568 10858156032 1195169856 12.731 100 95 186 0 1566489808 348919536 22400416
08:54:08 687 8192000000 10522654272 10950512640 1209471392 12.864 100 97 188 0 1630715808 398276032 22968384
08:55:13 998 8192000000 10538192112 10978795520 1217202768 12.936 100 94 184 0 1646822464 407135936 22483760
08:56:17 2241 8192000000 10560520416 10990710784 1217109456 12.935 100 89 172 2 1665441904 427681936 20650512
08:57:24 718 8192000000 10620313008 11034759168 1224318432 13.002 100 95 188 2 1726755872 478157280 24280160
08:58:26 973 8192000000 10616330912 11063943168 1223374576 12.993 100 100 195 1 1719848496 473343680 23130240
08:59:30 3253 8192000000 10647369072 11078762496 1228640336 13.042 100 81 159 1 1748527168 500465424 19421408
09:00:50 3443 8192000000 10680425392 11120832512 1227617088 13.032 100 70 138 1 1785705744 539837376 18251280
09:02:10 4237 8192000000 10674405136 11129081856 1214722976 12.913 100 68 132 0 1780300496 547575440 18002080
09:03:20 5465 8192000000 10630816960 11125059584 1205080480 12.823 100 62 124 2 1735569648 514288816 16200352
09:04:29 5779 8192000000 10621422320 11093819392 1199847488 12.775 100 60 120 1 1734631312 519823648 14960176
09:05:48 16318 8192000000 10514038624 11017674752 1189684496 12.680 100 37 72 0 1652906752 453326944 9895312
09:07:06 21071 8192000000 10452146544 10942349312 1169974512 12.497 100 33 66 0 1566505056 388000944 8529600
09:08:28 19877 8192000000 10351213024 10869628928 1164101728 12.442 100 23 46 0 1460646672 290753184 5791760
09:09:49 35706 8192000000 10188425632 10694152192 1150428928 12.314 100 14 28 0 1267625520 113947056 3249536
09:11:25 38851 8192000000 10147238640 10596024320 1139291888 12.209 98 23 46 1 1222884992 78278688 5314416
09:13:14 122367 8192000000 10069820112 10528432128 1118246400 12.010 98 29 56 1 1145173776 20401440 6525936
09:16:32 42705 8192000000 10133058592 10568110080 1141891248 12.233 100 60 109 3 1225840608 72318736 11630624
09:18:23 57418 8192000000 10125729888 10591993856 1114182928 11.972 98 12 22 0 1212153744 95103056 2867760
09:20:28 72295 8192000000 10126701104 10609917952 1125496272 12.079 99 13 26 1 1208405184 80230400 2678512
09:22:40 22885 8192000000 10151800160 10620919808 1134516720 12.164 100 13 26 0 1255219184 117500768 3201696
09:24:04 83760 8192000000 10098951152 10548445184 1102717824 11.863 98 10 20 0 1171685344 66916896 2050624
09:27:28 48896 8192000000 10054110656 10535088128 1104277216 11.878 98 11 23 0 1139010192 32630208 2102768
09:29:27 102670 8192000000 10091098640 10582487040 1092385120 11.765 97 14 28 0 1157612656 62513360 2714176
09:32:11 34314 8192000000 10076587040 10528890880 1138109856 12.198 100 33 67 0 1182725728 36914272 7701600
09:33:55 22213 8192000000 10161103376 10598535168 1136698608 12.184 99 41 78 0 1231090112 85361552 9029952
09:35:21 43597 8192000000 10204654688 10657939456 1143537488 12.249 100 34 68 0 1299799664 148735392 7526784
09:37:17 67390 8192000000 10098924944 10580123648 1108647248 11.920 97 17 34 0 1180900688 69006704 3246736
09:39:25 21400 8192000000 10104476032 10551799808 1128135456 12.104 99 45 90 0 1187485680 49496864 9853360
09:40:59 101340 8192000000 10075411536 10550108160 1115836320 11.988 98 37 74 0 1178394208 55453184 7104704
09:43:41 31538 8192000000 10078165104 10547900416 1117609072 12.004 98 27 53 0 1157903856 34713408 5581376
09:45:21 82615 8192000000 10104786128 10601263104 1116899200 11.998 98 15 31 0 1198294864 77495088 3900576
09:47:44 80305 8192000000 10096570960 10568597504 1099659776 11.834 96 12 24 0 1146161120 43968976 2532368
09:50:04 112095 8192000000 10053801840 10554572800 1078378432 11.632 96 12 24 0 1122697072 42002192 2316448
09:53:06 20578 8192000000 10095869792 10529021952 1116469488 11.994 98 54 103 1 1178371408 51813200 10088720
09:54:31 37429 8192000000 10099871776 10546352128 1115756336 11.987 98 37 75 0 1186003968 63363232 6884400
09:56:11 64554 8192000000 10092292656 10573037568 1111907920 11.950 97 31 62 0 1173773584 55669824 6195840
09:58:22 96427 8192000000 10100880144 10573684736 1094790464 11.788 96 17 34 1 1166775600 68423232 3561904
10:00:58 93468 8192000000 10106238000 10572419072 1138299872 12.200 99 20 40 0 1248897392 105989040 4608480
10:03:32 37103 8192000000 10133854240 10583564288 1123990048 12.065 99 52 104 0 1224873376 89326544 11556784
10:05:12 91378 8192000000 10068812688 10502217728 1111509920 11.947 98 39 76 2 1155011008 35972720 7528368
10:07:45 52224 8192000000 10056536464 10519674880 1116442224 11.993 99 3 6 0 1150581040 33649840 488976
10:09:37 63839 8192000000 10053718256 10524573696 1106942992 11.903 98 4 8 0 1145799216 38242480 613744
10:11:42 54471 8192000000 10049876752 10509758464 1086047136 11.705 96 34 65 2 1128855584 35879728 6928720
10:13:40 26374 8192000000 10085799008 10514817024 1120516864 12.032 100 55 106 1 1173222896 42205840 10500192
10:15:12 42273 8192000000 10074556608 10524868608 1121521888 12.041 99 24 48 0 1181748416 55202272 5024256
10:16:54 25135 8192000000 10130073440 10551771136 1094541520 11.786 97 53 106 0 1183742720 78671776 10529424
10:18:21 49885 8192000000 10063833104 10505486336 1091808016 11.760 98 35 70 1 1146871936 48088864 6975056
10:20:11 25376 8192000000 10090520672 10527043584 1116309456 11.992 100 33 66 0 1170114880 47258608 6546816
10:21:39 116692 8192000000 10066655920 10511855616 1111487840 11.947 99 43 86 2 1161053712 40923792 8642080
10:24:38 87253 8192000000 10081612528 10561822720 1140446624 12.220 100 9 18 0 1202978144 60883808 1647712
10:27:05 47454 8192000000 10080785440 10525634560 1114798288 11.978 98 19 35 1 1161830400 43052976 3979136
10:28:59 37614 8192000000 10121372384 10566729728 1133302640 12.152 98 29 58 0 1209005888 69405392 6297856
10:30:42 46906 8192000000 10166477040 10620547072 1141131872 12.226 99 24 48 0 1252287072 105466560 5688640
10:32:30 72365 8192000000 10106635184 10557874176 1102736832 11.864 97 18 36 0 1188691568 81638224 4316512
10:34:43 33888 8192000000 10151935568 10601627648 1141886304 12.233 100 30 60 0 1243029040 94386752 6755984
10:36:21 70073 8192000000 9353412736 9723404288 453424816 5.244 40 7 14 0 526499824 71462576 1612432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-07-2022 10:38:30 to 07-07-2022 10:38:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-07-2022 10:38:47 to 07-07-2022 10:38:49.

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          218552
MON$OLDEST_ACTIVE               218553
MON$OLDEST_SNAPSHOT             218553
MON$NEXT_TRANSACTION            218553
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                       2049888
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 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 ---
:70: 
:71: mscs-test-161.budzdorov.ru    Thu Jul  7 10:37:45 2022
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:73: 
--- end of diff output ---

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

07.07.22 10:38:50. 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-07-07 10:38:53 No severe PSQL-related problems occured
07.07.22 10:38:54. End of report.