Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.05.2022 10:38
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 16337944 kB
MemAvailable 31560516 kB
Buffers 0 kB
Cached 15394496 kB
SwapCached 3200 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-01 10:37:16
DTS_END                         2022-05-01 10:37:16.8480
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/40540
TRN_ID                          217134
ATT_ID                          639
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: 8881.03 27651 1065723
customer order: creation 161.57 482 19389
customer order: refuse 13.96 414 1675
order to supplier: creation 44.57 1394 5348
order to supplier: removal 3.28 169637 393
invoice (draft): creation 43.42 3338 5210
invoice (draft): removal 50.30 957 6036
invoice accept: apply 40.06 28839 4807
invoice accept: cancel 45.58 1133 5470
customer reserve: creation 3754.63 276 450555
customer reserve: removal 4441.59 98 532991
realization accept: apply 79.27 285 9512
realization accept: cancel 80.40 176 9648
payment from customer: creation 55.70 163 6684
payment from customer: removal 5.55 122 666
payment to supplier: creation 45.57 200 5468
payment to supplier: removal 3.27 110 392
service: total inventory turnovers 3.85 3905 462
service: total monetary turnovers 7.45 367 894
service: aggregate perf. data 0.99 16162 119
service: refresh index statistics 0.03 324955 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-05-2022 10:38:44 to 01-05-2022 10:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8609
TEST_TIME 2 6245
TEST_TIME 3 6891
TEST_TIME 4 6496
TEST_TIME 5 6732
TEST_TIME 6 7053
TEST_TIME 7 6629
TEST_TIME 8 6869
TEST_TIME 9 6941
TEST_TIME 10 6893
TEST_TIME 11 7182
TEST_TIME 12 7524
TEST_TIME 13 7547
TEST_TIME 14 7460
TEST_TIME 15 7514
TEST_TIME 16 7452
TEST_TIME 17 7527
TEST_TIME 18 7516
TEST_TIME 19 7568
TEST_TIME 20 7721
TEST_TIME 21 7832
TEST_TIME 22 8181
TEST_TIME 23 8421
TEST_TIME 24 8368
TEST_TIME 25 8728
TEST_TIME 26 9038
TEST_TIME 27 9261
TEST_TIME 28 9328
TEST_TIME 29 9900
TEST_TIME 30 10139
TEST_TIME 31 10497
TEST_TIME 32 11106
TEST_TIME 33 11546
TEST_TIME 34 11574
TEST_TIME 35 11416
TEST_TIME 36 11118
TEST_TIME 37 10929
TEST_TIME 38 10803
TEST_TIME 39 10684
TEST_TIME 40 10569
TEST_TIME 41 10530
TEST_TIME 42 10463
TEST_TIME 43 10391
TEST_TIME 44 10205
TEST_TIME 45 10160
TEST_TIME 46 10089
TEST_TIME 47 9925
TEST_TIME 48 9790
TEST_TIME 49 9770
TEST_TIME 50 9986
TEST_TIME 51 10002
TEST_TIME 52 9923
TEST_TIME 53 9863
TEST_TIME 54 9938
TEST_TIME 55 9929
TEST_TIME 56 9856
TEST_TIME 57 9898
TEST_TIME 58 9833
TEST_TIME 59 9892
TEST_TIME 60 9884
TEST_TIME 61 9968
TEST_TIME 62 9861
TEST_TIME 63 9754
TEST_TIME 64 9655
TEST_TIME 65 9582
TEST_TIME 66 9588
TEST_TIME 67 9537
TEST_TIME 68 9434
TEST_TIME 69 9340
TEST_TIME 70 9401
TEST_TIME 71 9421
TEST_TIME 72 9391
TEST_TIME 73 9387
TEST_TIME 74 9399
TEST_TIME 75 9366
TEST_TIME 76 9373
TEST_TIME 77 9445
TEST_TIME 78 9479
TEST_TIME 79 9442
TEST_TIME 80 9527
TEST_TIME 81 9586
TEST_TIME 82 9515
TEST_TIME 83 9531
TEST_TIME 84 9540
TEST_TIME 85 9448
TEST_TIME 86 9406
TEST_TIME 87 9326
TEST_TIME 88 9295
TEST_TIME 89 9338
TEST_TIME 90 9260
TEST_TIME 91 9190
TEST_TIME 92 9230
TEST_TIME 93 9239
TEST_TIME 94 9205
TEST_TIME 95 9174
TEST_TIME 96 9165
TEST_TIME 97 9160
TEST_TIME 98 9162
TEST_TIME 99 9143
TEST_TIME 100 9264
TEST_TIME 101 9248
TEST_TIME 102 9303
TEST_TIME 103 9284
TEST_TIME 104 9261
TEST_TIME 105 9301
TEST_TIME 106 9291
TEST_TIME 107 9217
TEST_TIME 108 9169
TEST_TIME 109 9171
TEST_TIME 110 9113
TEST_TIME 111 9063
TEST_TIME 112 9081
TEST_TIME 113 9055
TEST_TIME 114 9040
TEST_TIME 115 9018
TEST_TIME 116 9044
TEST_TIME 117 9027
TEST_TIME 118 9001
TEST_TIME 119 8965
TEST_TIME 120 8901

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-05-2022 10:38:48 to 01-05-2022 10:38:51.

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 1480524 1480524 0 0.00 0 17103 14 0 0
doc_list_biud 1480522 1480522 0 0.00 0 48226 41 0 0
sp_add_invoice_to_stock 4905 4807 98 1.99 44 263939 28839 0 98
sp_cancel_adding_invoice 5470 5470 0 0.00 7 74835 1134 0 0
sp_cancel_client_order 1675 1675 0 0.00 6 10501 414 0 0
sp_cancel_customer_reserve 532991 532991 0 0.00 1 48231 99 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 4 4135 123 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 4 1166 110 0 0
sp_cancel_supplier_invoice 6040 6036 4 0.06 2 16665 957 0 4
sp_cancel_supplier_order 393 393 0 0.00 6 1498055 169637 0 0
sp_cancel_write_off 9648 9648 0 0.00 2 27940 176 0 0
sp_client_order 19389 19389 0 0.00 6 26959 482 0 0
sp_customer_reserve 450555 450555 0 0.00 1 100174 276 0 0
sp_fill_shopping_cart 29918 29918 0 0.00 2 14023 90 0 0
sp_fill_shopping_cart_clo_res 450535 450535 0 0.00 0 16614 19 0 0
sp_get_clo_for_invoice 4787 4787 0 0.00 0 32254 332 0 0
sp_kill_cost_storno 16176 16176 0 0.00 0 5992 69 0 0
sp_kill_qstorno_ret_qs2qd 1082059 1082059 0 0.00 0 48217 42 0 0
sp_kill_qty_storno 570510 570510 0 0.00 0 48225 97 0 0
sp_lock_selected_doc 19835 19835 0 0.00 0 9163 8 0 0
sp_make_cost_storno 26451 26451 0 0.00 0 17087 82 0 0
sp_multiply_rows_for_pdistr 26450 26450 0 0.00 0 8581 36 0 0
sp_multiply_rows_for_qdistr 19389 19389 0 0.00 0 11099 188 0 0
sp_pay_from_customer 6684 6684 0 0.00 6 11351 163 0 0
sp_pay_to_supplier 5468 5468 0 0.00 7 16097 201 0 0
sp_payment_common 12152 12152 0 0.00 2 7246 41 0 0
sp_qd_handle_on_cancel_clo 1675 1675 0 0.00 1 10493 325 0 0
sp_qd_handle_on_invoice_upd_sts 10256 10256 0 0.00 0 22696 508 0 0
sp_qd_handle_on_reserve_upd_sts 9511 9511 0 0.00 0 16880 69 0 0
sp_reserve_write_off 9512 9512 0 0.00 9 21120 286 0 0
sp_supplier_invoice 5210 5210 0 0.00 12 80266 3339 0 0
sp_supplier_order 5348 5348 0 0.00 8 32191 1395 0 0
srv_aggregate_perf_data 119 119 0 0.00 2296 214770 16163 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 665 131540 38687 0 0
srv_find_qd_qs_mism 1018935 1018935 0 0.00 0 17038 12 0 0
srv_make_invnt_saldo 462 462 0 0.00 109 78928 3905 0 0
srv_make_money_saldo 894 894 0 0.00 2 18768 367 0 0
srv_recalc_idx_stat 4 4 0 0.00 196303 409556 324955 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 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 4812 4812 0 0.00 1 10768 968 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 1 7025 476 0 0
t$perf-norm:sp_cancel_client_order 1675 1675 0 0.00 0 3211 30 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 611 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 3131 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 1339 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 575 31 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 107884 7079 0 0
t$perf-norm:sp_cancel_write_off 947 947 0 0.00 0 5114 26 0 0
t$perf-norm:sp_client_order 19389 19389 0 0.00 0 7170 35 0 0
t$perf-norm:sp_customer_reserve 919 919 0 0.00 0 1064 14 0 0
t$perf-norm:sp_pay_from_customer 6684 6684 0 0.00 0 6445 20 0 0
t$perf-norm:sp_pay_to_supplier 5468 5468 0 0.00 0 7201 18 0 0
t$perf-norm:sp_reserve_write_off 9512 9512 0 0.00 0 5671 24 0 0
t$perf-norm:sp_supplier_invoice 5210 5210 0 0.00 0 5207 30 0 0
t$perf-norm:sp_supplier_order 5348 5348 0 0.00 0 3407 32 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 1241 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 35 4 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 2714 20 0 0
t$perf-norm:srv_make_money_saldo 894 894 0 0.00 0 187 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 195 62 0 0
v_add_invoice_to_stock 9756 9560 196 2.00 0 5439 27 0 196
v_all_customers 35474 35474 0 0.00 0 66 4 0 0
v_all_suppliers 28926 28926 0 0.00 0 70 4 0 0
v_all_wares 38760 38760 0 0.00 0 91 4 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 1653 18 0 0
v_cancel_client_order 3350 3350 0 0.00 0 5456 15 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 3249 18 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 3723 17 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1115 13 0 0
v_cancel_supplier_invoice 846 838 8 0.94 0 494 25 0 8
v_cancel_supplier_order 786 786 0 0.00 0 1900 15 0 0
v_cancel_write_off 1894 1894 0 0.00 0 3421 28 0 0
v_max_id_clo_ord 5335 5335 0 0.00 0 5715 8 0 0
v_max_id_clo_res 919 919 0 0.00 0 2801 23 0 0
v_max_id_ord_sup 5197 5197 0 0.00 0 4017 9 0 0
v_max_non_paid_invoice 1537 1537 0 0.00 0 135 7 0 0
v_max_non_paid_realizn 3363 3363 0 0.00 0 2755 8 0 0
v_min_id_clo_ord 5335 5335 0 0.00 0 5722 8 0 0
v_min_id_clo_res 919 919 0 0.00 0 7459 36 0 0
v_min_id_ord_sup 5197 5197 0 0.00 0 4890 11 0 0
v_min_non_paid_invoice 5468 5468 0 0.00 0 3292 10 0 0
v_min_non_paid_realizn 6684 6684 0 0.00 0 7236 15 0 0
v_our_firm 9928 9928 0 0.00 0 61 4 0 0
v_reserve_write_off 19016 19016 0 0.00 0 4390 13 0 0
x_lock_depdocs_on_canc_invoice 11087 11087 0 0.00 0 8827 126 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 4751 378 0 0
x_make_qstorno_1000_1200 5348 5348 0 0.00 3 32171 1208 0 0
x_make_qstorno_1200_2000 5210 5210 0 0.00 6 80207 3098 0 0
x_make_qstorno_2100_3300 450555 450555 0 0.00 0 100171 245 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-05-2022 10:38:52 to 01-05-2022 10:38:56.

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:21 35146 8192000000 9726304576 10028224512 802679760 8.923 100 85 169 1 830493104 7882304 19931040
08:10:06 19225 8192000000 9765412496 10075881472 840098544 9.301 100 90 180 0 874668752 13337072 21233136
08:11:29 10846 8192000000 9803034992 10125074432 878432752 9.684 100 87 171 1 917566688 20173024 18960912
08:12:44 10209 8192000000 9871902640 10209574912 924529008 10.141 100 88 175 2 984733232 41522176 18682048
08:13:57 5358 8192000000 9936319744 10282172416 966239056 10.550 100 86 172 1 1049803792 65803008 17761728
08:15:05 2869 8192000000 9967508592 10325454848 999560944 10.874 100 94 185 0 1088632944 69737072 19334928
08:16:10 1281 8192000000 9997343024 10358644736 1022325360 11.094 100 90 176 1 1112887376 73083776 17478240
08:17:12 1316 8192000000 10029633248 10400047104 1048712000 11.348 100 94 185 0 1146730144 79515936 18502208
08:18:16 1021 8192000000 10041307552 10416189440 1063792832 11.493 100 96 181 0 1162571872 81324768 17454272
08:19:18 704 8192000000 10037666704 10416840704 1074195360 11.592 100 96 182 0 1157959776 65100608 18663808
08:20:19 796 8192000000 10048040080 10433925120 1079544080 11.643 100 93 179 0 1173346288 75977632 17824576
08:21:21 1181 8192000000 10080298288 10463633408 1080901232 11.656 100 94 176 2 1202463232 104120096 17441904
08:22:24 1171 8192000000 10075982976 10463776768 1087530096 11.719 100 96 184 0 1195767616 89068320 19169200
08:23:26 1234 8192000000 10053249392 10438270976 1091762432 11.759 100 97 187 1 1175099344 64124592 19212320
08:24:28 1144 8192000000 10072809856 10461937664 1094949440 11.790 100 92 177 3 1193389984 78832976 19607568
08:25:30 1247 8192000000 10049179840 10440204288 1082017648 11.667 99 82 162 1 1169141200 70925936 16197616
08:26:32 1029 8192000000 10071602528 10460368896 1099739552 11.835 100 92 178 0 1190563920 72088560 18735808
08:27:34 1207 8192000000 10042843504 10427944960 1092372032 11.765 100 95 180 0 1165834800 56069136 17393632
08:28:37 1088 8192000000 10075817776 10467454976 1102830352 11.864 100 90 167 1 1191977968 72095552 17052064
08:29:38 1187 8192000000 10073908352 10463277056 1091212048 11.754 100 95 181 0 1196769680 86930176 18627456
08:30:41 1224 8192000000 10064840272 10448834560 1085120000 11.696 100 93 180 0 1185713136 81555104 19038032
08:31:43 2673 8192000000 10026346848 10407190528 1071261328 11.564 100 73 133 4 1141165648 56946816 12957504
08:32:47 1176 8192000000 9987305952 10362441728 1042365488 11.287 100 97 181 1 1112288496 50968256 18954752
08:33:49 1374 8192000000 10002131888 10369871872 1038911488 11.254 100 92 172 1 1124565104 68842592 16811024
08:34:51 1281 8192000000 10009952816 10385629184 1043066864 11.294 99 96 176 3 1131716368 70792032 17857472
08:35:53 1406 8192000000 9960203008 10334208000 1031648800 11.184 100 79 148 0 1081793344 33943312 16201232
08:36:55 1105 8192000000 10005324000 10393714688 1050274304 11.363 100 80 161 1 1131244048 64467936 16501808
08:38:01 9337 8192000000 9991501072 10368778240 1058804224 11.445 100 84 153 1 1107265680 32103536 16357920
08:39:18 13704 8192000000 10020165808 10409197568 1070400032 11.556 100 73 135 2 1144446384 59751920 14294432
08:40:48 1186 8192000000 10085985328 10469531648 1090782720 11.750 100 95 183 3 1206117984 94876768 20458496
08:41:52 1871 8192000000 10115753776 10504957952 1103034880 11.866 100 82 157 2 1239120304 120569968 15515456
08:42:57 975 8192000000 10136971312 10523160576 1113751744 11.968 100 91 167 1 1251184544 120155008 17277792
08:44:02 3998 8192000000 10151088736 10552459264 1126032336 12.084 100 62 122 0 1271346304 131734624 13579344
08:45:09 1416 8192000000 10190489984 10584641536 1131416784 12.135 100 86 164 2 1313210032 163322976 18470272
08:46:14 5285 8192000000 10231748288 10632228864 1140190336 12.217 100 76 148 0 1344303520 187923008 16190176
08:47:24 1245 8192000000 10239958544 10635157504 1152901952 12.337 100 87 166 0 1347938896 177326368 17710576
08:48:29 1591 8192000000 10269961472 10673315840 1168443232 12.482 100 89 171 1 1384069936 197635680 17991024
08:49:34 3028 8192000000 10275298368 10697428992 1175395232 12.547 100 87 170 1 1391301648 196841488 19064928
08:50:43 1570 8192000000 10348045056 10749571072 1193059376 12.712 100 96 185 2 1458456544 244135360 21261808
08:51:47 1570 8192000000 10368089952 10774228992 1195353568 12.733 100 85 163 1 1474526016 260727136 18445312
08:52:51 655 8192000000 10402257536 10809659392 1188300368 12.668 100 95 180 1 1512661024 303938976 20421680
08:53:55 1562 8192000000 10449694192 10866585600 1188956064 12.674 100 90 182 1 1558672112 348204048 21512000
08:55:01 987 8192000000 10502426464 10914697216 1199776656 12.774 100 97 190 1 1608654400 386110800 22766944
08:56:05 1178 8192000000 10537404192 10957627392 1189833376 12.682 99 96 192 0 1641204576 428125184 23246016
08:57:09 813 8192000000 10580638400 10997444608 1190958800 12.692 100 98 194 0 1684334624 469715408 23660416
08:58:14 984 8192000000 10549429248 11013922816 1201591312 12.791 100 99 191 1 1659611344 434810464 23209568
08:59:18 674 8192000000 10586462368 11041107968 1204336320 12.817 100 95 188 0 1690864880 463129120 23399440
09:00:20 4893 8192000000 10571677200 11024367616 1190173712 12.685 100 55 110 0 1669631232 465117248 14340272
09:01:31 2716 8192000000 10547288848 11027734528 1180007872 12.590 100 86 164 1 1647128240 445881344 21239024
09:02:40 3694 8192000000 10551542208 11055304704 1194043456 12.721 100 64 128 0 1657379504 446862064 16473984
09:03:50 10447 8192000000 10549387776 11000766464 1183688784 12.625 100 45 89 0 1651774176 456320496 11764896
09:05:09 36612 8192000000 10544626352 10996170752 1174933744 12.543 100 34 68 1 1634537424 450265344 9338336
09:06:47 16422 8192000000 10478131136 10989211648 1172367680 12.519 100 33 66 1 1577951808 396904080 8680048
09:08:04 14389 8192000000 10375174192 10887094272 1170688064 12.503 100 31 62 0 1512811568 334460160 7663344
09:09:28 30603 8192000000 10185938048 10751819776 1131693040 12.137 99 19 38 0 1353535296 217033712 4808544
09:10:59 92882 8192000000 10068751888 10499604480 1048304624 11.344 92 27 53 1 1089112384 35082880 5724880
09:13:36 74694 8192000000 10100860288 10552496128 1131286192 12.133 100 60 116 2 1185599328 41778256 12534880
09:15:52 118164 8192000000 10103610016 10558615552 1121098864 12.037 98 33 66 2 1185634272 57945280 6590128
09:18:52 72511 8192000000 10092901344 10577502208 1138781584 12.204 99 17 34 0 1193979728 51919264 3278880
09:21:40 31613 8192000000 10116175632 10569641984 1151036368 12.319 100 16 33 1 1225816288 71155936 3623984
09:23:12 36677 8192000000 10119484928 10573541376 1113217904 11.963 96 33 65 1 1184914992 65168816 6528272
09:24:49 119125 8192000000 10155587920 10659938304 1126641552 12.090 98 19 37 2 1276324944 145786720 3896672
09:27:50 53971 8192000000 10076185040 10553950208 1110396784 11.936 98 25 48 1 1155238320 39359088 5482448
09:29:45 55120 8192000000 10110794048 10585452544 1133225904 12.152 99 17 34 1 1217071136 79827184 4018048
09:31:59 32549 8192000000 10096327232 10534543360 1117881136 12.007 98 24 48 0 1164782880 42128304 4773440
09:33:34 82008 8192000000 10049449616 10498125824 1038211072 11.247 91 23 44 1 1090467856 47981392 4275392
09:35:58 43849 8192000000 10094434304 10567401472 1117269776 12.001 99 49 95 1 1206495840 79744400 9481664
09:37:44 43512 8192000000 10116276256 10596237312 1119157568 12.019 99 23 46 1 1196598944 72549344 4892032
09:39:28 101943 8192000000 10041008288 10480181248 1063392544 11.489 94 35 70 0 1083072032 12376496 7302992
09:42:10 2325 8192000000 10074687024 10495791104 1111173696 11.944 99 64 121 2 1175862976 52393184 12296096
09:43:16 37178 8192000000 10052955792 10520408064 1113595568 11.966 99 24 48 1 1180104096 61304352 5204176
09:44:53 79288 8192000000 10083733216 10540118016 1088882576 11.732 97 12 24 0 1169207008 77573312 2751120
09:47:13 66833 8192000000 10074053712 10522148864 1104908416 11.884 99 10 20 0 1185987968 79004880 2074672
09:49:20 31503 8192000000 10050415488 10484842496 1076026544 11.610 96 28 55 2 1119088640 37772032 5290064
09:50:55 40470 8192000000 10088875024 10554200064 1122500832 12.051 100 25 50 0 1197105568 69183184 5421552
09:52:37 33882 8192000000 10142997040 10591145984 1124630672 12.071 99 39 78 1 1217778960 85368464 7779824
09:54:11 66192 8192000000 10123916128 10612498432 1129888512 12.120 99 26 52 2 1210900016 75686256 5325248
09:56:27 56549 8192000000 10159550864 10632835072 1136501936 12.183 99 37 74 0 1222694432 78679392 7513104
09:59:12 22129 8192000000 10093756256 10516791296 1140856576 12.224 99 45 90 0 1181965376 31880944 9227856
10:00:37 60966 8192000000 10085018496 10564804608 1122989296 12.055 98 42 84 0 1222615008 90968976 8656736
10:02:38 86745 8192000000 10102943376 10555662336 1148364224 12.294 100 26 52 0 1195480560 42115664 5000672
10:05:19 127795 8192000000 10111784528 10629672960 1141657616 12.231 99 12 23 1 1231690368 86832656 3200096
10:08:27 75712 8192000000 10112357984 10639548416 1145914128 12.271 99 15 30 3 1262471248 112851440 3705680
10:10:49 71376 8192000000 10080894560 10524778496 1129743024 12.119 98 17 34 0 1171635984 38076064 3816896
10:13:04 57698 8192000000 10134715936 10594725888 1148710736 12.297 99 25 50 0 1231605072 77026480 5867856
10:15:02 78005 8192000000 10190437840 10637234176 1115779328 11.987 97 17 34 1 1243143056 123185056 4178672
10:17:50 66695 8192000000 10128150048 10590601216 1105736608 11.892 96 27 52 3 1202577456 91608304 5232544
10:20:04 18062 8192000000 10106463104 10525802496 1136218688 12.180 100 50 98 2 1182253120 35619744 10414688
10:21:22 25474 8192000000 10108480816 10555383808 1114176832 11.972 99 56 112 1 1213297968 87114032 12007104
10:22:49 42123 8192000000 10101062704 10548981760 1121393728 12.040 99 43 84 1 1198968864 69043952 8531184
10:24:32 131540 8192000000 10069209264 10542112768 1072533664 11.576 95 34 68 2 1170120208 91019584 6566960
10:28:09 64507 8192000000 10069837792 10547032064 1107469952 11.908 99 14 28 0 1155504064 45020368 3013744
10:30:16 49688 8192000000 10076551264 10534862848 1108881568 11.922 98 24 47 3 1166511904 52311728 5318608
10:32:13 56291 8192000000 10125904784 10567180288 1120466352 12.031 99 28 56 0 1211674976 84952288 6256336
10:34:10 61708 8192000000 10098942512 10546204672 1089932640 11.742 96 20 39 0 1162619232 68051680 4634912
10:36:14 26375 8192000000 10154290896 10584969216 1114379872 11.974 98 36 72 1 1223263616 101070992 7812752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-05-2022 10:38:58 to 01-05-2022 10:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 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 01-05-2022 10:39:15 to 01-05-2022 10:39:17.

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          208139
MON$OLDEST_ACTIVE               217283
MON$OLDEST_SNAPSHOT             217283
MON$NEXT_TRANSACTION            217283
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                       2069280
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2762 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2762 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2762 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2762 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Sun May  1 10:37:17 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

01.05.22 10:39:18. 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-01 10:39:21 No severe PSQL-related problems occured
01.05.22 10:39:22. End of report.