Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.07.2022 07:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15913684 kB
MemAvailable 31150636 kB
Buffers 0 kB
Cached 15828244 kB
SwapCached 2520 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-12 07:37:18
DTS_END                         2022-07-12 07:37:18.1560
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/34494
TRN_ID                          218504
ATT_ID                          661
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: 9122.68 24769 1094722
customer order: creation 166.53 481 19983
customer order: refuse 14.66 476 1759
order to supplier: creation 44.41 1388 5329
order to supplier: removal 3.46 170965 415
invoice (draft): creation 45.59 3081 5471
invoice (draft): removal 51.99 1005 6239
invoice accept: apply 41.01 26060 4921
invoice accept: cancel 46.80 1233 5616
customer reserve: creation 3880.78 243 465693
customer reserve: removal 4538.71 102 544645
realization accept: apply 81.99 306 9839
realization accept: cancel 81.18 175 9741
payment from customer: creation 57.97 184 6956
payment from customer: removal 5.37 127 644
payment to supplier: creation 46.70 202 5604
payment to supplier: removal 3.40 136 408
service: total inventory turnovers 3.99 4247 479
service: total monetary turnovers 7.32 365 878
service: aggregate perf. data 0.82 17553 98
service: refresh index statistics 0.03 267045 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-07-2022 07:39:47 to 12-07-2022 07:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4983
TEST_TIME 2 4342
TEST_TIME 3 4453
TEST_TIME 4 4350
TEST_TIME 5 4429
TEST_TIME 6 4616
TEST_TIME 7 4429
TEST_TIME 8 4360
TEST_TIME 9 4747
TEST_TIME 10 4957
TEST_TIME 11 5247
TEST_TIME 12 5559
TEST_TIME 13 5651
TEST_TIME 14 5959
TEST_TIME 15 6350
TEST_TIME 16 6556
TEST_TIME 17 6689
TEST_TIME 18 7057
TEST_TIME 19 6966
TEST_TIME 20 7020
TEST_TIME 21 7116
TEST_TIME 22 7194
TEST_TIME 23 7360
TEST_TIME 24 7487
TEST_TIME 25 7675
TEST_TIME 26 7986
TEST_TIME 27 8201
TEST_TIME 28 8440
TEST_TIME 29 8759
TEST_TIME 30 8990
TEST_TIME 31 9178
TEST_TIME 32 9476
TEST_TIME 33 9714
TEST_TIME 34 9628
TEST_TIME 35 10182
TEST_TIME 36 10472
TEST_TIME 37 10343
TEST_TIME 38 10277
TEST_TIME 39 10207
TEST_TIME 40 10138
TEST_TIME 41 10190
TEST_TIME 42 10167
TEST_TIME 43 10229
TEST_TIME 44 10269
TEST_TIME 45 10226
TEST_TIME 46 10066
TEST_TIME 47 9881
TEST_TIME 48 9721
TEST_TIME 49 9643
TEST_TIME 50 9660
TEST_TIME 51 9637
TEST_TIME 52 9610
TEST_TIME 53 9610
TEST_TIME 54 9539
TEST_TIME 55 9618
TEST_TIME 56 9522
TEST_TIME 57 9424
TEST_TIME 58 9339
TEST_TIME 59 9356
TEST_TIME 60 9344
TEST_TIME 61 9602
TEST_TIME 62 9573
TEST_TIME 63 9684
TEST_TIME 64 9597
TEST_TIME 65 9639
TEST_TIME 66 9687
TEST_TIME 67 9638
TEST_TIME 68 9623
TEST_TIME 69 9614
TEST_TIME 70 9581
TEST_TIME 71 9657
TEST_TIME 72 9547
TEST_TIME 73 9523
TEST_TIME 74 9435
TEST_TIME 75 9400
TEST_TIME 76 9377
TEST_TIME 77 9343
TEST_TIME 78 9287
TEST_TIME 79 9269
TEST_TIME 80 9234
TEST_TIME 81 9221
TEST_TIME 82 9130
TEST_TIME 83 9137
TEST_TIME 84 9145
TEST_TIME 85 9140
TEST_TIME 86 9160
TEST_TIME 87 9197
TEST_TIME 88 9168
TEST_TIME 89 9124
TEST_TIME 90 9210
TEST_TIME 91 9240
TEST_TIME 92 9222
TEST_TIME 93 9274
TEST_TIME 94 9296
TEST_TIME 95 9333
TEST_TIME 96 9289
TEST_TIME 97 9244
TEST_TIME 98 9207
TEST_TIME 99 9186
TEST_TIME 100 9155
TEST_TIME 101 9112
TEST_TIME 102 9094
TEST_TIME 103 9043
TEST_TIME 104 9079
TEST_TIME 105 9087
TEST_TIME 106 9064
TEST_TIME 107 9093
TEST_TIME 108 9061
TEST_TIME 109 9071
TEST_TIME 110 9101
TEST_TIME 111 9090
TEST_TIME 112 9075
TEST_TIME 113 9044
TEST_TIME 114 9156
TEST_TIME 115 9155
TEST_TIME 116 9284
TEST_TIME 117 9239
TEST_TIME 118 9172
TEST_TIME 119 9194
TEST_TIME 120 9139

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-07-2022 07:39:51 to 12-07-2022 07:39:54.

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 1521981 1521981 0 0.00 0 31555 14 0 0
doc_list_biud 1521974 1521974 0 0.00 0 86861 43 0 0
sp_add_invoice_to_stock 4993 4921 72 1.44 55 182018 26060 0 72
sp_cancel_adding_invoice 5616 5616 0 0.00 6 105138 1234 0 0
sp_cancel_client_order 1759 1759 0 0.00 6 33243 477 0 0
sp_cancel_customer_reserve 544645 544645 0 0.00 1 86869 103 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 4 4274 128 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 4 4677 136 0 0
sp_cancel_supplier_invoice 6245 6239 6 0.09 3 34079 1006 0 6
sp_cancel_supplier_order 415 415 0 0.00 6 1579535 170965 0 0
sp_cancel_write_off 9741 9741 0 0.00 2 28841 176 0 0
sp_client_order 19983 19983 0 0.00 6 36349 481 0 0
sp_customer_reserve 465693 465693 0 0.00 0 146042 243 0 0
sp_fill_shopping_cart 30768 30768 0 0.00 1 15634 81 0 0
sp_fill_shopping_cart_clo_res 465671 465671 0 0.00 0 20912 19 0 0
sp_get_clo_for_invoice 4893 4893 0 0.00 0 15629 243 0 0
sp_kill_cost_storno 16409 16409 0 0.00 0 7019 73 0 0
sp_kill_qstorno_ret_qs2qd 1105685 1105685 0 0.00 0 86537 45 0 0
sp_kill_qty_storno 583142 583142 0 0.00 0 86860 104 0 0
sp_lock_selected_doc 20344 20344 0 0.00 0 11224 11 0 0
sp_make_cost_storno 27291 27291 0 0.00 0 22798 90 0 0
sp_multiply_rows_for_pdistr 27287 27287 0 0.00 0 8720 37 0 0
sp_multiply_rows_for_qdistr 19982 19982 0 0.00 0 16319 170 0 0
sp_pay_from_customer 6956 6956 0 0.00 5 16741 185 0 0
sp_pay_to_supplier 5604 5604 0 0.00 6 16792 202 0 0
sp_payment_common 12560 12560 0 0.00 2 8138 51 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 33224 400 0 0
sp_qd_handle_on_invoice_upd_sts 10504 10504 0 0.00 0 35524 530 0 0
sp_qd_handle_on_reserve_upd_sts 9838 9838 0 0.00 0 30272 76 0 0
sp_reserve_write_off 9839 9839 0 0.00 7 31209 306 0 0
sp_supplier_invoice 5471 5471 0 0.00 9 98712 3081 0 0
sp_supplier_order 5329 5329 0 0.00 8 69026 1388 0 0
srv_aggregate_perf_data 98 98 0 0.00 2481 171486 17553 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 673 243693 41102 0 0
srv_find_qd_qs_mism 1045763 1045763 0 0.00 0 31549 11 0 0
srv_make_invnt_saldo 479 479 0 0.00 32 85155 4248 0 0
srv_make_money_saldo 878 878 0 0.00 1 26152 366 0 0
srv_recalc_idx_stat 4 4 0 0.00 92572 452675 267046 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4926 4926 0 0.00 0 10999 927 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 6680 478 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 6911 30 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 13980 50 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 1946 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 1353 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 4763 44 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 102568 7793 0 0
t$perf-norm:sp_cancel_write_off 951 951 0 0.00 0 1254 17 0 0
t$perf-norm:sp_client_order 19984 19984 0 0.00 0 22734 39 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 1376 15 0 0
t$perf-norm:sp_pay_from_customer 6956 6956 0 0.00 0 3541 19 0 0
t$perf-norm:sp_pay_to_supplier 5604 5604 0 0.00 0 1993 19 0 0
t$perf-norm:sp_reserve_write_off 9840 9840 0 0.00 0 5547 22 0 0
t$perf-norm:sp_supplier_invoice 5471 5471 0 0.00 0 3388 27 0 0
t$perf-norm:sp_supplier_order 5330 5330 0 0.00 0 21261 32 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 376 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 316 11 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 987 16 0 0
t$perf-norm:srv_make_money_saldo 878 878 0 0.00 0 3745 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 180 66 0 0
v_add_invoice_to_stock 9913 9769 144 1.45 0 5943 25 0 144
v_all_customers 36978 36978 0 0.00 0 64 4 0 0
v_all_suppliers 30130 30130 0 0.00 0 73 4 0 0
v_all_wares 39960 39960 0 0.00 0 62 4 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 803 16 0 0
v_cancel_client_order 3518 3518 0 0.00 0 3030 10 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 3654 17 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 1290 12 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 1340 11 0 0
v_cancel_supplier_invoice 806 794 12 1.48 0 7875 40 0 12
v_cancel_supplier_order 830 830 0 0.00 0 3966 16 0 0
v_cancel_write_off 1902 1902 0 0.00 0 4352 27 0 0
v_max_id_clo_ord 5326 5326 0 0.00 0 78 4 0 0
v_max_id_clo_res 983 983 0 0.00 0 1324 14 0 0
v_max_id_ord_sup 5459 5459 0 0.00 0 14370 10 0 0
v_max_non_paid_invoice 1324 1324 0 0.00 0 641 8 0 0
v_max_non_paid_realizn 3455 3455 0 0.00 0 735 7 0 0
v_min_id_clo_ord 5326 5326 0 0.00 0 3282 8 0 0
v_min_id_clo_res 983 983 0 0.00 0 1595 10 0 0
v_min_id_ord_sup 5459 5459 0 0.00 0 5954 12 0 0
v_min_non_paid_invoice 5604 5604 0 0.00 0 7724 21 0 0
v_min_non_paid_realizn 6956 6956 0 0.00 0 7009 25 0 0
v_our_firm 9980 9980 0 0.00 0 61 4 0 0
v_reserve_write_off 19671 19671 0 0.00 0 4305 12 0 0
x_lock_depdocs_on_canc_invoice 11458 11458 0 0.00 0 22887 119 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 14528 409 0 0
x_make_qstorno_1000_1200 5329 5329 0 0.00 2 68682 1232 0 0
x_make_qstorno_1200_2000 5471 5471 0 0.00 4 98662 2845 0 0
x_make_qstorno_2100_3300 465690 465690 0 0.00 0 146039 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-07-2022 07:39:55 to 12-07-2022 07:39:59.

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
05:07:21 2 8192000000 8798448128 8809533440 4478816 0.054 2 1 2 0 4578208 63616 35776
05:08:22 33167 8192000000 9724430896 10024452096 797948320 8.876 100 87 174 0 827343088 7806816 21587952
05:09:58 23749 8192000000 9767623616 10075676672 838820256 9.288 100 92 184 0 873820448 13040768 21959424
05:11:32 13278 8192000000 9814262064 10132586496 880723664 9.707 100 87 174 0 919210784 18178432 20308688
05:12:53 8854 8192000000 9865506640 10197098496 909842720 9.996 100 85 167 0 975060080 46983344 18234016
05:14:07 3036 8192000000 9910746000 10248507392 943497376 10.327 100 89 177 1 1030959424 68649328 18812720
05:15:15 1649 8192000000 9952626336 10305032192 975390336 10.639 100 87 167 0 1068287808 76299424 16598048
05:16:19 2038 8192000000 9992406288 10348802048 1008077472 10.957 100 91 179 1 1109996608 83903536 18015600
05:17:23 1054 8192000000 10005519200 10373406720 1032495952 11.192 100 95 172 0 1123094880 72727568 17871360
05:18:25 1045 8192000000 10021132032 10394419200 1053742752 11.397 100 96 189 2 1147771728 74467376 19561600
05:19:27 1038 8192000000 10033629600 10410655744 1061006480 11.466 100 93 182 1 1160224160 80281440 18936240
05:20:30 4745 8192000000 10014026880 10391904256 1067247328 11.526 100 79 153 0 1146801168 64708320 14845520
05:21:36 960 8192000000 10064580704 10447781888 1076544896 11.615 100 95 184 2 1186766112 90424432 19796784
05:22:38 6810 8192000000 10013291440 10411061248 1080797984 11.655 100 61 110 2 1147332944 55562608 10972352
05:23:46 3471 8192000000 10040085120 10433753088 1087799728 11.722 100 64 120 2 1163305152 63613744 11891680
05:24:51 765 8192000000 10082712720 10472595456 1096251952 11.802 100 97 183 0 1202960672 87253472 19455248
05:25:53 1100 8192000000 10077768448 10467434496 1098520336 11.824 100 97 192 1 1201752416 84397536 18834544
05:26:55 8338 8192000000 10054861664 10453401600 1094526736 11.786 100 53 102 0 1181277248 76751856 9998656
05:28:06 4748 8192000000 10028993728 10427154432 1082180208 11.668 99 74 143 1 1165128640 68870368 14078064
05:29:11 1403 8192000000 10039891216 10427244544 1090869424 11.751 100 84 155 1 1164795360 58316944 15608992
05:30:14 1199 8192000000 10068478544 10451603456 1077556752 11.624 100 96 186 2 1189216144 92805344 18854048
05:31:16 1038 8192000000 10054835904 10439520256 1081513648 11.662 100 96 183 1 1177683600 76496672 19673280
05:32:18 1256 8192000000 10011135744 10389073920 1058464256 11.442 100 91 175 2 1134675024 57580400 18630368
05:33:24 1234 8192000000 10029708416 10411765760 1058026032 11.438 100 84 165 1 1155771088 79942096 17802960
05:34:26 968 8192000000 9998728128 10378715136 1030461568 11.173 98 78 154 0 1119542352 72226528 16854256
05:35:28 2185 8192000000 10006025312 10386698240 1051910544 11.379 100 83 157 2 1128186992 60063584 16212864
05:36:32 1005 8192000000 10018705456 10401779712 1055047888 11.409 100 94 186 0 1143086928 68602512 19436528
05:37:38 25737 8192000000 9993410320 10379366400 1061899616 11.475 100 55 107 0 1110621904 38721792 10000496
05:39:10 2984 8192000000 10020908976 10406535168 1086242336 11.707 100 72 132 3 1145593376 45734032 13617008
05:40:19 19100 8192000000 10057473248 10456694784 1101671440 11.853 100 63 124 1 1178606752 63149552 13785760
05:41:50 4901 8192000000 10101770720 10492866560 1108061680 11.914 100 63 117 2 1223737520 102682096 12993744
05:43:13 11151 8192000000 10131739584 10527875072 1116573776 11.995 99 67 125 3 1248560608 117448240 14538592
05:44:27 5550 8192000000 10180777520 10581483520 1131236736 12.133 100 62 120 1 1297880400 153033104 13610560
05:45:45 2758 8192000000 10229273968 10625867776 1145589200 12.268 100 79 151 0 1343084576 180330416 17164960
05:46:53 1020 8192000000 10272020720 10674147328 1155839392 12.364 100 96 183 5 1391493904 214665344 20989168
05:48:06 2129 8192000000 10288397616 10699730944 1168114240 12.479 100 82 160 1 1405403808 218793808 18495760
05:49:12 1167 8192000000 10327845024 10728583168 1178524640 12.576 100 95 178 4 1444973680 245591440 20857600
05:50:17 2322 8192000000 10331375696 10748772352 1183399536 12.622 100 84 159 1 1445107696 242688080 19020080
05:51:22 1279 8192000000 10344465344 10784083968 1183086000 12.619 100 91 175 0 1455469120 251011504 21371616
05:52:27 1536 8192000000 10357841072 10780442624 1188522016 12.670 100 87 161 0 1471798720 265174176 18102528
05:53:35 1004 8192000000 10397883168 10817421312 1192862992 12.710 100 96 186 2 1509489296 293521472 23104832
05:54:40 799 8192000000 10416116048 10848485376 1200185504 12.778 100 99 192 0 1523218368 301470192 21562672
05:55:43 925 8192000000 10456373680 10883203072 1206012992 12.832 100 99 193 1 1567899904 339371008 22515904
05:56:48 1328 8192000000 10487132832 10898817024 1201006240 12.786 100 87 172 0 1591047872 369717568 20324064
05:57:58 951 8192000000 10502530272 10923843584 1211543040 12.883 100 97 185 2 1609652144 374534176 23574928
05:59:05 666 8192000000 10498467120 10932895744 1199774256 12.774 100 90 171 2 1602027168 382554304 19698608
06:00:09 980 8192000000 10541660160 10973351936 1202801696 12.802 100 94 185 0 1648087680 422284592 23001392
06:01:14 1411 8192000000 10507596064 10928001024 1190539984 12.688 100 91 167 1 1617119040 406013360 20565696
06:02:17 825 8192000000 10529264432 10980405248 1183220288 12.620 100 96 189 2 1637452512 430998448 23233776
06:03:22 914 8192000000 10483353328 10931499008 1170043312 12.497 99 84 168 1 1590237632 399330304 20864016
06:04:26 7564 8192000000 10477200048 10958319616 1182700608 12.615 100 56 112 0 1581944912 385277936 13966368
06:05:40 11306 8192000000 10425075200 10903314432 1185460688 12.641 100 41 82 1 1556343520 359375760 11507072
06:06:54 26097 8192000000 10438907056 10906480640 1172941600 12.524 99 36 72 0 1535539024 353263728 9333696
06:08:36 14880 8192000000 10387117712 10851639296 1179158608 12.582 100 28 56 0 1484812896 298765664 6888624
06:09:54 60394 8192000000 10239160368 10676609024 1117437328 12.003 96 26 50 1 1319292096 195712768 6142000
06:11:55 27032 8192000000 10211332080 10738864128 1144538608 12.258 100 22 41 1 1304424960 154720032 5166320
06:13:24 45712 8192000000 10050578480 10514792448 1110739936 11.939 98 13 26 0 1150370496 37027488 2603072
06:15:15 58873 8192000000 10093794864 10535563264 1108418544 11.917 97 25 51 1 1179115920 65785680 4911696
06:17:16 67230 8192000000 10126451760 10599489536 1149494608 12.305 100 11 22 0 1231696224 80145296 2056320
06:19:25 97821 8192000000 10085263136 10603696128 1113895696 11.969 97 9 18 0 1192996608 77125920 1974992
06:22:13 173063 8192000000 10107812256 10548207616 1091653440 11.758 95 28 50 2 1164913824 67476736 5783648
06:26:07 115412 8192000000 10124174800 10587852800 1056851120 11.426 93 24 44 0 1137212912 75336704 5025088
06:29:32 49259 8192000000 10163237024 10625216512 1142439536 12.238 100 42 80 1 1276828032 125324720 9063776
06:31:29 243693 8192000000 10184637776 10626637824 1095175056 11.792 96 14 28 0 1183126416 84905296 3046064
06:37:12 49384 8192000000 10139858576 10637123584 1122475264 12.050 99 20 38 0 1276326928 149361632 4490032
06:39:03 85929 8192000000 10103590096 10562232320 1127252096 12.095 98 22 42 0 1178137200 46744992 4140112
06:41:30 18844 8192000000 10121917536 10552926208 1143918544 12.252 100 60 118 0 1212820176 56821424 12080208
06:43:00 21458 8192000000 10129053408 10562023424 1147226480 12.283 100 56 110 1 1209193328 51002080 10964768
06:44:25 11268 8192000000 10064042992 10497732608 1122903856 12.054 98 24 46 1 1151484352 24095200 4485296
06:45:37 7465 8192000000 10110742880 10539884544 1137785232 12.195 100 51 102 0 1195410800 47299600 10325968
06:46:46 76655 8192000000 10140043392 10583650304 1127530800 12.098 98 23 47 0 1219203440 86940736 4731904
06:49:20 75484 8192000000 10117713424 10558894080 1137699264 12.194 100 39 76 1 1200403520 54947200 7757056
06:51:38 57005 8192000000 10081087520 10526724096 1119455968 12.022 98 31 60 1 1164728096 39241408 6030720
06:53:38 91638 8192000000 10059744880 10518056960 1090952560 11.752 96 13 26 1 1128790992 35130496 2707936
06:56:17 53054 8192000000 10087069792 10536013824 1111038704 11.942 98 13 24 0 1159459328 45928224 2492400
06:58:29 66511 8192000000 10085788720 10567032832 1107212416 11.906 98 12 24 0 1146875456 37109664 2553376
07:00:38 69340 8192000000 10079283568 10545577984 1124316912 12.068 99 11 22 0 1204008416 77131408 2560096
07:02:53 33958 8192000000 10054833840 10493734912 1088898560 11.732 97 35 70 0 1163304272 67197840 7207872
07:04:35 43381 8192000000 10094537696 10553040896 1106157312 11.896 99 25 50 1 1170807136 59605120 5044704
07:06:19 26725 8192000000 10147463584 10591555584 1125431168 12.078 100 47 94 0 1225683264 90399808 9852288
07:07:46 61121 8192000000 10110744416 10581516288 1106385552 11.898 98 27 52 0 1191317792 79751728 5180512
07:09:53 61786 8192000000 10072692576 10518016000 1081070048 11.658 96 36 72 1 1132625696 44985344 6570304
07:11:56 39000 8192000000 10092610992 10537598976 1097452816 11.813 97 23 44 0 1147543024 45899424 4190784
07:14:18 109947 8192000000 10084077056 10529800192 1103181568 11.868 98 39 76 2 1129782528 18441216 8159744
07:17:09 49504 8192000000 10074964736 10523885568 1116832080 11.997 99 24 41 1 1177982944 56160080 4990784
07:19:28 64494 8192000000 10103672912 10577395712 1116024416 11.989 99 14 28 1 1182461600 62991520 3445664
07:21:32 82463 8192000000 10072954848 10543738880 1110292112 11.935 98 11 22 0 1184961968 72118704 2551152
07:24:00 46559 8192000000 10095681440 10538864640 1124475152 12.069 99 25 50 0 1191154528 60822688 5856688
07:26:02 30727 8192000000 10132100288 10561531904 1113950832 11.970 98 34 68 0 1203915728 82122992 7841904
07:27:44 50470 8192000000 10167983536 10602606592 1131430560 12.135 99 36 72 0 1274756272 134865424 8460288
07:29:45 46542 8192000000 10207851120 10652069888 1136115040 12.179 99 29 56 0 1291612400 149361904 6135456
07:31:54 79475 8192000000 10144039776 10632253440 1130155216 12.123 99 12 24 0 1256342640 123429600 2757824
07:34:14 90058 8192000000 10095072832 10540417024 1125518112 12.079 99 33 66 1 1168106752 35838512 6750128
07:36:44 63502 8192000000 9568438064 9982582784 670077344 7.561 59 6 12 0 709628592 38622608 928640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-07-2022 07:40:01 to 12-07-2022 07:40:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 72 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-07-2022 07:40:18 to 12-07-2022 07:40:20.

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          218368
MON$OLDEST_ACTIVE               218701
MON$OLDEST_SNAPSHOT             218701
MON$NEXT_TRANSACTION            218701
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                       2062048
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.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 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    Tue Jul 12 07:38:04 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'

12.07.22 07:40:21. 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-12 07:40:24 No severe PSQL-related problems occured
12.07.22 07:40:25. End of report.