Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.10.2022 07:47
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 15194868 kB
MemAvailable 30522840 kB
Buffers 0 kB
Cached 16510452 kB
SwapCached 2120 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.3
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-10-11 07:46:36
DTS_END                         2022-10-11 07:46:36.2580
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/60718
TRN_ID                          219964
ATT_ID                          664
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: 8861.35 24729 1063362
customer order: creation 172.33 480 20679
customer order: refuse 14.78 489 1774
order to supplier: creation 46.24 1411 5549
order to supplier: removal 3.39 164479 407
invoice (draft): creation 45.55 3518 5466
invoice (draft): removal 48.68 1081 5841
invoice accept: apply 42.53 27690 5103
invoice accept: cancel 43.09 1462 5171
customer reserve: creation 3921.42 261 470570
customer reserve: removal 4228.37 103 507404
realization accept: apply 84.84 308 10181
realization accept: cancel 79.14 175 9497
payment from customer: creation 59.58 195 7150
payment from customer: removal 5.69 154 683
payment to supplier: creation 49.66 221 5959
payment to supplier: removal 3.48 121 417
service: total inventory turnovers 3.69 4828 443
service: total monetary turnovers 8.01 425 961
service: aggregate perf. data 0.86 19144 103
service: refresh index statistics 0.03 268025 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-10-2022 07:47:56 to 11-10-2022 07:47:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5926
TEST_TIME 2 6752
TEST_TIME 3 6034
TEST_TIME 4 6025
TEST_TIME 5 6483
TEST_TIME 6 6699
TEST_TIME 7 6698
TEST_TIME 8 6695
TEST_TIME 9 7049
TEST_TIME 10 7126
TEST_TIME 11 6861
TEST_TIME 12 6824
TEST_TIME 13 7009
TEST_TIME 14 7040
TEST_TIME 15 7125
TEST_TIME 16 7265
TEST_TIME 17 7305
TEST_TIME 18 7434
TEST_TIME 19 7614
TEST_TIME 20 7668
TEST_TIME 21 7836
TEST_TIME 22 8071
TEST_TIME 23 8087
TEST_TIME 24 8280
TEST_TIME 25 8409
TEST_TIME 26 8606
TEST_TIME 27 8522
TEST_TIME 28 8549
TEST_TIME 29 8679
TEST_TIME 30 8724
TEST_TIME 31 8646
TEST_TIME 32 9173
TEST_TIME 33 9420
TEST_TIME 34 9318
TEST_TIME 35 9550
TEST_TIME 36 9458
TEST_TIME 37 10140
TEST_TIME 38 10282
TEST_TIME 39 10307
TEST_TIME 40 10277
TEST_TIME 41 10412
TEST_TIME 42 10281
TEST_TIME 43 10139
TEST_TIME 44 9974
TEST_TIME 45 9868
TEST_TIME 46 9709
TEST_TIME 47 9702
TEST_TIME 48 9797
TEST_TIME 49 9867
TEST_TIME 50 9736
TEST_TIME 51 9677
TEST_TIME 52 9675
TEST_TIME 53 9606
TEST_TIME 54 9589
TEST_TIME 55 9648
TEST_TIME 56 9585
TEST_TIME 57 9602
TEST_TIME 58 9508
TEST_TIME 59 9402
TEST_TIME 60 9399
TEST_TIME 61 9291
TEST_TIME 62 9346
TEST_TIME 63 9302
TEST_TIME 64 9234
TEST_TIME 65 9189
TEST_TIME 66 9103
TEST_TIME 67 9108
TEST_TIME 68 9183
TEST_TIME 69 9141
TEST_TIME 70 9124
TEST_TIME 71 9169
TEST_TIME 72 9224
TEST_TIME 73 9228
TEST_TIME 74 9223
TEST_TIME 75 9279
TEST_TIME 76 9273
TEST_TIME 77 9242
TEST_TIME 78 9265
TEST_TIME 79 9355
TEST_TIME 80 9311
TEST_TIME 81 9250
TEST_TIME 82 9308
TEST_TIME 83 9230
TEST_TIME 84 9256
TEST_TIME 85 9246
TEST_TIME 86 9218
TEST_TIME 87 9171
TEST_TIME 88 9114
TEST_TIME 89 9099
TEST_TIME 90 9037
TEST_TIME 91 9003
TEST_TIME 92 8965
TEST_TIME 93 8957
TEST_TIME 94 8985
TEST_TIME 95 8946
TEST_TIME 96 8946
TEST_TIME 97 8954
TEST_TIME 98 8970
TEST_TIME 99 8963
TEST_TIME 100 8986
TEST_TIME 101 9052
TEST_TIME 102 9108
TEST_TIME 103 9075
TEST_TIME 104 9087
TEST_TIME 105 9119
TEST_TIME 106 9209
TEST_TIME 107 9189
TEST_TIME 108 9121
TEST_TIME 109 9085
TEST_TIME 110 9072
TEST_TIME 111 9036
TEST_TIME 112 8994
TEST_TIME 113 8944
TEST_TIME 114 8911
TEST_TIME 115 8887
TEST_TIME 116 8844
TEST_TIME 117 8875
TEST_TIME 118 8919
TEST_TIME 119 8902
TEST_TIME 120 8886

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-10-2022 07:47:59 to 11-10-2022 07:48:03.

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 1494970 1494970 0 0.00 0 19501 15 0 0
doc_list_biud 1494963 1494963 0 0.00 0 62799 42 0 0
sp_add_invoice_to_stock 5233 5103 130 2.48 23 183494 27691 0 130
sp_cancel_adding_invoice 5171 5171 0 0.00 6 119041 1463 0 0
sp_cancel_client_order 1774 1774 0 0.00 6 26515 490 0 0
sp_cancel_customer_reserve 507404 507404 0 0.00 1 62828 104 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 8732 155 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 2134 121 0 0
sp_cancel_supplier_invoice 5847 5841 6 0.10 3 24622 1081 0 6
sp_cancel_supplier_order 407 407 0 0.00 18 1534316 164479 0 0
sp_cancel_write_off 9497 9497 0 0.00 2 9304 175 0 0
sp_client_order 20679 20679 0 0.00 5 16272 480 0 0
sp_customer_reserve 470570 470570 0 0.00 0 97635 262 0 0
sp_fill_shopping_cart 31680 31680 0 0.00 1 17656 94 0 0
sp_fill_shopping_cart_clo_res 470539 470539 0 0.00 0 16617 19 0 0
sp_get_clo_for_invoice 5062 5062 0 0.00 0 42507 309 0 0
sp_kill_cost_storno 15768 15768 0 0.00 0 11177 84 0 0
sp_kill_qstorno_ret_qs2qd 1030553 1030553 0 0.00 0 62757 45 0 0
sp_kill_qty_storno 545336 545336 0 0.00 0 62795 106 0 0
sp_lock_selected_doc 21107 21107 0 0.00 0 9869 9 0 0
sp_make_cost_storno 28351 28351 0 0.00 0 17005 91 0 0
sp_multiply_rows_for_pdistr 28351 28351 0 0.00 0 10645 42 0 0
sp_multiply_rows_for_qdistr 20679 20679 0 0.00 0 16235 180 0 0
sp_pay_from_customer 7150 7150 0 0.00 5 17161 195 0 0
sp_pay_to_supplier 5959 5959 0 0.00 6 19217 221 0 0
sp_payment_common 13109 13109 0 0.00 2 19209 53 0 0
sp_qd_handle_on_cancel_clo 1774 1774 0 0.00 1 26508 408 0 0
sp_qd_handle_on_invoice_upd_sts 10232 10232 0 0.00 0 22066 599 0 0
sp_qd_handle_on_reserve_upd_sts 10181 10181 0 0.00 0 11490 66 0 0
sp_reserve_write_off 10181 10181 0 0.00 9 19523 308 0 0
sp_supplier_invoice 5466 5466 0 0.00 9 108452 3519 0 0
sp_supplier_order 5549 5549 0 0.00 7 27312 1412 0 0
srv_aggregate_perf_data 103 103 0 0.00 2028 230415 19145 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 699 165414 31365 0 0
srv_find_qd_qs_mism 1013061 1013061 0 0.00 0 12007 11 0 0
srv_make_invnt_saldo 443 443 0 0.00 43 44886 4829 0 0
srv_make_money_saldo 961 961 0 0.00 1 15186 426 0 0
srv_recalc_idx_stat 4 4 0 0.00 182310 439126 268025 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 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 5109 5109 0 0.00 0 16771 1131 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 1 9397 562 0 0
t$perf-norm:sp_cancel_client_order 1774 1774 0 0.00 0 1434 32 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 2300 45 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 945 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 1982 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 452 452 0 0.00 0 1558 53 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 1 126707 7342 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 1941 24 0 0
t$perf-norm:sp_client_order 20679 20679 0 0.00 0 15870 43 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 6976 30 0 0
t$perf-norm:sp_pay_from_customer 7150 7150 0 0.00 0 3877 21 0 0
t$perf-norm:sp_pay_to_supplier 5959 5959 0 0.00 0 2748 20 0 0
t$perf-norm:sp_reserve_write_off 10181 10181 0 0.00 0 6919 27 0 0
t$perf-norm:sp_supplier_invoice 5466 5466 0 0.00 0 2656 37 0 0
t$perf-norm:sp_supplier_order 5549 5549 0 0.00 0 5012 35 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 1797 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 2113 42 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 968 15 0 0
t$perf-norm:srv_make_money_saldo 961 961 0 0.00 0 1249 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 183 69 0 0
v_add_invoice_to_stock 10373 10113 260 2.50 0 4446 29 0 260
v_all_customers 38078 38078 0 0.00 0 81 4 0 0
v_all_suppliers 30562 30562 0 0.00 0 181 5 0 0
v_all_wares 41356 41356 0 0.00 0 180 5 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 736 16 0 0
v_cancel_client_order 3548 3548 0 0.00 0 3512 12 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 959 13 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 4140 18 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 1021 12 0 0
v_cancel_supplier_invoice 916 904 12 1.31 0 1284 27 0 12
v_cancel_supplier_order 814 814 0 0.00 0 636 13 0 0
v_cancel_write_off 1994 1994 0 0.00 0 5105 33 0 0
v_max_id_clo_ord 5545 5545 0 0.00 0 5331 7 0 0
v_max_id_clo_res 944 944 0 0.00 0 2528 20 0 0
v_max_id_ord_sup 5455 5455 0 0.00 0 3801 7 0 0
v_max_non_paid_invoice 1677 1677 0 0.00 0 2449 10 0 0
v_max_non_paid_realizn 3770 3770 0 0.00 0 764 9 0 0
v_min_id_clo_ord 5545 5545 0 0.00 0 2581 11 0 0
v_min_id_clo_res 944 944 0 0.00 0 172 8 0 0
v_min_id_ord_sup 5455 5455 0 0.00 0 6997 14 0 0
v_min_non_paid_invoice 5959 5959 0 0.00 0 19204 25 0 0
v_min_non_paid_realizn 7150 7150 0 0.00 0 8225 18 0 0
v_our_firm 10036 10036 0 0.00 0 82 5 0 0
v_reserve_write_off 20362 20362 0 0.00 0 11835 13 0 0
x_lock_depdocs_on_canc_invoice 10560 10560 0 0.00 0 16292 138 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 18169 465 0 0
x_make_qstorno_1000_1200 5549 5549 0 0.00 3 27298 1210 0 0
x_make_qstorno_1200_2000 5466 5466 0 0.00 4 108180 3267 0 0
x_make_qstorno_2100_3300 470570 470570 0 0.00 0 97632 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-10-2022 07:48:04 to 11-10-2022 07:48:08.

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:16:40 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:17:42 27356 8192000000 9719183648 10014695424 792563328 8.821 100 93 184 0 822360352 7377520 22419504
05:19:13 19435 8192000000 9748538048 10053951488 825686608 9.156 100 91 182 0 859862688 12028848 22147232
05:20:46 15438 8192000000 9791350096 10109095936 856832960 9.468 100 90 178 0 902773200 25270672 20669568
05:22:09 8752 8192000000 9839394720 10175733760 910352304 10.001 100 75 150 0 958058352 31221392 16484656
05:23:23 4170 8192000000 9899559664 10241228800 951304736 10.404 100 84 163 0 1017947232 48454912 18187584
05:24:31 2895 8192000000 9960375936 10308083712 990183760 10.783 100 93 186 0 1071462400 62271072 19007568
05:25:38 2477 8192000000 9977709024 10334531584 1003251184 10.910 100 90 173 2 1094983584 74811664 16920736
05:26:43 890 8192000000 10013331344 10377166848 1027488608 11.144 100 92 176 0 1133555504 88545200 17521696
05:27:46 1467 8192000000 10005014784 10374246400 1045765744 11.320 100 96 179 3 1120583696 57272064 17545888
05:28:48 871 8192000000 10047786352 10420731904 1056819728 11.426 100 97 187 0 1168003952 92193296 18990928
05:29:51 2190 8192000000 10042803552 10420174848 1063740912 11.492 100 89 171 1 1165371504 84906256 16724336
05:30:55 898 8192000000 10050123936 10437136384 1069415808 11.547 100 90 176 0 1172659504 85429792 17813904
05:31:57 989 8192000000 10046322496 10429337600 1073740304 11.588 100 95 185 0 1169580512 76686848 19153360
05:33:00 1624 8192000000 10034848256 10424438784 1084166768 11.687 100 91 171 3 1157510400 55421376 17922256
05:34:02 1153 8192000000 10058603280 10444378112 1084328576 11.689 100 95 181 0 1181104864 77877312 18898976
05:35:05 1206 8192000000 10047992112 10435833856 1085447808 11.699 100 90 161 1 1162690576 62189488 15053280
05:36:07 1243 8192000000 10066867504 10454585344 1093719024 11.778 100 91 176 1 1186039040 72933120 19386896
05:37:09 977 8192000000 10041922720 10431729664 1097578368 11.815 100 91 171 1 1168838736 54118112 17142256
05:38:11 2039 8192000000 10046212704 10437070848 1091878912 11.761 100 81 151 1 1173036816 65984048 15173856
05:39:16 3216 8192000000 10028956464 10429911040 1085282848 11.698 100 69 137 1 1152155856 53788032 13084976
05:40:20 1104 8192000000 10029569824 10410643456 1066316720 11.517 100 95 177 1 1152513632 67542704 18654208
05:41:21 1223 8192000000 10053911728 10439335936 1075726048 11.607 100 95 184 1 1175037440 81271360 18040032
05:42:24 938 8192000000 10038107632 10422374400 1076445696 11.614 100 89 176 0 1161391968 67369520 17576752
05:43:25 2102 8192000000 9951231072 10319179776 1012360528 10.998 99 79 153 0 1069323888 40812208 16151152
05:44:28 2081 8192000000 10030022640 10408747008 1050200176 11.363 100 87 162 1 1151503568 86229200 15074192
05:45:31 902 8192000000 9977692816 10353446912 1038732928 11.252 100 90 171 4 1102109760 44574240 18802592
05:46:33 1149 8192000000 9987479792 10365145088 1043892112 11.302 100 94 173 2 1114828048 50107952 20827984
05:47:41 2197 8192000000 10013278656 10387136512 1047018160 11.332 100 80 155 1 1132723424 70139040 15566224
05:48:51 6581 8192000000 10027595616 10410004480 1067981584 11.533 100 69 124 3 1148729744 68588208 12159952
05:50:05 2261 8192000000 10051969696 10428399616 1066563120 11.519 99 77 145 3 1168630368 87252560 14814688
05:51:14 1066 8192000000 10109138944 10496360448 1093817744 11.779 100 98 188 1 1227011424 113175296 20018384
05:52:19 11728 8192000000 10123171520 10513154048 1111991840 11.951 100 79 152 2 1242931472 115050960 15888672
05:53:35 5868 8192000000 10144257664 10540711936 1100146288 11.839 99 67 130 0 1250823664 136888016 13789360
05:54:49 6474 8192000000 10141716416 10533785600 1114636592 11.976 100 76 146 2 1260724160 130908912 15178656
05:55:58 1017 8192000000 10157103872 10549211136 1118331712 12.011 100 94 171 3 1271665856 134833056 18501088
05:57:03 3722 8192000000 10170513088 10566606848 1127628976 12.099 100 69 131 0 1275436816 134279808 13528032
05:58:11 1328 8192000000 10209537840 10606841856 1149743520 12.307 100 92 173 0 1328946944 159160112 20043312
05:59:16 689 8192000000 10269419216 10670120960 1160634560 12.409 100 94 181 0 1379075568 199642112 18798896
06:00:21 1128 8192000000 10293054032 10689466368 1167430480 12.473 100 96 188 0 1398646352 210934464 20281408
06:01:27 2472 8192000000 10342545696 10744549376 1168280080 12.481 100 94 179 0 1446498400 257659472 20558848
06:02:32 1579 8192000000 10347330096 10757427200 1168546384 12.483 100 92 181 1 1453015760 263773616 20695760
06:03:38 1838 8192000000 10340720608 10753536000 1160651968 12.409 100 90 175 0 1445084304 265139104 19293232
06:04:47 974 8192000000 10367430720 10776809472 1168679664 12.484 100 97 187 3 1477366640 287055040 21631936
06:05:53 1123 8192000000 10339572464 10777616384 1179299744 12.584 100 91 174 0 1444224704 245450880 19474080
06:07:00 1119 8192000000 10332068992 10769301504 1165513520 12.455 100 95 180 0 1437459120 251734704 20210896
06:08:05 956 8192000000 10369852320 10781978624 1174434784 12.538 100 99 196 1 1474754736 277317344 23002608
06:09:10 833 8192000000 10394447920 10809581568 1184711600 12.634 100 99 192 1 1501971872 294808768 22451504
06:10:13 1350 8192000000 10401221056 10811473920 1169096528 12.488 100 92 181 1 1506345984 314795712 22453744
06:11:16 853 8192000000 10414894320 10843414528 1180673056 12.596 100 80 161 0 1522949408 322261664 20014688
06:12:22 1489 8192000000 10404174448 10823688192 1181056096 12.600 100 89 176 1 1512617360 310799760 20761504
06:13:25 1454 8192000000 10392122096 10833485824 1175750400 12.551 100 79 154 1 1493879024 300098512 18030112
06:14:30 1655 8192000000 10446467200 10877915136 1186835888 12.654 100 96 187 1 1549310624 341044144 21430592
06:15:34 1398 8192000000 10489347056 10899046400 1176202880 12.555 100 93 184 0 1596049616 398174496 21672240
06:16:38 7687 8192000000 10460604736 10921304064 1175282784 12.546 100 47 94 0 1571957776 383452624 13222368
06:17:59 7913 8192000000 10498273520 10958299136 1173773920 12.532 100 61 113 0 1603339264 415006352 14558992
06:19:22 4305 8192000000 10390780192 10849341440 1166701088 12.466 99 51 97 0 1494601600 316471632 11428880
06:20:30 16514 8192000000 10363116336 10837827584 1171570304 12.512 100 35 70 1 1472682848 292916448 8196096
06:21:52 17911 8192000000 10330597808 10771525632 1149413280 12.304 100 21 40 0 1435901184 281068400 5419504
06:23:11 26031 8192000000 10150609648 10626727936 1130420816 12.125 99 29 53 0 1303156816 165490048 7245952
06:24:46 38916 8192000000 10094494160 10539188224 1115460224 11.984 98 40 74 0 1171147392 48698496 6988672
06:26:26 48307 8192000000 10094514480 10536992768 1097800128 11.817 97 18 37 2 1173911616 72812352 3299136
06:28:15 61969 8192000000 10085973216 10517458944 1140062176 12.216 100 31 60 0 1178468496 32567664 5838656
06:30:21 148425 8192000000 10184421552 10634715136 1129513504 12.117 98 29 58 0 1232309856 96269632 6526720
06:33:54 66799 8192000000 10144306656 10606878720 1136740080 12.185 100 22 44 0 1275068976 133443920 4884976
06:36:40 21847 8192000000 10068194496 10502885376 1116782096 11.997 98 47 82 0 1151810704 26134080 8894528
06:38:04 26968 8192000000 10130448256 10567438336 1128495328 12.107 99 40 78 0 1227857440 90896704 8465408
06:39:34 23789 8192000000 10135478240 10577870848 1136170704 12.179 100 38 74 0 1245566032 101469088 7926240
06:41:02 36672 8192000000 10104056624 10576814080 1143803072 12.251 100 30 57 1 1222833600 72447712 6582816
06:42:46 69944 8192000000 10065813072 10546147328 1130516544 12.126 99 18 34 0 1170984464 36837680 3630240
06:44:57 59248 8192000000 10101478400 10554286080 1130743440 12.128 99 14 28 0 1185041808 51084624 3213744
06:47:18 28621 8192000000 10082160832 10534596608 1130230960 12.124 100 24 45 0 1192702464 57110048 5361456
06:48:47 75098 8192000000 10071278592 10553532416 1137495280 12.192 100 10 20 0 1167627584 27736368 2395936
06:51:08 54585 8192000000 10050134144 10513719296 1128041776 12.103 99 5 10 0 1155866592 27114000 710816
06:53:08 49080 8192000000 10122842192 10623348736 1144030448 12.253 100 36 73 0 1241935600 89289840 8615312
06:55:00 19102 8192000000 10080923296 10528075776 1118727632 12.015 98 31 62 1 1174438640 48757776 6953232
06:56:22 16398 8192000000 10125458896 10567503872 1130140624 12.123 100 45 90 0 1212377856 72750672 9486560
06:57:42 47887 8192000000 10142847856 10598621184 1134836928 12.167 99 31 57 0 1241411184 100833440 5740816
06:59:47 58650 8192000000 10113180928 10557849600 1143428656 12.248 100 18 36 0 1210190112 63289984 3471472
07:01:47 17011 8192000000 10104866288 10532548608 1119958416 12.027 98 50 100 0 1172604240 43002464 9643360
07:03:06 38177 8192000000 10161445984 10591047680 1143003760 12.244 100 42 82 0 1247187824 95590864 8593200
07:04:51 64138 8192000000 10098960208 10546814976 1118156528 12.010 98 20 40 0 1198433600 76416672 3860400
07:06:56 165414 8192000000 10108585568 10570833920 1118738784 12.015 98 18 36 0 1201489344 78946944 3803616
07:10:42 70540 8192000000 10066401440 10569691136 1124508576 12.070 99 20 40 0 1217853536 89270720 4074240
07:12:55 63114 8192000000 10038269456 10480758784 1103834176 11.874 98 24 48 0 1137739280 28276960 5628144
07:14:59 69945 8192000000 10018680016 10489384960 1082873936 11.675 97 20 40 0 1118313520 31406960 4032624
07:17:13 32663 8192000000 10058793648 10509774848 1109131248 11.924 99 27 53 0 1164192080 49218336 5842496
07:18:50 61152 8192000000 10070545008 10550112256 1085377136 11.699 97 13 26 0 1169624944 81476352 2771456
07:21:09 6711 8192000000 10038614000 10471948288 1110449520 11.937 99 68 120 2 1143249440 18969968 13829952
07:22:16 12560 8192000000 10101622400 10536443904 1122321920 12.049 100 45 90 0 1209260848 77356400 9582528
07:23:29 44799 8192000000 10103086144 10551099392 1116547344 11.994 99 26 52 1 1203115904 81457472 5111088
07:25:14 38365 8192000000 10129047776 10574704640 1110989792 11.942 99 39 75 0 1208474400 89816096 7668512
07:27:02 24607 8192000000 10155315920 10589106176 1123989312 12.065 99 55 108 1 1247049776 111129872 11930592
07:28:27 61393 8192000000 10105964160 10594996224 1095369600 11.794 97 22 44 1 1164790768 64974416 4446752
07:30:33 40197 8192000000 10156098448 10619412480 1120140944 12.028 99 53 104 0 1238510880 107626512 10743424
07:32:15 123104 8192000000 10066228384 10584850432 1109172304 11.925 98 21 42 0 1202711824 89503760 4035760
07:35:19 15576 8192000000 10053777168 10486853632 1118548960 12.013 99 55 106 0 1156334400 26609056 11176384
07:36:37 50116 8192000000 10030979920 10487386112 1077746560 11.626 96 9 18 0 1115528848 36165712 1616576
07:39:35 74334 8192000000 10107814576 10571177984 1122573712 12.051 99 24 48 0 1216562080 88941904 5046464
07:41:56 75790 8192000000 10119713728 10604335104 1108257232 11.916 98 10 20 0 1211895072 101373632 2264208
07:44:16 45378 8192000000 10068491120 10509484032 1105240720 11.887 98 16 30 0 1147449904 38821840 3387344
07:46:02 29863 8192000000 10114139168 10572623872 1138188768 12.198 100 36 72 0 1221252352 75569712 7493872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-10-2022 07:48:10 to 11-10-2022 07:48:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 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 11-10-2022 07:48:27 to 11-10-2022 07:48:29.

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          217333
MON$OLDEST_ACTIVE               221214
MON$OLDEST_SNAPSHOT             221214
MON$NEXT_TRANSACTION            221214
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                       2068384
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2858 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2858 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2858 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2858 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Oct 11 07:46:46 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

11.10.22 07:48:30. 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-10-11 07:48:33 No severe PSQL-related problems occured
11.10.22 07:48:34. End of report.