Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.03.2022 13: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 16435244 kB
MemAvailable 31667248 kB
Buffers 0 kB
Cached 15304512 kB
SwapCached 2140 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-03-26 13:37:15
DTS_END                         2022-03-26 13:37:15.2680
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/41958
TRN_ID                          226587
ATT_ID                          657
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: 9014.47 26042 1081736
customer order: creation 178.18 457 21381
customer order: refuse 15.40 425 1848
order to supplier: creation 47.09 1335 5651
order to supplier: removal 3.32 172445 398
invoice (draft): creation 47.95 3231 5754
invoice (draft): removal 46.99 1091 5639
invoice accept: apply 44.45 28837 5334
invoice accept: cancel 42.78 1419 5133
customer reserve: creation 4155.48 275 498658
customer reserve: removal 4132.92 108 495950
realization accept: apply 86.35 291 10362
realization accept: cancel 80.09 191 9611
payment from customer: creation 59.67 177 7160
payment from customer: removal 6.28 111 754
payment to supplier: creation 50.89 193 6107
payment to supplier: removal 3.46 168 415
service: total inventory turnovers 4.35 4012 522
service: total monetary turnovers 7.93 392 952
service: aggregate perf. data 0.86 18513 103
service: refresh index statistics 0.03 287162 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-03-2022 13:40:00 to 26-03-2022 13:40:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3945
TEST_TIME 2 5072
TEST_TIME 3 5682
TEST_TIME 4 5948
TEST_TIME 5 6157
TEST_TIME 6 5799
TEST_TIME 7 6002
TEST_TIME 8 5931
TEST_TIME 9 6135
TEST_TIME 10 6442
TEST_TIME 11 6849
TEST_TIME 12 6951
TEST_TIME 13 7035
TEST_TIME 14 6942
TEST_TIME 15 6968
TEST_TIME 16 7208
TEST_TIME 17 7034
TEST_TIME 18 7036
TEST_TIME 19 7072
TEST_TIME 20 7393
TEST_TIME 21 7259
TEST_TIME 22 7303
TEST_TIME 23 7409
TEST_TIME 24 7793
TEST_TIME 25 8052
TEST_TIME 26 8178
TEST_TIME 27 8303
TEST_TIME 28 8203
TEST_TIME 29 8191
TEST_TIME 30 8268
TEST_TIME 31 8526
TEST_TIME 32 8659
TEST_TIME 33 8808
TEST_TIME 34 9251
TEST_TIME 35 9903
TEST_TIME 36 9933
TEST_TIME 37 10352
TEST_TIME 38 10630
TEST_TIME 39 10540
TEST_TIME 40 10422
TEST_TIME 41 10359
TEST_TIME 42 10227
TEST_TIME 43 10214
TEST_TIME 44 10212
TEST_TIME 45 10173
TEST_TIME 46 10121
TEST_TIME 47 10062
TEST_TIME 48 9882
TEST_TIME 49 9779
TEST_TIME 50 9754
TEST_TIME 51 9742
TEST_TIME 52 9813
TEST_TIME 53 9780
TEST_TIME 54 9906
TEST_TIME 55 9852
TEST_TIME 56 9735
TEST_TIME 57 9650
TEST_TIME 58 9750
TEST_TIME 59 9751
TEST_TIME 60 9688
TEST_TIME 61 9679
TEST_TIME 62 9685
TEST_TIME 63 9712
TEST_TIME 64 9738
TEST_TIME 65 9667
TEST_TIME 66 9625
TEST_TIME 67 9597
TEST_TIME 68 9730
TEST_TIME 69 9770
TEST_TIME 70 9775
TEST_TIME 71 9723
TEST_TIME 72 9645
TEST_TIME 73 9565
TEST_TIME 74 9524
TEST_TIME 75 9489
TEST_TIME 76 9483
TEST_TIME 77 9466
TEST_TIME 78 9407
TEST_TIME 79 9372
TEST_TIME 80 9351
TEST_TIME 81 9335
TEST_TIME 82 9269
TEST_TIME 83 9302
TEST_TIME 84 9368
TEST_TIME 85 9355
TEST_TIME 86 9319
TEST_TIME 87 9332
TEST_TIME 88 9391
TEST_TIME 89 9391
TEST_TIME 90 9397
TEST_TIME 91 9479
TEST_TIME 92 9438
TEST_TIME 93 9441
TEST_TIME 94 9401
TEST_TIME 95 9339
TEST_TIME 96 9302
TEST_TIME 97 9298
TEST_TIME 98 9271
TEST_TIME 99 9232
TEST_TIME 100 9180
TEST_TIME 101 9145
TEST_TIME 102 9158
TEST_TIME 103 9161
TEST_TIME 104 9144
TEST_TIME 105 9174
TEST_TIME 106 9160
TEST_TIME 107 9151
TEST_TIME 108 9143
TEST_TIME 109 9163
TEST_TIME 110 9161
TEST_TIME 111 9155
TEST_TIME 112 9162
TEST_TIME 113 9152
TEST_TIME 114 9172
TEST_TIME 115 9224
TEST_TIME 116 9170
TEST_TIME 117 9101
TEST_TIME 118 9089
TEST_TIME 119 9088
TEST_TIME 120 9022

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-03-2022 13:40:04 to 26-03-2022 13:40:07.

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 1540178 1540178 0 0.00 0 21174 16 0 0
doc_list_biud 1540170 1540170 0 0.00 0 31054 41 0 0
sp_add_invoice_to_stock 5442 5334 108 1.98 57 266049 28837 0 108
sp_cancel_adding_invoice 5133 5133 0 0.00 7 93514 1419 0 0
sp_cancel_client_order 1848 1848 0 0.00 6 9087 425 0 0
sp_cancel_customer_reserve 495950 495950 0 0.00 1 24909 108 0 0
sp_cancel_pay_from_customer 754 754 0 0.00 4 2604 111 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 4 5293 169 0 0
sp_cancel_supplier_invoice 5650 5639 11 0.19 4 20779 1091 0 11
sp_cancel_supplier_order 398 398 0 0.00 17 1711637 172446 0 0
sp_cancel_write_off 9611 9611 0 0.00 2 14420 192 0 0
sp_client_order 21381 21381 0 0.00 6 18655 458 0 0
sp_customer_reserve 498658 498658 0 0.00 1 136562 276 0 0
sp_fill_shopping_cart 32771 32771 0 0.00 2 20499 82 0 0
sp_fill_shopping_cart_clo_res 498646 498646 0 0.00 0 16825 18 0 0
sp_get_clo_for_invoice 5321 5321 0 0.00 0 9670 218 0 0
sp_kill_cost_storno 15913 15913 0 0.00 0 7920 80 0 0
sp_kill_qstorno_ret_qs2qd 1007548 1007548 0 0.00 0 24813 47 0 0
sp_kill_qty_storno 534259 534259 0 0.00 0 31053 109 0 0
sp_lock_selected_doc 21737 21737 0 0.00 0 4860 6 0 0
sp_make_cost_storno 28950 28950 0 0.00 0 16169 82 0 0
sp_multiply_rows_for_pdistr 28949 28949 0 0.00 0 10416 40 0 0
sp_multiply_rows_for_qdistr 21381 21381 0 0.00 0 10012 170 0 0
sp_pay_from_customer 7160 7160 0 0.00 5 21843 177 0 0
sp_pay_to_supplier 6107 6107 0 0.00 6 16829 194 0 0
sp_payment_common 13267 13267 0 0.00 2 21831 45 0 0
sp_qd_handle_on_cancel_clo 1848 1848 0 0.00 0 9058 345 0 0
sp_qd_handle_on_invoice_upd_sts 10453 10453 0 0.00 0 31051 552 0 0
sp_qd_handle_on_reserve_upd_sts 10360 10360 0 0.00 0 14422 74 0 0
sp_reserve_write_off 10362 10362 0 0.00 8 21182 291 0 0
sp_supplier_invoice 5754 5754 0 0.00 9 78393 3231 0 0
sp_supplier_order 5651 5651 0 0.00 8 36051 1335 0 0
srv_aggregate_perf_data 103 103 0 0.00 2797 147678 18513 0 0
srv_fill_mon_cache_memory 76 76 0 0.00 808 164584 29731 0 0
srv_find_qd_qs_mism 1030009 1030009 0 0.00 0 16866 13 0 0
srv_make_invnt_saldo 522 522 0 0.00 72 73680 4013 0 0
srv_make_money_saldo 952 952 0 0.00 1 14095 393 0 0
srv_recalc_idx_stat 4 4 0 0.00 103784 395529 287163 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5338 5338 0 0.00 1 15546 947 0 0
t$perf-norm:sp_cancel_adding_invoice 458 458 0 0.00 1 8075 494 0 0
t$perf-norm:sp_cancel_client_order 1848 1848 0 0.00 0 2532 26 0 0
t$perf-norm:sp_cancel_customer_reserve 740 740 0 0.00 0 899 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 754 754 0 0.00 0 1099 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 415 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 2265 32 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 125755 7623 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 1407 17 0 0
t$perf-norm:sp_client_order 21381 21381 0 0.00 0 6855 37 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 816 13 0 0
t$perf-norm:sp_pay_from_customer 7160 7160 0 0.00 0 6342 19 0 0
t$perf-norm:sp_pay_to_supplier 6107 6107 0 0.00 0 1911 18 0 0
t$perf-norm:sp_reserve_write_off 10362 10362 0 0.00 0 8004 27 0 0
t$perf-norm:sp_supplier_invoice 5754 5754 0 0.00 0 6507 33 0 0
t$perf-norm:sp_supplier_order 5651 5651 0 0.00 0 6049 38 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 187 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 1215 23 0 0
t$perf-norm:srv_make_invnt_saldo 522 522 0 0.00 0 1514 19 0 0
t$perf-norm:srv_make_money_saldo 952 952 0 0.00 0 542 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 217 72 0 0
v_add_invoice_to_stock 10852 10636 216 1.99 0 4676 24 0 216
v_all_customers 39064 39064 0 0.00 0 153 4 0 0
v_all_suppliers 31566 31566 0 0.00 0 153 4 0 0
v_all_wares 42751 42751 0 0.00 0 104 4 0 0
v_cancel_adding_invoice 916 916 0 0.00 0 3881 22 0 0
v_cancel_client_order 3696 3696 0 0.00 0 6354 16 0 0
v_cancel_customer_prepayment 1508 1508 0 0.00 0 2531 13 0 0
v_cancel_customer_reserve 1480 1480 0 0.00 0 4590 14 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 4551 23 0 0
v_cancel_supplier_invoice 842 820 22 2.61 0 3668 32 0 22
v_cancel_supplier_order 796 796 0 0.00 0 2383 14 0 0
v_cancel_write_off 2070 2070 0 0.00 0 1638 22 0 0
v_max_id_clo_ord 5651 5651 0 0.00 0 2837 6 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 1701 13 0 0
v_max_id_ord_sup 5742 5742 0 0.00 0 5036 8 0 0
v_max_non_paid_invoice 1716 1716 0 0.00 0 783 8 0 0
v_max_non_paid_realizn 3685 3685 0 0.00 0 866 7 0 0
v_min_id_clo_ord 5651 5651 0 0.00 0 2354 8 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 4677 19 0 0
v_min_id_ord_sup 5742 5742 0 0.00 0 20436 13 0 0
v_min_non_paid_invoice 6107 6107 0 0.00 0 5328 13 0 0
v_min_non_paid_realizn 7158 7158 0 0.00 0 21823 19 0 0
v_our_firm 10625 10625 0 0.00 0 74 5 0 0
v_reserve_write_off 20717 20717 0 0.00 0 6349 13 0 0
x_lock_depdocs_on_canc_invoice 10362 10362 0 0.00 0 11707 129 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 5205 363 0 0
x_make_qstorno_1000_1200 5651 5651 0 0.00 3 36036 1154 0 0
x_make_qstorno_1200_2000 5754 5754 0 0.00 4 74611 3016 0 0
x_make_qstorno_2100_3300 498658 498658 0 0.00 0 136557 245 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 26-03-2022 13:40:08 to 26-03-2022 13:40:13.

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:19 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:20 27850 8192000000 9725376112 10022363136 801987808 8.916 100 89 178 0 832627376 8368624 22270944
11:09:51 22367 8192000000 9764700896 10075152384 837226432 9.272 100 86 170 1 868856864 11944688 19685744
11:11:17 22097 8192000000 9812745056 10131472384 874624128 9.646 100 87 175 0 916218512 21954304 19640080
11:12:46 9978 8192000000 9858882000 10192637952 914191136 10.039 100 81 161 0 973112656 41223680 17697840
11:14:00 5025 8192000000 9925789504 10267504640 958074064 10.470 100 87 171 2 1035555536 59606864 17874608
11:15:08 2588 8192000000 9962083728 10320031744 994149008 10.822 100 80 157 1 1084101680 74697952 15254720
11:16:12 9111 8192000000 9996765104 10359648256 1024297136 11.113 100 80 152 2 1113128960 73985664 14846160
11:17:23 1489 8192000000 10027487168 10401775616 1045968544 11.322 100 97 190 2 1146096976 80531568 19596864
11:18:27 1122 8192000000 10037389744 10410258432 1062429248 11.480 100 96 187 1 1152836752 71899680 18507824
11:19:31 1422 8192000000 10040636848 10422779904 1070844800 11.560 100 90 171 0 1162624096 74331664 17447632
11:20:34 1344 8192000000 10065276896 10445791232 1079062480 11.639 100 98 196 3 1188821040 89258976 20499584
11:21:40 1474 8192000000 10050491760 10432446464 1087987296 11.724 100 98 189 2 1174470768 66867712 19615760
11:22:42 10073 8192000000 10045049664 10427629568 1085429568 11.699 100 87 172 3 1162577648 60600352 16547728
11:23:54 1177 8192000000 10063967712 10452234240 1090135456 11.744 100 93 182 3 1182579744 73886432 18557856
11:24:57 1527 8192000000 10065373536 10456084480 1095320848 11.793 100 99 184 2 1188435664 73670624 19444192
11:25:59 1133 8192000000 10074784624 10464956416 1099572240 11.834 100 97 189 1 1199184080 80873728 18738112
11:27:01 886 8192000000 10045751584 10438733824 1099320384 11.831 100 95 178 0 1170160576 51598352 19241840
11:28:04 1021 8192000000 10065336992 10452762624 1092921584 11.770 100 83 152 1 1178699808 70981376 14796848
11:29:06 4263 8192000000 10058682064 10445864960 1083452592 11.680 99 84 164 1 1178233904 77987280 16794032
11:30:12 942 8192000000 10022152576 10410586112 1083506944 11.681 100 86 160 1 1144160256 43315040 17338272
11:31:13 5441 8192000000 10022695056 10415525888 1069273936 11.545 99 64 106 1 1134713056 53611392 11827728
11:32:20 1065 8192000000 10015215408 10397892608 1061751488 11.473 100 83 164 3 1138530608 59068480 17710640
11:33:22 1429 8192000000 10014511008 10392403968 1057422784 11.432 100 95 183 0 1135491552 58287424 19781344
11:34:24 1069 8192000000 10024322160 10399879168 1034744240 11.214 100 97 188 2 1145711408 91376336 19590832
11:35:26 2181 8192000000 10018648352 10397429760 1051855024 11.378 100 68 133 1 1134590544 69656992 13078528
11:36:29 3059 8192000000 9961365552 10337394688 1037049376 11.236 100 73 121 0 1089109616 40987216 11073024
11:37:36 7654 8192000000 9947935008 10328535040 1041442368 11.279 99 65 126 0 1073252048 18895200 12914480
11:38:53 7382 8192000000 10016759888 10399883264 1056704240 11.425 100 71 129 1 1134593648 64708000 13181408
11:40:07 1700 8192000000 10051887632 10428846080 1079056688 11.638 100 92 172 1 1167586448 71369216 17160544
11:41:13 4308 8192000000 10064390560 10450907136 1086542896 11.710 100 79 150 5 1188657184 82969104 19145184
11:42:28 5212 8192000000 10088656224 10477387776 1097018336 11.809 100 80 156 0 1204912432 90885744 17008352
11:43:41 3583 8192000000 10107733728 10502914048 1107708160 11.911 100 61 115 0 1226844320 106615792 12520368
11:44:48 3676 8192000000 10159116736 10554761216 1118788480 12.016 100 89 166 0 1267671520 129304192 19578848
11:46:01 801 8192000000 10198957296 10586648576 1130096448 12.122 100 98 188 1 1315966544 166815728 19054368
11:47:06 3546 8192000000 10213201216 10621435904 1146485616 12.276 100 86 166 1 1330317120 166059936 17771568
11:48:14 875 8192000000 10237707184 10631557120 1148727920 12.298 100 96 184 1 1349620208 181310944 19581344
11:49:18 1367 8192000000 10276028768 10675986432 1160791392 12.411 100 96 185 0 1385225296 204344480 20089424
11:50:21 3835 8192000000 10269085168 10676277248 1165163520 12.452 100 71 138 0 1378797216 198791008 14842688
11:51:35 1707 8192000000 10348585584 10747695104 1172898992 12.524 100 95 184 0 1459450544 265725184 20826368
11:52:44 2698 8192000000 10368155248 10782887936 1183292304 12.621 100 83 166 0 1476730800 274375472 19063024
11:53:59 2755 8192000000 10384527296 10789187584 1188672352 12.671 100 87 165 1 1489334384 281193616 19468416
11:55:09 1763 8192000000 10440921472 10854440960 1203351648 12.807 100 86 172 0 1549449456 325891696 20206112
11:56:15 2850 8192000000 10458081504 10886156288 1199829712 12.775 100 92 179 3 1561317456 340949120 20538624
11:57:22 942 8192000000 10471205056 10899279872 1201528432 12.791 100 95 188 3 1572800912 349165984 22106496
11:58:24 992 8192000000 10503523952 10907398144 1192699312 12.708 100 98 195 0 1606255600 390919856 22636432
11:59:28 926 8192000000 10535907472 10964811776 1194000688 12.721 100 97 187 1 1643705552 425422640 24282224
12:00:32 887 8192000000 10528284672 10989010944 1199887680 12.775 100 98 188 0 1634016992 410203056 23926256
12:01:35 993 8192000000 10522744160 10985787392 1194699536 12.727 100 100 195 0 1628536528 409273264 24563728
12:02:39 1073 8192000000 10529400144 10966695936 1209174128 12.861 100 96 185 0 1631160832 400091056 21895648
12:03:47 8885 8192000000 10543193648 10979876864 1200794448 12.784 100 52 104 0 1644685520 430562960 13328112
12:05:03 10726 8192000000 10539123616 10971242496 1190910960 12.692 100 47 90 0 1637172688 435043056 11218672
12:06:18 14612 8192000000 10558757904 11010232320 1193012928 12.711 100 41 79 0 1658507920 454993504 10501488
12:07:38 4193 8192000000 10557078096 10993688576 1169806880 12.495 100 46 88 0 1661625600 480484992 11333728
12:08:46 16062 8192000000 10537330448 10980483072 1172050304 12.516 100 53 104 2 1642238880 457051888 13136688
12:10:04 8059 8192000000 10503186880 10977415168 1158212544 12.387 100 42 83 0 1610522080 441423824 10885712
12:11:25 8873 8192000000 10418253360 10906025984 1146774864 12.279 100 38 71 1 1540159392 385035680 8348848
12:12:34 15364 8192000000 10288409056 10731192320 1151760384 12.326 100 24 49 0 1399743808 241303856 6679568
12:13:51 26477 8192000000 10217182736 10703912960 1145175376 12.264 100 21 42 1 1360599088 210289504 5134208
12:15:27 50309 8192000000 10097716288 10542788608 1109275504 11.926 97 18 32 4 1152433152 38013376 5144272
12:17:21 14524 8192000000 10100638976 10516414464 1123697136 12.062 99 70 132 2 1195946272 57961168 14287968
12:19:21 33930 8192000000 10085820800 10538807296 1131342400 12.134 100 36 66 1 1207926832 69734800 6849632
12:20:56 54952 8192000000 10078354400 10517114880 1094139504 11.782 96 30 58 0 1122647360 22783728 5724128
12:22:56 164581 8192000000 10099916848 10523189248 1020750400 11.079 90 28 50 2 1094631760 68199632 5681728
12:26:42 2562 8192000000 10152977200 10575122432 1140023312 12.216 100 60 114 0 1254167344 101323968 12820064
12:27:46 11562 8192000000 10199282496 10632437760 1129947200 12.121 100 45 88 0 1291376592 151661664 9767728
12:29:00 12454 8192000000 10189913424 10621374464 1127520976 12.098 100 66 128 1 1287186160 145837360 13827824
12:30:18 77962 8192000000 10113488208 10626424832 1116812816 11.997 99 16 32 0 1251528864 130663904 4052144
12:32:54 29395 8192000000 10125002736 10594574336 1127653120 12.099 100 17 28 3 1222459792 92292816 2513856
12:34:25 53703 8192000000 10089094736 10613002240 1132448368 12.144 100 17 35 0 1218900080 82549392 3902320
12:36:35 30204 8192000000 10081477696 10516791296 1134020016 12.159 100 31 63 0 1185296848 44630240 6646592
12:38:07 11146 8192000000 10096943904 10523451392 1123537856 12.060 100 54 105 1 1193026784 58646896 10842032
12:39:20 103503 8192000000 10068269696 10517864448 1125010432 12.074 99 23 46 3 1187245104 57369120 4865552
12:42:07 45547 8192000000 10099274176 10538393600 1113479056 11.965 98 33 66 0 1147207584 26685872 7042656
12:43:54 48788 8192000000 10154659584 10597380096 1106611936 11.900 97 25 50 1 1213408112 101560848 5235328
12:45:44 67895 8192000000 10078134368 10535505920 1094340224 11.784 97 20 40 2 1156341216 57592176 4408816
12:48:33 102385 8192000000 10047639040 10485706752 1112492240 11.956 99 24 47 1 1149112032 31035888 5583904
12:51:16 65669 8192000000 10074090224 10492047360 1090492368 11.747 97 43 81 1 1159666320 61031504 8142448
12:53:25 39473 8192000000 10071725264 10515939328 1094228000 11.783 98 29 58 1 1154497136 54664080 5605056
12:55:08 35908 8192000000 10081397520 10518142976 1109831504 11.931 100 20 36 1 1192701776 78591200 4279072
12:56:44 76532 8192000000 10090512432 10561150976 1116236240 11.991 100 12 24 1 1218356128 99520960 2598928
12:59:13 90983 8192000000 10108708208 10599829504 1134062272 12.160 100 16 32 1 1220713088 83318560 3332256
13:01:52 47601 8192000000 10106668656 10538057728 1116910976 11.998 98 41 80 1 1197170368 71849328 8410064
13:03:42 13446 8192000000 10131427904 10565324800 1140594112 12.221 100 52 102 1 1233457536 82522368 10341056
13:04:56 33401 8192000000 10094203280 10568384512 1118555520 12.013 99 31 62 0 1223930656 99089552 6285584
13:06:31 11365 8192000000 10129258912 10569011200 1135624736 12.174 100 53 101 2 1229387888 82903344 10859808
13:07:45 17033 8192000000 10107307648 10545463296 1119906512 12.026 99 60 114 1 1192193104 60339600 11946992
13:09:06 64036 8192000000 10091418416 10534432768 1114107968 11.971 99 29 57 1 1165181488 45520160 5553360
13:11:12 95883 8192000000 10140390992 10608631808 1132496656 12.145 100 38 76 1 1200490816 60016672 7977488
13:13:58 28307 8192000000 10116494800 10565500928 1121457904 12.041 99 33 66 1 1195313952 66953296 6902752
13:15:27 23765 8192000000 10028774336 10480087040 1114314336 11.973 99 13 27 1 1145517600 28385264 2818000
13:16:52 62674 8192000000 10088096720 10538475520 1116934416 11.998 98 13 26 2 1173010064 53117344 2958304
13:18:57 50797 8192000000 10064308496 10558132224 1103531344 11.871 98 8 17 0 1172422656 67361600 1529712
13:20:49 41871 8192000000 10048656960 10492928000 1066638800 11.520 95 26 52 0 1109204240 36680096 5885344
13:22:57 43164 8192000000 10051979728 10501894144 1103678720 11.873 98 29 56 1 1139039664 29347632 6013312
13:24:42 29449 8192000000 10102568256 10544844800 1132855456 12.148 100 43 86 0 1193133056 51011328 9266272
13:26:14 40431 8192000000 10061729712 10518147072 1098612160 11.824 97 24 48 2 1160258496 56844320 4802016
13:27:54 23117 8192000000 10084336352 10518867968 1128322368 12.106 100 46 87 1 1171079232 33924016 8832848
13:29:20 33049 8192000000 10121769712 10555023360 1129562944 12.117 100 41 80 1 1211060320 73603136 7894240
13:30:54 29227 8192000000 10104460176 10545221632 1112242992 11.954 99 42 78 2 1194535312 74353856 7938464
13:32:32 101978 8192000000 10043691600 10503675904 1120758432 12.034 100 12 24 0 1172125520 49165248 2201840
13:35:27 40510 8192000000 10084445088 10533588992 1127810112 12.101 100 34 68 0 1203407072 69047424 6549536
13:37:09 29445 8192000000 9794158784 10225221632 897089952 9.869 80 2 4 0 927282896 29936848 256096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 26-03-2022 13:40:14 to 26-03-2022 13:40:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-03-2022 13:40:32 to 26-03-2022 13:40:34.

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          226815
MON$OLDEST_ACTIVE               226816
MON$OLDEST_SNAPSHOT             226816
MON$NEXT_TRANSACTION            226816
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                       2074400
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Sat Mar 26 13:38:08 2022
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

26.03.22 13:40:35. 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-03-26 13:40:37 No severe PSQL-related problems occured
26.03.22 13:40:39. End of report.