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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-25 10:37:19
DTS_END                         2022-07-25 10:37:19.9180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38442
TRN_ID                          216906
ATT_ID                          581
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: 8698.33 23371 1043800
customer order: creation 171.31 507 20557
customer order: refuse 14.45 393 1734
order to supplier: creation 45.56 1392 5467
order to supplier: removal 3.48 138369 417
invoice (draft): creation 45.66 3017 5479
invoice (draft): removal 49.02 859 5882
invoice accept: apply 39.82 26270 4778
invoice accept: cancel 43.94 1164 5273
customer reserve: creation 3708.20 251 444984
customer reserve: removal 4286.25 90 514350
realization accept: apply 81.93 270 9831
realization accept: cancel 79.34 175 9521
payment from customer: creation 58.68 161 7041
payment from customer: removal 5.70 129 684
payment to supplier: creation 48.25 189 5790
payment to supplier: removal 3.71 147 445
service: total inventory turnovers 4.37 3189 524
service: total monetary turnovers 7.58 337 909
service: aggregate perf. data 1.08 12395 129
service: refresh index statistics 0.04 278118 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-07-2022 10:38:23 to 25-07-2022 10:38:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10699
TEST_TIME 2 6392
TEST_TIME 3 6094
TEST_TIME 4 5847
TEST_TIME 5 5923
TEST_TIME 6 5736
TEST_TIME 7 5638
TEST_TIME 8 5707
TEST_TIME 9 5926
TEST_TIME 10 5997
TEST_TIME 11 5949
TEST_TIME 12 6430
TEST_TIME 13 6469
TEST_TIME 14 6620
TEST_TIME 15 6680
TEST_TIME 16 6736
TEST_TIME 17 6787
TEST_TIME 18 7451
TEST_TIME 19 7484
TEST_TIME 20 7472
TEST_TIME 21 7697
TEST_TIME 22 8082
TEST_TIME 23 8590
TEST_TIME 24 9005
TEST_TIME 25 8819
TEST_TIME 26 8654
TEST_TIME 27 9016
TEST_TIME 28 9096
TEST_TIME 29 9796
TEST_TIME 30 10234
TEST_TIME 31 10741
TEST_TIME 32 10561
TEST_TIME 33 10574
TEST_TIME 34 10617
TEST_TIME 35 10361
TEST_TIME 36 10211
TEST_TIME 37 10074
TEST_TIME 38 9957
TEST_TIME 39 9893
TEST_TIME 40 9862
TEST_TIME 41 9741
TEST_TIME 42 9718
TEST_TIME 43 9673
TEST_TIME 44 9559
TEST_TIME 45 9425
TEST_TIME 46 9398
TEST_TIME 47 9250
TEST_TIME 48 9314
TEST_TIME 49 9240
TEST_TIME 50 9169
TEST_TIME 51 9173
TEST_TIME 52 9239
TEST_TIME 53 9267
TEST_TIME 54 9256
TEST_TIME 55 9192
TEST_TIME 56 9291
TEST_TIME 57 9292
TEST_TIME 58 9314
TEST_TIME 59 9257
TEST_TIME 60 9256
TEST_TIME 61 9236
TEST_TIME 62 9441
TEST_TIME 63 9324
TEST_TIME 64 9197
TEST_TIME 65 9143
TEST_TIME 66 9087
TEST_TIME 67 9036
TEST_TIME 68 9054
TEST_TIME 69 8986
TEST_TIME 70 8978
TEST_TIME 71 8908
TEST_TIME 72 8968
TEST_TIME 73 8945
TEST_TIME 74 8936
TEST_TIME 75 9032
TEST_TIME 76 9017
TEST_TIME 77 9041
TEST_TIME 78 9014
TEST_TIME 79 9080
TEST_TIME 80 9072
TEST_TIME 81 9069
TEST_TIME 82 9079
TEST_TIME 83 9059
TEST_TIME 84 9002
TEST_TIME 85 8939
TEST_TIME 86 8926
TEST_TIME 87 8906
TEST_TIME 88 8862
TEST_TIME 89 8843
TEST_TIME 90 8821
TEST_TIME 91 8820
TEST_TIME 92 8795
TEST_TIME 93 8791
TEST_TIME 94 8791
TEST_TIME 95 8774
TEST_TIME 96 8792
TEST_TIME 97 8786
TEST_TIME 98 8841
TEST_TIME 99 8898
TEST_TIME 100 8863
TEST_TIME 101 8956
TEST_TIME 102 8945
TEST_TIME 103 8945
TEST_TIME 104 8990
TEST_TIME 105 9013
TEST_TIME 106 8944
TEST_TIME 107 8880
TEST_TIME 108 8903
TEST_TIME 109 8889
TEST_TIME 110 8847
TEST_TIME 111 8801
TEST_TIME 112 8768
TEST_TIME 113 8763
TEST_TIME 114 8761
TEST_TIME 115 8754
TEST_TIME 116 8761
TEST_TIME 117 8778
TEST_TIME 118 8748
TEST_TIME 119 8751
TEST_TIME 120 8721

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-07-2022 10:38:27 to 25-07-2022 10:38:30.

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 1456342 1456342 0 0.00 0 38394 12 0 0
doc_list_biud 1456330 1456330 0 0.00 0 64612 37 0 0
sp_add_invoice_to_stock 4912 4778 134 2.72 57 192666 26270 0 134
sp_cancel_adding_invoice 5273 5273 0 0.00 5 104707 1164 0 0
sp_cancel_client_order 1734 1734 0 0.00 5 10040 393 0 0
sp_cancel_customer_reserve 514350 514350 0 0.00 1 64614 90 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 4 6739 130 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 4 5736 148 0 0
sp_cancel_supplier_invoice 5893 5882 11 0.18 3 33553 860 0 11
sp_cancel_supplier_order 417 417 0 0.00 8 1220479 138370 0 0
sp_cancel_write_off 9521 9521 0 0.00 2 8300 176 0 0
sp_client_order 20557 20557 0 0.00 5 34717 508 0 0
sp_customer_reserve 444984 444984 0 0.00 0 90346 252 0 0
sp_fill_shopping_cart 31494 31494 0 0.00 1 19696 81 0 0
sp_fill_shopping_cart_clo_res 444953 444953 0 0.00 0 21135 20 0 0
sp_get_clo_for_invoice 4740 4740 0 0.00 0 26187 355 0 0
sp_kill_cost_storno 15923 15923 0 0.00 0 7531 70 0 0
sp_kill_qstorno_ret_qs2qd 1044520 1044520 0 0.00 0 64594 40 0 0
sp_kill_qty_storno 551746 551746 0 0.00 0 64612 91 0 0
sp_lock_selected_doc 20328 20328 0 0.00 0 7808 8 0 0
sp_make_cost_storno 27402 27402 0 0.00 0 38386 72 0 0
sp_multiply_rows_for_pdistr 27400 27400 0 0.00 0 12420 36 0 0
sp_multiply_rows_for_qdistr 20557 20557 0 0.00 0 14120 233 0 0
sp_pay_from_customer 7041 7041 0 0.00 5 15801 161 0 0
sp_pay_to_supplier 5790 5790 0 0.00 6 24638 190 0 0
sp_payment_common 12830 12830 0 0.00 2 11818 40 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 0 10003 315 0 0
sp_qd_handle_on_invoice_upd_sts 10013 10013 0 0.00 0 24501 454 0 0
sp_qd_handle_on_reserve_upd_sts 9829 9829 0 0.00 0 14896 65 0 0
sp_reserve_write_off 9831 9831 0 0.00 8 29526 271 0 0
sp_supplier_invoice 5479 5479 0 0.00 7 98840 3018 0 0
sp_supplier_order 5467 5467 0 0.00 7 101859 1392 0 0
srv_aggregate_perf_data 129 129 0 0.00 2005 175861 12395 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 869 160499 38588 0 0
srv_find_qd_qs_mism 997069 997069 0 0.00 0 17090 10 0 0
srv_make_invnt_saldo 524 524 0 0.00 82 74131 3189 0 0
srv_make_money_saldo 909 909 0 0.00 1 11359 337 0 0
srv_recalc_idx_stat 5 5 0 0.00 52429 452806 278118 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 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 4785 4785 0 0.00 1 11663 834 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 38260 593 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 7744 27 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 20002 59 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 4740 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 2398 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 880 33 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 73301 5168 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 1984 17 0 0
t$perf-norm:sp_client_order 20557 20557 0 0.00 0 20040 35 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 970 10 0 0
t$perf-norm:sp_pay_from_customer 7041 7041 0 0.00 0 5675 21 0 0
t$perf-norm:sp_pay_to_supplier 5790 5790 0 0.00 0 4220 16 0 0
t$perf-norm:sp_reserve_write_off 9831 9831 0 0.00 0 5519 20 0 0
t$perf-norm:sp_supplier_invoice 5479 5479 0 0.00 0 9046 28 0 0
t$perf-norm:sp_supplier_order 5468 5468 0 0.00 0 2884 28 0 0
t$perf-norm:srv_aggregate_perf_data 129 129 0 0.00 0 274 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 211 7 0 0
t$perf-norm:srv_make_invnt_saldo 524 524 0 0.00 0 1240 16 0 0
t$perf-norm:srv_make_money_saldo 909 909 0 0.00 0 1092 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 114 29 0 0
v_add_invoice_to_stock 9746 9478 268 2.74 0 6862 24 0 268
v_all_customers 37382 37382 0 0.00 0 100 3 0 0
v_all_suppliers 30348 30348 0 0.00 0 67 4 0 0
v_all_wares 41110 41110 0 0.00 0 155 4 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 569 13 0 0
v_cancel_client_order 3468 3468 0 0.00 0 5841 13 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 5032 14 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 4177 16 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 5728 27 0 0
v_cancel_supplier_invoice 810 788 22 2.71 0 5269 25 0 22
v_cancel_supplier_order 834 834 0 0.00 0 4524 14 0 0
v_cancel_write_off 2072 2072 0 0.00 0 4686 28 0 0
v_max_id_clo_ord 5465 5465 0 0.00 0 254 5 0 0
v_max_id_clo_res 973 973 0 0.00 0 3545 21 0 0
v_max_id_ord_sup 5473 5473 0 0.00 0 10568 9 0 0
v_max_non_paid_invoice 1552 1552 0 0.00 0 178 7 0 0
v_max_non_paid_realizn 3690 3690 0 0.00 0 6390 9 0 0
v_min_id_clo_ord 5465 5465 0 0.00 0 3305 8 0 0
v_min_id_clo_res 973 973 0 0.00 0 5350 26 0 0
v_min_id_ord_sup 5473 5473 0 0.00 0 5239 11 0 0
v_min_non_paid_invoice 5789 5789 0 0.00 0 11767 14 0 0
v_min_non_paid_realizn 7041 7041 0 0.00 0 7635 15 0 0
v_our_firm 10430 10430 0 0.00 0 103 4 0 0
v_reserve_write_off 19656 19656 0 0.00 0 8670 14 0 0
x_lock_depdocs_on_canc_invoice 10761 10761 0 0.00 0 17924 113 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 3431 275 0 0
x_make_qstorno_1000_1200 5467 5467 0 0.00 2 101839 1232 0 0
x_make_qstorno_1200_2000 5479 5479 0 0.00 3 93610 2786 0 0
x_make_qstorno_2100_3300 444983 444983 0 0.00 0 90277 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-07-2022 10:38:31 to 25-07-2022 10:38:35.

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
08:07:23 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:24 27367 8192000000 9732374432 10031140864 806884784 8.966 100 89 178 0 835781104 7582704 21313616
08:10:02 17191 8192000000 9762685024 10072297472 838763168 9.287 100 86 171 0 872246080 13268560 20214352
08:11:29 11717 8192000000 9817826320 10135752704 879708656 9.697 100 89 178 0 921832832 21744032 20380144
08:12:46 5024 8192000000 9876098704 10213150720 922759008 10.123 100 78 154 0 988612752 49465200 16388544
08:13:57 5684 8192000000 9919513792 10266427392 965465216 10.542 100 77 154 0 1033692368 52209024 16018128
08:15:07 1819 8192000000 9969236352 10323034112 995829680 10.838 100 93 183 0 1089012448 74800880 18381888
08:16:10 1067 8192000000 10011855728 10374881280 1018031632 11.053 100 95 185 2 1124745536 88527152 18186752
08:17:13 842 8192000000 10023136592 10401206272 1049664336 11.357 100 88 172 2 1150485600 83123504 17697760
08:18:15 1428 8192000000 10029287392 10405941248 1060673632 11.463 100 88 174 0 1156516480 78802496 17040352
08:19:18 1161 8192000000 10040776032 10412511232 1064241248 11.497 100 89 162 0 1159860976 79972848 15646880
08:20:19 4148 8192000000 10036183040 10416087040 1073763808 11.588 100 74 139 0 1164396048 76875568 13756672
08:21:25 1250 8192000000 10055555360 10443046912 1075051296 11.600 100 89 171 1 1178385456 86000096 17334064
08:22:27 1082 8192000000 10062213120 10446520320 1085081056 11.696 100 97 190 1 1186464240 83083376 18299808
08:23:29 1087 8192000000 10036581920 10430128128 1082661056 11.673 100 78 154 0 1162609952 63801456 16147440
08:24:31 988 8192000000 10051513984 10436689920 1087172256 11.716 100 92 169 3 1174092368 68427488 18492624
08:25:33 932 8192000000 10047883904 10436890624 1090952848 11.752 100 94 180 0 1165647408 56549072 18145488
08:26:35 1229 8192000000 10055689904 10441461760 1094477696 11.785 100 99 189 2 1176256896 63182544 18596656
08:27:37 865 8192000000 10038104032 10430013440 1087967072 11.723 99 86 164 0 1157497472 53125392 16405008
08:28:39 972 8192000000 10025183248 10415464448 1077727520 11.626 99 90 158 4 1147402512 51918320 17756672
08:29:41 1312 8192000000 10020558656 10406158336 1060223872 11.459 99 84 164 1 1141537744 64738688 16575184
08:30:43 1051 8192000000 10020653184 10397880320 1054399264 11.403 100 92 176 0 1142619312 69968032 18252016
08:31:46 1199 8192000000 10049679312 10432446464 1066857856 11.522 100 93 171 2 1173372704 88107232 18407616
08:32:48 1185 8192000000 10019427936 10392420352 1039748880 11.262 100 95 178 0 1138932864 79955072 19228912
08:33:50 1281 8192000000 10009738128 10380701696 1043978224 11.303 100 97 186 2 1131726992 70049856 17698912
08:34:52 767 8192000000 9963590656 10331643904 1020002400 11.072 100 86 166 0 1084022272 48018368 16001504
08:35:54 5130 8192000000 9949163280 10318639104 1007620112 10.952 98 73 138 1 1070218816 48437184 14161520
08:37:00 1498 8192000000 9983959136 10363092992 1041529248 11.279 100 76 150 2 1101951504 44465120 15957136
08:38:07 13321 8192000000 9989586928 10372165632 1071476672 11.566 100 74 131 0 1111541584 27946176 12118736
08:39:26 30157 8192000000 10048832880 10442706944 1081643184 11.663 100 64 122 2 1159867824 64025120 14199520
08:41:04 1006 8192000000 10071123792 10457673728 1104116064 11.877 100 95 176 0 1188834032 66261104 18456864
08:42:12 7673 8192000000 10084369936 10483019776 1112133952 11.953 100 66 126 2 1203933056 78256864 13542240
08:43:24 1743 8192000000 10131399840 10531168256 1123954336 12.064 100 98 175 1 1246576576 104750208 17872032
08:44:30 1656 8192000000 10145344560 10543742976 1140450624 12.220 100 91 159 1 1266863440 108208416 18204400
08:45:35 941 8192000000 10212469232 10606784512 1149034096 12.300 100 95 181 2 1322941840 154165792 19741952
08:46:43 3606 8192000000 10211012368 10613944320 1160018976 12.403 100 74 138 1 1327234688 151755312 15460400
08:47:50 5672 8192000000 10232290400 10637303808 1162730176 12.429 100 71 137 0 1349826368 172153856 14942336
08:48:59 1677 8192000000 10274759040 10690605056 1163661456 12.438 100 81 160 2 1388952848 206315472 18975920
08:50:03 1574 8192000000 10289504768 10696622080 1173689344 12.531 100 88 174 0 1401987568 207353440 20944784
08:51:07 1306 8192000000 10314788784 10725728256 1178537200 12.577 100 92 173 3 1425857104 228916208 18403696
08:52:12 2087 8192000000 10351273792 10763362304 1189790720 12.681 100 90 176 0 1466752464 256590112 20371632
08:53:16 1386 8192000000 10379681728 10793541632 1180460800 12.594 100 89 170 1 1491630176 290700640 20468736
08:54:22 1464 8192000000 10417349488 10849280000 1189103904 12.675 100 89 172 0 1525771696 316695296 19972496
08:55:26 857 8192000000 10400488160 10841333760 1174977520 12.543 99 94 187 0 1508991280 311926848 22086912
08:56:31 2890 8192000000 10410916016 10819817472 1186285504 12.649 100 84 167 0 1514908064 309725280 18897280
08:57:38 905 8192000000 10434629568 10869522432 1175479648 12.548 100 96 189 1 1539913152 341164480 23269024
08:58:42 977 8192000000 10477033200 10901712896 1180794752 12.598 100 99 191 1 1576060592 372518000 22747840
08:59:44 1643 8192000000 10421158944 10897301504 1173550528 12.530 100 63 124 0 1525987520 336212640 16224352
09:00:48 4376 8192000000 10397070896 10840457216 1157148864 12.377 100 55 105 1 1512041984 341515024 13378096
09:01:58 9712 8192000000 10344524672 10774458368 1161932800 12.421 100 34 64 0 1451260512 281296784 8030928
09:03:17 5638 8192000000 10409055680 10831409152 1173620928 12.531 100 56 106 1 1508268784 321601728 13046128
09:04:28 23698 8192000000 10422083280 10921517056 1169684864 12.494 100 37 74 0 1558751392 379211632 9854896
09:05:57 58259 8192000000 10268473616 10732187648 1110618160 11.938 96 20 40 0 1368884320 253069968 5196192
09:07:56 37016 8192000000 10096142880 10539069440 1113366320 11.964 98 15 30 0 1209669888 92630560 3673008
09:09:33 4053 8192000000 10048899984 10479013888 1132372288 12.144 99 57 101 2 1148716192 5080544 11263360
09:10:39 63359 8192000000 10107859440 10568544256 1087078256 11.715 95 13 25 0 1165710560 76233504 2398800
09:12:43 126875 8192000000 10069597888 10512797696 1107481616 11.909 98 33 60 3 1183163312 68277152 7404544
09:15:50 24576 8192000000 10106518160 10554179584 1124978192 12.074 99 34 69 0 1192209968 60299760 6932016
09:17:24 110958 8192000000 10106594944 10572615680 1118826720 12.016 98 14 26 0 1203634160 81815184 2992256
09:20:21 31429 8192000000 10064052416 10536837120 1116750064 11.996 98 12 24 0 1153957712 34736512 2471136
09:22:10 160499 8192000000 10115079968 10597523456 1108042288 11.914 97 21 42 0 1204690448 91631360 5016800
09:27:00 49393 8192000000 10126880624 10575884288 1146506640 12.277 100 31 60 0 1206282016 53677616 6097760
09:28:55 65374 8192000000 10138518048 10650624000 1145206368 12.264 99 22 44 0 1240125680 89998912 4920400
09:31:04 47684 8192000000 10101125920 10554122240 1145523200 12.267 99 22 44 1 1173554512 23548000 4483312
09:32:52 36393 8192000000 10136474000 10599157760 1130362992 12.125 98 37 72 0 1231385584 93568176 7454416
09:34:31 52640 8192000000 10099872336 10557583360 1125430000 12.078 98 30 59 0 1202367888 70562208 6375680
09:36:24 31030 8192000000 10131408272 10557530112 1122632832 12.052 98 41 82 0 1182269872 51607264 8029776
09:38:04 75736 8192000000 10096886656 10649411584 1118927168 12.017 97 20 39 1 1213361008 90449264 3984576
09:40:30 91366 8192000000 10080280528 10515795968 1140194144 12.217 100 42 83 2 1166125680 16985344 8946192
09:43:25 88557 8192000000 10101549936 10575433728 1119043552 12.018 98 12 23 0 1180083984 58731344 2309088
09:45:54 70188 8192000000 10118641568 10589184000 1132124416 12.141 99 9 18 0 1205617072 71616016 1876640
09:48:04 63574 8192000000 10104501680 10592321536 1115901376 11.988 98 21 40 0 1186614720 66619856 4093488
09:50:46 54793 8192000000 10105292512 10578706432 1132908400 12.149 100 18 36 0 1222432512 85682000 3842112
09:52:41 45394 8192000000 10089029808 10527322112 1081100432 11.658 95 26 52 0 1134747184 47905440 5741312
09:54:31 43769 8192000000 10117303488 10562871296 1133476240 12.154 100 28 56 0 1192352704 53363456 5513008
09:56:29 88924 8192000000 10098576448 10575835136 1117810368 12.006 98 18 36 0 1184550560 62905616 3834576
09:58:58 18271 8192000000 10117489344 10543374336 1132517968 12.145 100 55 110 0 1212757584 68929936 11309680
10:00:31 105211 8192000000 10060537216 10498560000 1091360224 11.756 96 32 64 1 1132614496 34829120 6425152
10:03:24 22635 8192000000 10098434320 10541780992 1101316768 11.850 98 58 116 0 1179238752 66767616 11154368
10:04:47 55291 8192000000 10092189440 10549366784 1103272736 11.869 98 15 30 0 1165305584 58448160 3584688
10:06:43 45480 8192000000 10079630912 10541940736 1101960864 11.856 98 15 30 0 1164800496 58877360 3962272
10:09:28 47523 8192000000 10086330064 10530816000 1126115552 12.085 99 16 32 0 1173700432 44243952 3340928
10:11:21 34081 8192000000 10113774208 10562768896 1121467856 12.041 98 24 48 0 1189360336 62851168 5041312
10:13:05 37301 8192000000 10142096320 10595794944 1131806208 12.138 99 43 85 0 1255783168 115097616 8879344
10:14:56 48642 8192000000 10143913664 10641506304 1131527664 12.136 99 31 62 1 1256083728 117898592 6657472
10:16:55 55555 8192000000 10137368672 10578739200 1139968448 12.215 99 25 50 0 1235306752 90190544 5147760
10:19:44 17081 8192000000 10119957328 10554540032 1119672528 12.024 98 50 100 0 1177864208 48357712 9833968
10:21:01 45953 8192000000 10148098000 10605076480 1125398896 12.078 98 51 102 0 1208806528 73250128 10157504
10:22:49 36833 8192000000 10122631856 10572886016 1137811968 12.195 99 36 73 0 1211769152 67203392 6753792
10:24:27 67495 8192000000 10135671728 10599251968 1145389312 12.266 100 31 62 0 1231597088 80162464 6045312
10:26:44 80426 8192000000 10048282864 10515427328 1099925664 11.837 96 13 26 0 1122859664 20277024 2656976
10:29:06 77711 8192000000 10083664016 10546929664 1095835408 11.798 96 17 34 0 1140952896 41580240 3537248
10:31:25 39560 8192000000 10086218544 10548723712 1128082304 12.103 98 29 56 0 1187652240 54092128 5477808
10:33:13 76626 8192000000 10059398768 10544021504 1090431296 11.747 96 26 53 0 1171355152 74851440 6072416
10:35:33 25678 8192000000 10107663408 10542952448 1120661664 12.033 99 35 69 0 1182338720 55035680 6641376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-07-2022 10:38:37 to 25-07-2022 10:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 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 25-07-2022 10:38:54 to 25-07-2022 10:38:56.

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          216905
MON$OLDEST_ACTIVE               221822
MON$OLDEST_SNAPSHOT             221822
MON$NEXT_TRANSACTION            221822
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                       2034080
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2807 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2807 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2807 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2807 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 ---
:91: 
:92: mscs-test-161.budzdorov.ru    Mon Jul 25 10:37:21 2022
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:94: 
:95: 
:96: mscs-test-161.budzdorov.ru    Mon Jul 25 10:37:24 2022
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:98: 
:99: 
:100: mscs-test-161.budzdorov.ru    Mon Jul 25 10:37:26 2022
:101:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:102: 
:103: 
:104: mscs-test-161.budzdorov.ru    Mon Jul 25 10:37:26 2022
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:106: 
--- end of diff output ---

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

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