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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-10 07:37:15
DTS_END                         2022-05-10 07:37:15.5390
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/32902
TRN_ID                          228186
ATT_ID                          685
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: 9096.46 21253 1091575
customer order: creation 181.01 482 21721
customer order: refuse 15.53 390 1863
order to supplier: creation 47.75 1359 5730
order to supplier: removal 3.34 175547 401
invoice (draft): creation 47.27 3075 5672
invoice (draft): removal 47.94 1115 5753
invoice accept: apply 43.84 25269 5261
invoice accept: cancel 43.28 1205 5194
customer reserve: creation 4071.23 242 488547
customer reserve: removal 4301.88 106 516225
realization accept: apply 88.38 261 10606
realization accept: cancel 71.06 181 8527
payment from customer: creation 60.90 173 7308
payment from customer: removal 5.59 115 671
payment to supplier: creation 50.17 177 6020
payment to supplier: removal 3.63 97 436
service: total inventory turnovers 4.61 3164 553
service: total monetary turnovers 7.97 283 956
service: aggregate perf. data 1.06 12922 127
service: refresh index statistics 0.03 198900 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-05-2022 07:38:16 to 10-05-2022 07:38:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11386
TEST_TIME 2 7379
TEST_TIME 3 7889
TEST_TIME 4 7836
TEST_TIME 5 7522
TEST_TIME 6 7465
TEST_TIME 7 7144
TEST_TIME 8 7039
TEST_TIME 9 6978
TEST_TIME 10 6961
TEST_TIME 11 7102
TEST_TIME 12 7203
TEST_TIME 13 7208
TEST_TIME 14 7148
TEST_TIME 15 7178
TEST_TIME 16 7086
TEST_TIME 17 7084
TEST_TIME 18 7194
TEST_TIME 19 7181
TEST_TIME 20 7376
TEST_TIME 21 7454
TEST_TIME 22 7890
TEST_TIME 23 7816
TEST_TIME 24 8050
TEST_TIME 25 8533
TEST_TIME 26 8595
TEST_TIME 27 9251
TEST_TIME 28 9679
TEST_TIME 29 10373
TEST_TIME 30 11173
TEST_TIME 31 11435
TEST_TIME 32 11360
TEST_TIME 33 11137
TEST_TIME 34 11168
TEST_TIME 35 11245
TEST_TIME 36 11165
TEST_TIME 37 10889
TEST_TIME 38 10787
TEST_TIME 39 10788
TEST_TIME 40 10545
TEST_TIME 41 10475
TEST_TIME 42 10360
TEST_TIME 43 10216
TEST_TIME 44 10326
TEST_TIME 45 10284
TEST_TIME 46 10304
TEST_TIME 47 10189
TEST_TIME 48 10213
TEST_TIME 49 10051
TEST_TIME 50 9930
TEST_TIME 51 9992
TEST_TIME 52 9841
TEST_TIME 53 9760
TEST_TIME 54 9628
TEST_TIME 55 9591
TEST_TIME 56 9592
TEST_TIME 57 9495
TEST_TIME 58 9458
TEST_TIME 59 9466
TEST_TIME 60 9478
TEST_TIME 61 9608
TEST_TIME 62 9563
TEST_TIME 63 9680
TEST_TIME 64 9676
TEST_TIME 65 9802
TEST_TIME 66 9780
TEST_TIME 67 9804
TEST_TIME 68 9758
TEST_TIME 69 9729
TEST_TIME 70 9620
TEST_TIME 71 9585
TEST_TIME 72 9584
TEST_TIME 73 9520
TEST_TIME 74 9466
TEST_TIME 75 9424
TEST_TIME 76 9376
TEST_TIME 77 9357
TEST_TIME 78 9394
TEST_TIME 79 9397
TEST_TIME 80 9382
TEST_TIME 81 9384
TEST_TIME 82 9430
TEST_TIME 83 9428
TEST_TIME 84 9492
TEST_TIME 85 9516
TEST_TIME 86 9425
TEST_TIME 87 9376
TEST_TIME 88 9375
TEST_TIME 89 9306
TEST_TIME 90 9262
TEST_TIME 91 9241
TEST_TIME 92 9294
TEST_TIME 93 9283
TEST_TIME 94 9255
TEST_TIME 95 9245
TEST_TIME 96 9283
TEST_TIME 97 9268
TEST_TIME 98 9282
TEST_TIME 99 9303
TEST_TIME 100 9268
TEST_TIME 101 9296
TEST_TIME 102 9370
TEST_TIME 103 9324
TEST_TIME 104 9338
TEST_TIME 105 9389
TEST_TIME 106 9405
TEST_TIME 107 9321
TEST_TIME 108 9282
TEST_TIME 109 9292
TEST_TIME 110 9240
TEST_TIME 111 9190
TEST_TIME 112 9193
TEST_TIME 113 9163
TEST_TIME 114 9156
TEST_TIME 115 9142
TEST_TIME 116 9164
TEST_TIME 117 9175
TEST_TIME 118 9185
TEST_TIME 119 9179
TEST_TIME 120 9126

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-05-2022 07:38:20 to 10-05-2022 07:38:23.

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 1541092 1541092 0 0.00 0 15031 14 0 0
doc_list_biud 1541084 1541084 0 0.00 0 41497 41 0 0
sp_add_invoice_to_stock 5417 5261 156 2.87 43 197003 25269 0 156
sp_cancel_adding_invoice 5194 5194 0 0.00 5 85107 1205 0 0
sp_cancel_client_order 1863 1863 0 0.00 6 10226 390 0 0
sp_cancel_customer_reserve 516225 516225 0 0.00 1 31004 107 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 4 2581 115 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 4 1997 97 0 0
sp_cancel_supplier_invoice 5766 5753 13 0.22 2 15277 1116 0 13
sp_cancel_supplier_order 401 401 0 0.00 16 1611964 175548 0 0
sp_cancel_write_off 8527 8527 0 0.00 2 41510 182 0 0
sp_client_order 21721 21721 0 0.00 5 25923 482 0 0
sp_customer_reserve 488547 488547 0 0.00 0 105518 242 0 0
sp_fill_shopping_cart 33113 33113 0 0.00 1 9605 77 0 0
sp_fill_shopping_cart_clo_res 488514 488514 0 0.00 0 18547 19 0 0
sp_get_clo_for_invoice 5223 5223 0 0.00 0 30260 281 0 0
sp_kill_cost_storno 14828 14828 0 0.00 0 6224 69 0 0
sp_kill_qstorno_ret_qs2qd 1047131 1047131 0 0.00 0 41485 46 0 0
sp_kill_qty_storno 553791 553791 0 0.00 0 41493 106 0 0
sp_lock_selected_doc 21787 21787 0 0.00 0 10440 8 0 0
sp_make_cost_storno 29157 29157 0 0.00 0 15008 75 0 0
sp_multiply_rows_for_pdistr 29157 29157 0 0.00 0 13379 34 0 0
sp_multiply_rows_for_qdistr 21721 21721 0 0.00 0 9003 207 0 0
sp_pay_from_customer 7308 7308 0 0.00 5 22254 174 0 0
sp_pay_to_supplier 6020 6020 0 0.00 6 11238 177 0 0
sp_payment_common 13326 13326 0 0.00 2 7414 41 0 0
sp_qd_handle_on_cancel_clo 1863 1863 0 0.00 0 9587 308 0 0
sp_qd_handle_on_invoice_upd_sts 10417 10417 0 0.00 0 21155 476 0 0
sp_qd_handle_on_reserve_upd_sts 10605 10605 0 0.00 0 16424 57 0 0
sp_reserve_write_off 10606 10606 0 0.00 8 21980 262 0 0
sp_supplier_invoice 5672 5672 0 0.00 9 89686 3076 0 0
sp_supplier_order 5730 5730 0 0.00 6 44148 1360 0 0
srv_aggregate_perf_data 127 127 0 0.00 2438 79443 12923 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 821 117437 35261 0 0
srv_find_qd_qs_mism 1040236 1040236 0 0.00 0 7923 11 0 0
srv_make_invnt_saldo 553 553 0 0.00 68 58313 3165 0 0
srv_make_money_saldo 956 956 0 0.00 1 11853 283 0 0
srv_recalc_idx_stat 4 4 0 0.00 50871 347104 198900 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5268 5268 0 0.00 1 22548 838 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 21388 371 0 0
t$perf-norm:sp_cancel_client_order 1863 1863 0 0.00 0 1693 24 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 7247 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 13333 32 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 3681 33 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 1519 37 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 81126 6540 0 0
t$perf-norm:sp_cancel_write_off 1045 1045 0 0.00 0 987 17 0 0
t$perf-norm:sp_client_order 21721 21721 0 0.00 0 11174 37 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 5423 24 0 0
t$perf-norm:sp_pay_from_customer 7308 7308 0 0.00 0 6973 18 0 0
t$perf-norm:sp_pay_to_supplier 6020 6020 0 0.00 0 4680 16 0 0
t$perf-norm:sp_reserve_write_off 10606 10606 0 0.00 0 14001 22 0 0
t$perf-norm:sp_supplier_invoice 5673 5673 0 0.00 0 5576 29 0 0
t$perf-norm:sp_supplier_order 5730 5730 0 0.00 0 7630 28 0 0
t$perf-norm:srv_aggregate_perf_data 127 127 0 0.00 0 247 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 468 15 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 1572 17 0 0
t$perf-norm:srv_make_money_saldo 956 956 0 0.00 0 732 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 520 195 0 0
v_add_invoice_to_stock 10748 10436 312 2.90 0 6176 23 0 312
v_all_customers 39776 39776 0 0.00 0 120 3 0 0
v_all_suppliers 31762 31762 0 0.00 0 165 4 0 0
v_all_wares 43438 43438 0 0.00 0 172 3 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 480 11 0 0
v_cancel_client_order 3726 3726 0 0.00 0 2180 11 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 980 11 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 2334 11 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 751 9 0 0
v_cancel_supplier_invoice 890 864 26 2.92 0 849 23 0 26
v_cancel_supplier_order 802 802 0 0.00 0 724 10 0 0
v_cancel_write_off 2090 2090 0 0.00 0 6096 23 0 0
v_max_id_clo_ord 5729 5729 0 0.00 0 176 4 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 2077 15 0 0
v_max_id_ord_sup 5663 5663 0 0.00 0 4899 10 0 0
v_max_non_paid_invoice 1527 1527 0 0.00 0 1966 9 0 0
v_max_non_paid_realizn 3818 3818 0 0.00 0 4984 8 0 0
v_min_id_clo_ord 5729 5729 0 0.00 0 3739 8 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 4652 19 0 0
v_min_id_ord_sup 5663 5663 0 0.00 0 5111 10 0 0
v_min_non_paid_invoice 6020 6020 0 0.00 0 4791 13 0 0
v_min_non_paid_realizn 7305 7305 0 0.00 0 7399 16 0 0
v_our_firm 10634 10634 0 0.00 0 106 4 0 0
v_reserve_write_off 21204 21204 0 0.00 0 7489 11 0 0
x_lock_depdocs_on_canc_invoice 10515 10515 0 0.00 0 33326 130 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 4353 313 0 0
x_make_qstorno_1000_1200 5730 5730 0 0.00 2 44134 1209 0 0
x_make_qstorno_1200_2000 5672 5672 0 0.00 4 89649 2858 0 0
x_make_qstorno_2100_3300 488546 488546 0 0.00 0 105516 211 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-05-2022 07:38:24 to 10-05-2022 07:38:28.

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:19 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:20 12143 8192000000 9694711232 9988747264 778136192 8.674 100 87 175 0 806862064 7172000 21553872
05:09:36 23005 8192000000 9746419376 10049945600 822305280 9.122 100 86 171 0 854124544 11016880 20802384
05:11:05 17232 8192000000 9786479440 10104012800 857903600 9.479 100 81 162 0 893982544 17597200 18481744
05:12:27 10332 8192000000 9864847040 10194546688 915471696 10.051 100 83 166 0 964278208 30582528 18223984
05:13:48 8678 8192000000 9915810512 10261966848 958992176 10.479 100 86 168 1 1030561712 54004336 17565200
05:14:59 3092 8192000000 9963645712 10317393920 994289936 10.823 100 88 173 0 1074282608 62203408 17789264
05:16:07 1609 8192000000 9999903536 10357043200 1015393888 11.028 100 93 182 1 1115687328 81783456 18509984
05:17:10 1271 8192000000 10007237936 10373529600 1041336960 11.278 100 99 196 1 1122087472 61518960 19231552
05:18:12 1093 8192000000 10033206464 10408181760 1060790496 11.464 100 90 175 0 1156349760 77403664 18155600
05:19:14 10537 8192000000 10042014240 10418614272 1072677312 11.578 100 92 169 2 1167844560 78728848 16438400
05:20:26 1649 8192000000 10051674704 10438385664 1077177536 11.621 100 87 167 1 1172176608 78629760 16369312
05:21:29 971 8192000000 10049549536 10435809280 1087871776 11.722 100 91 177 2 1178699168 72299808 18527584
05:22:31 1092 8192000000 10066711328 10453118976 1092096272 11.763 100 86 170 0 1186306608 77053984 17156352
05:23:33 1438 8192000000 10040502752 10428461056 1100844320 11.846 100 77 150 2 1162371648 45188288 16339040
05:24:35 2953 8192000000 10047201776 10450276352 1097660928 11.815 100 73 145 1 1175694352 63204800 14828624
05:25:38 1457 8192000000 10072494560 10464993280 1109412416 11.927 100 89 166 2 1201779920 75084928 17282576
05:26:41 1326 8192000000 10088918144 10480308224 1103299776 11.869 100 94 179 0 1210218032 88563664 18354592
05:27:44 1088 8192000000 10071226016 10464841728 1097605888 11.815 100 92 175 2 1195694096 80392688 17695520
05:28:46 1225 8192000000 10057992464 10448211968 1098914912 11.827 100 97 185 0 1181847584 65400816 17531856
05:29:48 900 8192000000 10049864992 10433540096 1081032432 11.657 100 96 183 0 1167010624 67357504 18620688
05:30:50 1295 8192000000 10031547792 10413342720 1068164240 11.535 100 97 179 1 1155932192 68838544 18929408
05:31:52 8295 8192000000 10014673472 10402357248 1060547920 11.462 100 60 115 1 1136661936 64039872 12074144
05:33:02 1906 8192000000 9971223968 10347921408 1039020288 11.255 98 79 152 4 1091853664 36890800 15942576
05:34:06 1002 8192000000 9986226480 10364366848 1050402928 11.365 100 84 161 1 1106270320 39712832 16154560
05:35:08 1111 8192000000 9962455824 10338758656 1030312192 11.171 99 79 154 1 1083507936 36885152 16310592
05:36:10 2918 8192000000 9988708496 10364923904 1040261424 11.267 99 74 143 0 1110614752 55431264 14922064
05:37:14 1638 8192000000 10020991712 10402529280 1058215216 11.439 100 83 146 1 1141322544 69061376 14045952
05:38:22 2882 8192000000 10033350544 10412183552 1068610720 11.539 100 74 140 2 1153528640 70927200 13990720
05:39:30 4133 8192000000 10036978096 10416783360 1070673072 11.559 100 82 155 0 1154933952 68463872 15797008
05:40:38 17748 8192000000 10054645088 10454159360 1086543440 11.710 100 58 114 0 1186765056 88568848 11652768
05:42:03 1375 8192000000 10089598304 10476924928 1108513808 11.918 100 87 168 1 1208678464 82513392 17651264
05:43:06 1474 8192000000 10126058896 10520285184 1122879824 12.054 100 80 151 2 1244052736 104509776 16663136
05:44:11 7656 8192000000 10161028352 10559729664 1130330896 12.124 100 70 138 1 1273604736 128301520 14972320
05:45:32 1755 8192000000 10180839040 10575585280 1146147952 12.273 100 86 166 0 1300235664 136747936 17339776
05:46:38 3755 8192000000 10221785408 10621997056 1152782624 12.336 100 78 151 0 1336758400 167543696 16432080
05:47:45 1161 8192000000 10290224144 10686746624 1170754736 12.504 100 95 185 1 1402546880 211367776 20424368
05:48:49 1475 8192000000 10326889904 10724397056 1184670608 12.634 100 90 172 1 1440696064 236320336 19705120
05:49:52 939 8192000000 10372946464 10774282240 1196226032 12.741 100 92 178 0 1482329040 265970880 20132128
05:50:57 1028 8192000000 10396570384 10805555200 1202449424 12.799 100 89 170 0 1508232224 284753616 21029184
05:52:01 2162 8192000000 10457363440 10875273216 1199328560 12.770 100 93 185 0 1560151088 339555488 21267040
05:53:10 1358 8192000000 10509916736 10917523456 1210308096 12.872 100 91 178 1 1615463296 384159488 20995712
05:54:16 990 8192000000 10554455136 10981482496 1225991600 13.017 100 98 196 0 1663186032 412583024 24611408
05:55:20 1173 8192000000 10591033904 11001597952 1207794928 12.849 100 95 190 0 1695796320 465296976 22704416
05:56:25 1093 8192000000 10627185136 11059294208 1211849632 12.886 100 95 184 1 1736948992 500343920 24755440
05:57:29 1511 8192000000 10659582640 11082752000 1210655232 12.875 100 86 170 1 1766920464 535094176 21171056
05:58:33 814 8192000000 10647825728 11105386496 1207929984 12.850 100 94 181 0 1750152704 518473664 23749056
05:59:38 1878 8192000000 10654698720 11092774912 1199885792 12.775 100 83 162 2 1760160576 539054128 21220656
06:00:45 9025 8192000000 10631374256 11086221312 1159532608 12.399 98 65 128 0 1725107104 548759904 16814592
06:01:58 7135 8192000000 10644206544 11110686720 1178317424 12.575 100 65 128 0 1769495168 573812496 17365248
06:03:09 11483 8192000000 10607103296 11065896960 1174129328 12.535 100 52 100 1 1713134736 525389088 13616320
06:04:32 4727 8192000000 10536405376 11078668288 1146884416 12.280 100 46 92 0 1666671984 507100384 12687184
06:05:41 12239 8192000000 10400229600 10950172672 1125619568 12.080 99 40 80 0 1487840144 351548976 10671600
06:06:59 30372 8192000000 10179831632 10704039936 1117579280 12.004 99 14 26 1 1322070608 201306992 3184336
06:08:32 86245 8192000000 10111292512 10559692800 1123265552 12.058 98 11 22 1 1197706736 71781408 2659776
06:11:00 40506 8192000000 10149459792 10621661184 1140764448 12.223 100 35 67 1 1253757616 105585360 7407808
06:12:46 111996 8192000000 10073206976 10545864704 1127746048 12.100 99 21 42 0 1173743152 40490304 5506800
06:15:50 117437 8192000000 10083039328 10535702528 1103075216 11.867 96 22 44 0 1144178656 36508576 4594864
06:19:01 66490 8192000000 10150476672 10596945920 1103627008 11.872 97 20 40 2 1195272464 87090400 4555056
06:21:15 55030 8192000000 10148647968 10646085632 1129062512 12.113 100 26 54 0 1280148304 144728208 6357584
06:23:10 90767 8192000000 10076908752 10532384768 1110973968 11.942 98 24 46 2 1164068368 48383056 4711344
06:25:41 74470 8192000000 10061052640 10518401024 1115145568 11.981 99 10 20 0 1155574768 38120624 2308576
06:27:56 49081 8192000000 10050822784 10543300608 1126323344 12.087 100 16 32 0 1206170416 75531728 4315344
06:29:46 28488 8192000000 10041454256 10477428736 1118636544 12.014 99 55 85 2 1149906144 23665728 7603872
06:31:15 38956 8192000000 10058197072 10509139968 1122239984 12.048 99 43 78 5 1152137776 21261872 8635920
06:33:14 64878 8192000000 10079194592 10564997120 1115050320 11.980 98 13 26 0 1169527920 51894816 2582784
06:35:19 17170 8192000000 10108084896 10547363840 1116793152 11.997 98 37 72 1 1196949040 72128704 8027184
06:36:40 63801 8192000000 10146001376 10619293696 1131650416 12.137 99 28 56 0 1236694224 98443520 6600288
06:38:45 27411 8192000000 10101730512 10533044224 1108982128 11.923 98 30 60 1 1169903536 55382224 5539184
06:40:13 68273 8192000000 10096043392 10570391552 1117589600 12.004 98 22 44 0 1217240864 95173680 4477584
06:42:21 46802 8192000000 10073080768 10523869184 1102046144 11.857 97 20 38 0 1158403440 52692000 3665296
06:44:13 11595 8192000000 10121401808 10543755264 1122695200 12.052 99 55 108 1 1206496208 73048368 10752640
06:46:10 104076 8192000000 10115553952 10584821760 1128422720 12.106 99 58 109 2 1217832944 77177424 12232800
06:48:55 48596 8192000000 10079988528 10553028608 1117045568 11.999 98 14 28 1 1188782352 68307040 3429744
06:50:48 49965 8192000000 10038574352 10531905536 1083782944 11.684 96 4 8 1 1118583552 34283680 516928
06:52:39 47198 8192000000 10070466304 10514620416 1115066448 11.980 98 21 40 1 1155350960 36072480 4212032
06:54:30 16685 8192000000 10139253072 10578694144 1137697456 12.194 99 40 80 0 1238308176 92098848 8511872
06:55:51 52084 8192000000 10082653776 10524983296 1071846896 11.570 94 25 50 2 1155108336 77133872 6127568
06:57:44 51485 8192000000 10098688032 10540036096 1103033328 11.866 97 26 52 1 1165939344 57390304 5515712
06:59:35 8627 8192000000 10081868752 10499772416 1109910144 11.932 99 68 134 2 1169620192 46178160 13531888
07:00:46 61771 8192000000 10086191648 10555727872 1095092992 11.791 97 28 55 1 1167641424 66886416 5662016
07:02:54 5752 8192000000 10065127136 10488680448 1129795120 12.119 100 67 128 0 1163809728 20511408 13503200
07:04:00 60617 8192000000 10098544224 10541043712 1132520464 12.145 100 40 79 0 1203338736 62815664 8002608
07:06:01 45775 8192000000 10086355056 10562007040 1117280768 12.001 98 12 24 0 1181499072 61070112 3148192
07:08:19 48658 8192000000 10061521664 10498039808 1076130288 11.611 95 16 32 0 1139126320 59840512 3155520
07:10:09 58503 8192000000 10070620800 10528833536 1098700496 11.825 97 21 42 0 1139280288 36393040 4186752
07:12:08 42810 8192000000 10079661488 10530983936 1089881520 11.742 96 30 60 1 1150065328 53801472 6382336
07:13:55 27790 8192000000 10102446672 10538967040 1129422944 12.116 99 41 78 1 1184203216 46601984 8178288
07:15:26 84921 8192000000 10112735376 10581823488 1102997296 11.866 97 36 72 0 1182200928 71941872 7261760
07:17:53 34688 8192000000 10136840992 10603470848 1130624032 12.127 99 35 70 0 1211652592 73840976 7187584
07:20:04 37653 8192000000 10059598400 10482913280 1082454416 11.671 95 35 56 1 1106765008 18207936 6102656
07:21:44 52176 8192000000 10096123424 10579202048 1122650144 12.052 99 28 55 1 1212923392 84209552 6063696
07:23:39 107760 8192000000 10085942800 10538270720 1126097760 12.085 99 19 38 2 1210738720 80408976 4231984
07:26:27 84204 8192000000 10073901520 10573520896 1136680112 12.184 100 5 10 0 1180376176 42648432 1047632
07:29:28 65788 8192000000 10074502304 10534850560 1085690384 11.702 96 29 58 0 1122209488 30217200 6301904
07:31:35 39699 8192000000 10116316928 10558251008 1128499008 12.107 99 27 54 3 1202371536 67992816 5879712
07:33:32 43796 8192000000 10088198720 10543300608 1096539200 11.805 96 26 52 2 1173988112 71820832 5628080
07:35:21 38348 8192000000 10090362048 10522071040 1105980144 11.894 98 32 64 1 1169430320 56563552 6886624
07:37:05 26729 8192000000 9267046576 9601593344 392041648 4.567 35 11 22 0 442919040 48714704 2162688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-05-2022 07:38:30 to 10-05-2022 07:38:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-05-2022 07:38:47 to 10-05-2022 07:38:49.

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          227988
MON$OLDEST_ACTIVE               228549
MON$OLDEST_SNAPSHOT             228549
MON$NEXT_TRANSACTION            228549
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                       2035872
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

10.05.22 07:38:50. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-05-10 07:38:53 No severe PSQL-related problems occured
10.05.22 07:38:54. End of report.