Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.01.2022 13: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 16014468 kB
MemAvailable 31315616 kB
Buffers 0 kB
Cached 15699388 kB
SwapCached 2328 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-01-14 13:37:13
DTS_END                         2022-01-14 13:37:13.8110
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/34676
TRN_ID                          218802
ATT_ID                          633
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: 9419.66 27407 1130359
customer order: creation 165.90 468 19908
customer order: refuse 14.24 384 1709
order to supplier: creation 44.00 1365 5280
order to supplier: removal 3.42 180124 410
invoice (draft): creation 45.06 3222 5407
invoice (draft): removal 55.93 969 6711
invoice accept: apply 40.41 25584 4849
invoice accept: cancel 50.29 1144 6035
customer reserve: creation 3858.05 236 462966
customer reserve: removal 4848.13 99 581775
realization accept: apply 80.04 300 9605
realization accept: cancel 86.72 152 10406
payment from customer: creation 57.45 181 6894
payment from customer: removal 5.75 123 690
payment to supplier: creation 48.41 207 5809
payment to supplier: removal 3.48 128 418
service: total inventory turnovers 3.80 4371 456
service: total monetary turnovers 7.51 349 901
service: aggregate perf. data 1.05 14228 126
service: refresh index statistics 0.03 314505 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-01-2022 13:38:39 to 14-01-2022 13:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8833
TEST_TIME 2 7107
TEST_TIME 3 6137
TEST_TIME 4 6052
TEST_TIME 5 5887
TEST_TIME 6 6322
TEST_TIME 7 5979
TEST_TIME 8 6637
TEST_TIME 9 6590
TEST_TIME 10 6542
TEST_TIME 11 6971
TEST_TIME 12 7098
TEST_TIME 13 7272
TEST_TIME 14 7618
TEST_TIME 15 7735
TEST_TIME 16 7579
TEST_TIME 17 7523
TEST_TIME 18 7605
TEST_TIME 19 7466
TEST_TIME 20 7726
TEST_TIME 21 8153
TEST_TIME 22 8361
TEST_TIME 23 8333
TEST_TIME 24 8284
TEST_TIME 25 8336
TEST_TIME 26 8368
TEST_TIME 27 8568
TEST_TIME 28 9210
TEST_TIME 29 9430
TEST_TIME 30 9550
TEST_TIME 31 10020
TEST_TIME 32 10728
TEST_TIME 33 11017
TEST_TIME 34 11400
TEST_TIME 35 11269
TEST_TIME 36 11244
TEST_TIME 37 11510
TEST_TIME 38 11414
TEST_TIME 39 11344
TEST_TIME 40 11155
TEST_TIME 41 11054
TEST_TIME 42 11042
TEST_TIME 43 11044
TEST_TIME 44 10955
TEST_TIME 45 11008
TEST_TIME 46 10776
TEST_TIME 47 10579
TEST_TIME 48 10543
TEST_TIME 49 10357
TEST_TIME 50 10353
TEST_TIME 51 10360
TEST_TIME 52 10238
TEST_TIME 53 10199
TEST_TIME 54 10329
TEST_TIME 55 10253
TEST_TIME 56 10211
TEST_TIME 57 10140
TEST_TIME 58 10111
TEST_TIME 59 9989
TEST_TIME 60 9954
TEST_TIME 61 9835
TEST_TIME 62 9831
TEST_TIME 63 9848
TEST_TIME 64 9797
TEST_TIME 65 9769
TEST_TIME 66 9712
TEST_TIME 67 9708
TEST_TIME 68 9720
TEST_TIME 69 9718
TEST_TIME 70 9854
TEST_TIME 71 9854
TEST_TIME 72 9965
TEST_TIME 73 10013
TEST_TIME 74 10006
TEST_TIME 75 10040
TEST_TIME 76 10012
TEST_TIME 77 9966
TEST_TIME 78 9895
TEST_TIME 79 9956
TEST_TIME 80 9859
TEST_TIME 81 9838
TEST_TIME 82 9758
TEST_TIME 83 9714
TEST_TIME 84 9730
TEST_TIME 85 9711
TEST_TIME 86 9732
TEST_TIME 87 9680
TEST_TIME 88 9596
TEST_TIME 89 9611
TEST_TIME 90 9558
TEST_TIME 91 9515
TEST_TIME 92 9509
TEST_TIME 93 9475
TEST_TIME 94 9441
TEST_TIME 95 9414
TEST_TIME 96 9404
TEST_TIME 97 9412
TEST_TIME 98 9406
TEST_TIME 99 9400
TEST_TIME 100 9504
TEST_TIME 101 9599
TEST_TIME 102 9722
TEST_TIME 103 9640
TEST_TIME 104 9621
TEST_TIME 105 9628
TEST_TIME 106 9605
TEST_TIME 107 9569
TEST_TIME 108 9581
TEST_TIME 109 9660
TEST_TIME 110 9630
TEST_TIME 111 9598
TEST_TIME 112 9615
TEST_TIME 113 9600
TEST_TIME 114 9661
TEST_TIME 115 9637
TEST_TIME 116 9596
TEST_TIME 117 9534
TEST_TIME 118 9543
TEST_TIME 119 9509
TEST_TIME 120 9438

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-01-2022 13:38:43 to 14-01-2022 13:38:46.

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 1556455 1556455 0 0.00 0 29087 13 0 0
doc_list_biud 1556443 1556443 0 0.00 0 61451 42 0 0
sp_add_invoice_to_stock 4934 4849 85 1.72 32 186527 25585 0 85
sp_cancel_adding_invoice 6035 6035 0 0.00 7 111034 1144 0 0
sp_cancel_client_order 1709 1709 0 0.00 6 11908 385 0 0
sp_cancel_customer_reserve 581775 581775 0 0.00 1 37617 99 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 4 3526 123 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 2626 129 0 0
sp_cancel_supplier_invoice 6718 6711 7 0.10 3 61512 970 0 7
sp_cancel_supplier_order 410 410 0 0.00 11 1480360 180125 0 0
sp_cancel_write_off 10406 10406 0 0.00 2 10084 152 0 0
sp_client_order 19908 19908 0 0.00 5 29901 469 0 0
sp_customer_reserve 462966 462966 0 0.00 1 54071 237 0 0
sp_fill_shopping_cart 30574 30574 0 0.00 2 14637 77 0 0
sp_fill_shopping_cart_clo_res 462946 462946 0 0.00 0 16319 18 0 0
sp_get_clo_for_invoice 4822 4822 0 0.00 0 31631 269 0 0
sp_kill_cost_storno 17549 17549 0 0.00 0 29078 71 0 0
sp_kill_qstorno_ret_qs2qd 1181077 1181077 0 0.00 0 61449 42 0 0
sp_kill_qty_storno 621469 621469 0 0.00 0 61450 98 0 0
sp_lock_selected_doc 20095 20095 0 0.00 0 10974 8 0 0
sp_make_cost_storno 27130 27130 0 0.00 0 16450 89 0 0
sp_multiply_rows_for_pdistr 27127 27127 0 0.00 0 10530 43 0 0
sp_multiply_rows_for_qdistr 19907 19907 0 0.00 0 13751 162 0 0
sp_pay_from_customer 6894 6894 0 0.00 5 17250 181 0 0
sp_pay_to_supplier 5809 5809 0 0.00 6 17580 208 0 0
sp_payment_common 12702 12702 0 0.00 2 17195 48 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 0 11859 308 0 0
sp_qd_handle_on_invoice_upd_sts 10855 10855 0 0.00 0 28587 518 0 0
sp_qd_handle_on_reserve_upd_sts 9603 9603 0 0.00 0 20785 69 0 0
sp_reserve_write_off 9605 9605 0 0.00 9 22717 301 0 0
sp_supplier_invoice 5407 5407 0 0.00 8 85201 3222 0 0
sp_supplier_order 5280 5280 0 0.00 6 44009 1366 0 0
srv_aggregate_perf_data 126 126 0 0.00 2555 145714 14228 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 839 129173 36155 0 0
srv_find_qd_qs_mism 1082072 1082072 0 0.00 0 16985 9 0 0
srv_make_invnt_saldo 456 456 0 0.00 41 95167 4371 0 0
srv_make_money_saldo 901 901 0 0.00 1 13042 350 0 0
srv_recalc_idx_stat 4 4 0 0.00 257284 429338 314506 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4854 4854 0 0.00 1 12762 934 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 7463 461 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 1799 28 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 8800 45 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 1694 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 2511 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1723 33 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 96932 7629 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 1526 22 0 0
t$perf-norm:sp_client_order 19908 19908 0 0.00 0 9104 33 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 610 17 0 0
t$perf-norm:sp_pay_from_customer 6895 6895 0 0.00 0 5156 22 0 0
t$perf-norm:sp_pay_to_supplier 5809 5809 0 0.00 0 4399 22 0 0
t$perf-norm:sp_reserve_write_off 9605 9605 0 0.00 0 7595 26 0 0
t$perf-norm:sp_supplier_invoice 5407 5407 0 0.00 0 7987 38 0 0
t$perf-norm:sp_supplier_order 5280 5280 0 0.00 0 6158 34 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 400 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 213 8 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 1133 14 0 0
t$perf-norm:srv_make_money_saldo 901 901 0 0.00 0 521 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 141 55 0 0
v_add_invoice_to_stock 9794 9624 170 1.73 0 6515 26 0 170
v_all_customers 36868 36868 0 0.00 0 161 3 0 0
v_all_suppliers 30228 30228 0 0.00 0 69 4 0 0
v_all_wares 39798 39798 0 0.00 0 179 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 2600 19 0 0
v_cancel_client_order 3418 3418 0 0.00 0 3076 9 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 2355 14 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 2106 15 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 2586 20 0 0
v_cancel_supplier_invoice 848 834 14 1.65 0 5176 33 0 14
v_cancel_supplier_order 820 820 0 0.00 0 1007 14 0 0
v_cancel_write_off 1918 1918 0 0.00 0 3393 26 0 0
v_max_id_clo_ord 5274 5274 0 0.00 0 737 5 0 0
v_max_id_clo_res 962 962 0 0.00 0 1126 12 0 0
v_max_id_ord_sup 5396 5396 0 0.00 0 4124 6 0 0
v_max_non_paid_invoice 1364 1364 0 0.00 0 60 7 0 0
v_max_non_paid_realizn 3453 3453 0 0.00 0 5051 10 0 0
v_min_id_clo_ord 5274 5274 0 0.00 0 2460 9 0 0
v_min_id_clo_res 962 962 0 0.00 0 6413 15 0 0
v_min_id_ord_sup 5396 5396 0 0.00 0 3252 7 0 0
v_min_non_paid_invoice 5808 5808 0 0.00 0 5597 19 0 0
v_min_non_paid_realizn 6894 6894 0 0.00 0 4807 17 0 0
v_our_firm 9808 9808 0 0.00 0 62 4 0 0
v_reserve_write_off 19206 19206 0 0.00 0 4796 12 0 0
x_lock_depdocs_on_canc_invoice 12329 12329 0 0.00 0 8518 111 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 17129 419 0 0
x_make_qstorno_1000_1200 5280 5280 0 0.00 2 43988 1193 0 0
x_make_qstorno_1200_2000 5407 5407 0 0.00 3 81679 3024 0 0
x_make_qstorno_2100_3300 462964 462964 0 0.00 0 54069 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-01-2022 13:38:47 to 14-01-2022 13:38:51.

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:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:19 25037 8192000000 9726435984 10026373120 808049424 8.978 100 88 176 0 836873408 7387312 21436672
11:09:52 21153 8192000000 9774535392 10082975744 844152944 9.341 100 88 176 0 878092096 12790640 21148512
11:11:22 18879 8192000000 9806072288 10127503360 878429152 9.684 100 79 158 0 917797328 21417344 17950832
11:12:43 10156 8192000000 9878625104 10213511168 923721616 10.133 100 86 172 0 988002912 45853072 18428224
11:13:56 5725 8192000000 9921041472 10259374080 965066944 10.539 100 89 174 1 1030897776 47966160 17864672
11:15:05 3790 8192000000 9968709840 10317746176 994052112 10.821 100 93 184 0 1074442720 61854368 18536240
11:16:11 2124 8192000000 10016742144 10377785344 1015854352 11.032 100 92 183 0 1141666144 107579136 18232656
11:17:15 1687 8192000000 10006917488 10372063232 1034931248 11.216 100 90 173 0 1126022720 73881920 17209552
11:18:19 1022 8192000000 10033165584 10408325120 1058514560 11.442 100 98 193 2 1157863440 79146048 20202832
11:19:21 878 8192000000 10029275424 10403205120 1070100240 11.553 100 96 188 2 1154197408 64378848 19718320
11:20:22 1051 8192000000 10055571312 10436333568 1074482208 11.595 100 96 192 1 1177986928 83956336 19548384
11:21:24 1924 8192000000 10056679312 10442051584 1080097952 11.648 100 74 142 0 1181049504 87401536 13550016
11:22:27 1008 8192000000 10061119120 10447302656 1085767040 11.702 100 94 183 1 1179872672 75444336 18661296
11:23:29 1089 8192000000 10082077200 10465468416 1089938992 11.742 100 93 180 0 1199459488 90925056 18595440
11:24:31 1124 8192000000 10066770560 10455277568 1099612544 11.834 100 89 169 0 1191515264 73327488 18575232
11:25:33 1205 8192000000 10078871568 10466177024 1095549520 11.795 100 82 158 1 1204229008 92713312 15966176
11:26:35 1060 8192000000 10044719600 10434482176 1097362224 11.813 100 83 158 2 1165587248 51611040 16613984
11:27:37 988 8192000000 10061262416 10450575360 1104892816 11.884 100 98 183 3 1182619056 59138832 18587408
11:28:38 6267 8192000000 10044648672 10448175104 1099042976 11.829 100 61 122 1 1179066256 67576832 12446448
11:29:47 1650 8192000000 10037780976 10426843136 1088531008 11.729 100 65 126 1 1151034720 49055440 13448272
11:30:49 1413 8192000000 10024023200 10410889216 1078615024 11.634 100 87 173 0 1145406112 48605424 18185664
11:31:59 2625 8192000000 10019436272 10401652736 1051963872 11.380 99 68 125 3 1143234992 78878512 12392608
11:33:02 2028 8192000000 9985405376 10350305280 1013878128 11.013 99 78 143 2 1100922640 73626096 13418416
11:34:05 4716 8192000000 10013140752 10393497600 1047792128 11.339 100 72 135 2 1131697712 71167376 12738208
11:35:11 2003 8192000000 9993807280 10375073792 1041109968 11.275 100 79 143 4 1118757472 61875936 15771568
11:36:15 923 8192000000 9983079776 10350886912 1028745440 11.156 99 95 174 4 1102807680 55330288 18731952
11:37:20 20088 8192000000 9977847888 10357702656 1053174448 11.391 100 63 116 2 1099044544 34382464 11487632
11:38:51 11543 8192000000 10039615568 10419105792 1075070080 11.600 100 72 139 1 1155644880 66714848 13859952
11:40:11 8990 8192000000 10032488096 10420006912 1068290992 11.536 99 63 121 2 1144056656 63790080 11975584
11:41:29 1798 8192000000 10094140256 10483527680 1104128832 11.877 100 77 145 3 1212077792 91241312 16707648
11:42:37 1102 8192000000 10115940368 10501406720 1105366336 11.889 100 73 144 1 1230254464 110135152 14752976
11:43:41 1092 8192000000 10164548528 10555637760 1120012832 12.027 100 96 186 0 1279766624 139058000 20695792
11:44:47 7914 8192000000 10184731840 10592227328 1122134400 12.047 100 70 135 0 1300690240 164221456 14334384
11:46:01 1381 8192000000 10196400336 10585595904 1138130320 12.198 100 81 151 2 1303180384 147946192 17103872
11:47:06 2007 8192000000 10216485120 10616176640 1147940944 12.290 100 74 145 3 1329314384 165647536 15725904
11:48:12 1362 8192000000 10286938304 10688655360 1163705072 12.438 100 91 180 2 1405347824 221554544 20088208
11:49:18 1875 8192000000 10301910896 10705334272 1170685408 12.503 100 89 175 2 1415603440 224975872 19942160
11:50:24 2453 8192000000 10316462832 10730057728 1174410864 12.538 100 92 183 0 1426300448 231173104 20716480
11:51:30 1911 8192000000 10350061792 10771701760 1185609584 12.642 100 93 186 1 1454224512 246867312 21747616
11:52:38 1337 8192000000 10331079136 10749124608 1186260736 12.649 100 92 181 2 1437337584 228841808 22235040
11:53:44 1092 8192000000 10401195840 10801115136 1204825280 12.821 100 95 184 2 1504219744 278796480 20597984
11:54:49 1798 8192000000 10431621632 10844651520 1199303376 12.770 100 92 178 3 1541598560 318853520 23441664
11:55:57 833 8192000000 10456144272 10879291392 1191771904 12.700 100 94 179 2 1561859152 347484640 22602608
11:57:03 1770 8192000000 10512432256 10950942720 1204619488 12.819 100 98 196 1 1620863600 391715280 24528832
11:58:08 1081 8192000000 10487281616 10954375168 1212196064 12.889 100 98 189 2 1597937248 362430880 23310304
11:59:11 1400 8192000000 10506070352 10935263232 1218482064 12.948 100 75 148 1 1611026576 373819072 18725440
12:00:21 1364 8192000000 10560799728 10981052416 1215825616 12.923 100 74 147 0 1664582736 430948608 17808512
12:01:26 1575 8192000000 10632474448 11054456832 1221626448 12.977 100 86 172 0 1737248016 494191808 21429760
12:02:31 5274 8192000000 10675475568 11096879104 1213030432 12.897 100 68 136 0 1780545216 550060992 17453792
12:03:43 6607 8192000000 10672493376 11112439808 1201916160 12.794 100 67 134 2 1770144016 550614864 17612992
12:04:58 23432 8192000000 10594792976 11102801920 1183311664 12.621 100 44 88 0 1736956848 541890256 11754928
12:06:30 23892 8192000000 10550354096 11046678528 1174787824 12.542 100 36 72 0 1648322704 464246480 9288400
12:08:07 19719 8192000000 10473969792 10976952320 1126140656 12.085 97 31 62 1 1604700800 470049136 8511008
12:10:15 15421 8192000000 10206991440 10722222080 1136102976 12.179 100 37 69 2 1331230032 187000736 8126320
12:11:33 53124 8192000000 10162100976 10600869888 1137519184 12.192 98 29 56 0 1245438448 101539472 6379792
12:13:26 30198 8192000000 10145940848 10609422336 1140444176 12.220 100 31 57 0 1263769632 116765264 6560192
12:15:04 86340 8192000000 10063101856 10515238912 1085290064 11.698 95 23 46 0 1150466992 60191392 4985536
12:17:32 96563 8192000000 10092966816 10578874368 1108890960 11.922 97 23 46 1 1202450832 88995856 4564016
12:21:21 23858 8192000000 10087484256 10521313280 1110265744 11.935 98 41 78 0 1179181648 60669888 8246016
12:22:50 129173 8192000000 10101351472 10569211904 1114828288 11.978 98 33 62 2 1166203360 44688528 6686544
12:26:01 55292 8192000000 10087205472 10544336896 1133298416 12.152 100 28 54 1 1182936512 43510224 6127872
12:27:58 37173 8192000000 10153478336 10597949440 1130566944 12.127 99 29 58 1 1219446320 82806752 6072624
12:29:43 19083 8192000000 10146453856 10567372800 1129138048 12.113 99 64 118 0 1218685456 75544976 14002432
12:31:03 65009 8192000000 10109703504 10614689792 1121589632 12.042 99 22 43 0 1210523824 83927984 5006208
12:33:11 64297 8192000000 10085156656 10551844864 1125424176 12.078 99 19 38 1 1185052016 55520720 4107120
12:35:16 66714 8192000000 10074492544 10541252608 1113691024 11.967 98 23 46 0 1167070352 48389968 4989360
12:37:30 43632 8192000000 10084846496 10579922944 1121562112 12.042 99 20 41 0 1188851504 63184992 4104400
12:39:39 58872 8192000000 10071893760 10536898560 1117680848 12.005 99 15 27 2 1171678496 49722912 4274736
12:41:44 36821 8192000000 10102780128 10537377792 1125416432 12.078 99 35 67 0 1186481264 54370688 6694144
12:43:22 30178 8192000000 10148937408 10593132544 1141979648 12.234 100 19 38 1 1222106752 75892656 4234448
12:44:53 77899 8192000000 10181038944 10640535552 1134277984 12.162 99 19 38 2 1261029952 122649632 4102336
12:47:11 78437 8192000000 10155086192 10622951424 1137316944 12.190 99 18 36 0 1222549568 81438432 3794192
12:49:30 97710 8192000000 10078769104 10534014976 1067053376 11.524 93 17 34 0 1126887392 56540240 3293776
12:52:08 46440 8192000000 10128279504 10606075904 1133060768 12.150 99 41 82 0 1220651584 79247440 8343376
12:53:55 110852 8192000000 10130167840 10622820352 1151022272 12.319 100 17 34 2 1224274528 68648112 4604144
12:56:48 45502 8192000000 10066572624 10503278592 1129125856 12.113 99 38 72 2 1160481712 22850352 8505504
12:58:34 92883 8192000000 10153338880 10609004544 1141074784 12.226 99 12 24 0 1232927536 89003648 2849104
13:01:13 72898 8192000000 10134420752 10585018368 1128796720 12.110 98 19 39 1 1249673760 116592048 4284992
13:03:26 34417 8192000000 10050430704 10506702848 1107666816 11.910 97 17 33 1 1128473408 17382592 3424000
13:05:09 64195 8192000000 10083964688 10547470336 1112816448 11.959 98 16 32 1 1162513680 46408320 3288912
13:07:14 58692 8192000000 10039988064 10482122752 1083526672 11.681 96 9 18 0 1103598256 18382272 1689312
13:09:14 52919 8192000000 10078568656 10506383360 1095077152 11.791 97 30 60 0 1146060080 45026400 5956528
13:11:08 15038 8192000000 10130718912 10565361664 1126114208 12.085 98 40 80 1 1202220944 66847456 9259280
13:12:27 22081 8192000000 10189084608 10621976576 1143534864 12.249 99 37 74 2 1273713872 122414528 7764480
13:13:56 24982 8192000000 10230890256 10668421120 1153618544 12.343 100 52 104 0 1318674320 153477456 11578320
13:15:24 19470 8192000000 10261594240 10689708032 1151612944 12.325 100 41 82 3 1355508560 195005056 8890560
13:16:45 64686 8192000000 10208737456 10659176448 1144970896 12.262 99 21 42 0 1324110768 174007456 5132416
13:19:03 79827 8192000000 10102669680 10570088448 1142324944 12.237 99 23 46 0 1269279104 122227792 4726368
13:21:55 84728 8192000000 10106274064 10577350656 1125852256 12.082 98 23 46 1 1173149888 42952768 4344864
13:24:20 36923 8192000000 10127587760 10564460544 1124372960 12.068 98 32 64 0 1219302720 87596496 7333264
13:27:05 34928 8192000000 10079554912 10502275072 1117656000 12.005 98 31 55 1 1135379264 12029728 5693536
13:28:42 18673 8192000000 10118332368 10545745920 1131029232 12.131 99 49 96 3 1185031328 44397648 9604448
13:30:03 56080 8192000000 10144660800 10585911296 1117787616 12.006 97 24 48 1 1200282624 77709104 4785904
13:32:00 52203 8192000000 10103405168 10544766976 1117252976 12.001 97 28 55 2 1168808720 46018400 5537344
13:33:55 114315 8192000000 10096665840 10557239296 1119799200 12.025 98 38 76 1 1180138848 52962464 7377184
13:36:50 7354 8192000000 10071709920 10504237056 1139289312 12.209 99 53 99 0 1167316832 18129712 9897808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-01-2022 13:38:53 to 14-01-2022 13:39:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-01-2022 13:39:10 to 14-01-2022 13:39:12.

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          216869
MON$OLDEST_ACTIVE               218962
MON$OLDEST_SNAPSHOT             218962
MON$NEXT_TRANSACTION            218962
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                       2085216
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:496: 
:497: mscs-test-161.budzdorov.ru    Fri Jan 14 13:37:16 2022
:498:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:499: 
--- end of diff output ---

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

14.01.22 13:39:13. 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-01-14 13:39:16 No severe PSQL-related problems occured
14.01.22 13:39:17. End of report.