Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.05.2022 07:38
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 16164192 kB
MemAvailable 31355008 kB
Buffers 0 kB
Cached 15503724 kB
SwapCached 2544 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-26 07:37:16
DTS_END                         2022-05-26 07:37:16.6260
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/51536
TRN_ID                          212088
ATT_ID                          616
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: 8564.93 26276 1027792
customer order: creation 159.86 473 19183
customer order: refuse 13.59 388 1631
order to supplier: creation 41.82 1351 5018
order to supplier: removal 3.09 165522 371
invoice (draft): creation 42.69 3203 5123
invoice (draft): removal 48.76 890 5851
invoice accept: apply 38.84 26218 4661
invoice accept: cancel 44.43 1219 5331
customer reserve: creation 3565.40 252 427848
customer reserve: removal 4321.11 95 518533
realization accept: apply 77.88 283 9346
realization accept: cancel 87.68 161 10521
payment from customer: creation 54.53 171 6544
payment from customer: removal 5.21 108 625
payment to supplier: creation 44.81 181 5377
payment to supplier: removal 3.43 103 412
service: total inventory turnovers 3.70 4054 444
service: total monetary turnovers 7.15 355 858
service: aggregate perf. data 0.93 15191 111
service: refresh index statistics 0.03 305296 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-05-2022 07:38:20 to 26-05-2022 07:38:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9476
TEST_TIME 2 7165
TEST_TIME 3 7300
TEST_TIME 4 7339
TEST_TIME 5 7119
TEST_TIME 6 6839
TEST_TIME 7 6652
TEST_TIME 8 6668
TEST_TIME 9 6711
TEST_TIME 10 6970
TEST_TIME 11 7029
TEST_TIME 12 6897
TEST_TIME 13 6919
TEST_TIME 14 7061
TEST_TIME 15 7115
TEST_TIME 16 7253
TEST_TIME 17 7301
TEST_TIME 18 7328
TEST_TIME 19 7668
TEST_TIME 20 7970
TEST_TIME 21 7969
TEST_TIME 22 8072
TEST_TIME 23 8560
TEST_TIME 24 8540
TEST_TIME 25 8959
TEST_TIME 26 8959
TEST_TIME 27 9063
TEST_TIME 28 9809
TEST_TIME 29 9952
TEST_TIME 30 10721
TEST_TIME 31 10999
TEST_TIME 32 10892
TEST_TIME 33 10584
TEST_TIME 34 10434
TEST_TIME 35 10430
TEST_TIME 36 10239
TEST_TIME 37 10030
TEST_TIME 38 9931
TEST_TIME 39 9834
TEST_TIME 40 9701
TEST_TIME 41 9714
TEST_TIME 42 9649
TEST_TIME 43 9585
TEST_TIME 44 9770
TEST_TIME 45 9797
TEST_TIME 46 9769
TEST_TIME 47 9693
TEST_TIME 48 9711
TEST_TIME 49 9663
TEST_TIME 50 9666
TEST_TIME 51 9764
TEST_TIME 52 9688
TEST_TIME 53 9549
TEST_TIME 54 9464
TEST_TIME 55 9345
TEST_TIME 56 9315
TEST_TIME 57 9316
TEST_TIME 58 9328
TEST_TIME 59 9279
TEST_TIME 60 9199
TEST_TIME 61 9148
TEST_TIME 62 9101
TEST_TIME 63 9060
TEST_TIME 64 8970
TEST_TIME 65 9091
TEST_TIME 66 9073
TEST_TIME 67 9043
TEST_TIME 68 9050
TEST_TIME 69 9089
TEST_TIME 70 9039
TEST_TIME 71 9020
TEST_TIME 72 9060
TEST_TIME 73 9045
TEST_TIME 74 9164
TEST_TIME 75 9112
TEST_TIME 76 9247
TEST_TIME 77 9306
TEST_TIME 78 9267
TEST_TIME 79 9321
TEST_TIME 80 9220
TEST_TIME 81 9150
TEST_TIME 82 9105
TEST_TIME 83 9111
TEST_TIME 84 9061
TEST_TIME 85 9035
TEST_TIME 86 8985
TEST_TIME 87 8908
TEST_TIME 88 8877
TEST_TIME 89 8878
TEST_TIME 90 8887
TEST_TIME 91 8836
TEST_TIME 92 8874
TEST_TIME 93 8857
TEST_TIME 94 8836
TEST_TIME 95 8861
TEST_TIME 96 8859
TEST_TIME 97 8824
TEST_TIME 98 8880
TEST_TIME 99 8995
TEST_TIME 100 9046
TEST_TIME 101 9122
TEST_TIME 102 9110
TEST_TIME 103 9055
TEST_TIME 104 8990
TEST_TIME 105 8987
TEST_TIME 106 8976
TEST_TIME 107 8916
TEST_TIME 108 8873
TEST_TIME 109 8833
TEST_TIME 110 8816
TEST_TIME 111 8781
TEST_TIME 112 8748
TEST_TIME 113 8732
TEST_TIME 114 8677
TEST_TIME 115 8662
TEST_TIME 116 8677
TEST_TIME 117 8655
TEST_TIME 118 8621
TEST_TIME 119 8661
TEST_TIME 120 8598

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-05-2022 07:38:23 to 26-05-2022 07:38:27.

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 1422744 1422744 0 0.00 0 21629 13 0 0
doc_list_biud 1422724 1422724 0 0.00 0 37599 40 0 0
sp_add_invoice_to_stock 4797 4661 136 2.83 49 201784 26219 0 136
sp_cancel_adding_invoice 5331 5331 0 0.00 7 81082 1220 0 0
sp_cancel_client_order 1631 1631 0 0.00 6 6588 388 0 0
sp_cancel_customer_reserve 518533 518533 0 0.00 1 37600 95 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 3 5059 109 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 1636 104 0 0
sp_cancel_supplier_invoice 5866 5851 15 0.25 3 34500 891 0 15
sp_cancel_supplier_order 371 371 0 0.00 17 1418903 165523 0 0
sp_cancel_write_off 10521 10521 0 0.00 2 11875 161 0 0
sp_client_order 19183 19183 0 0.00 6 61078 474 0 0
sp_customer_reserve 427848 427848 0 0.00 1 169708 252 0 0
sp_fill_shopping_cart 29313 29313 0 0.00 2 17049 83 0 0
sp_fill_shopping_cart_clo_res 427803 427803 0 0.00 0 15033 21 0 0
sp_get_clo_for_invoice 4597 4597 0 0.00 0 25777 371 0 0
sp_kill_cost_storno 16889 16889 0 0.00 0 11999 68 0 0
sp_kill_qstorno_ret_qs2qd 1053809 1053809 0 0.00 0 37597 42 0 0
sp_kill_qty_storno 556180 556180 0 0.00 0 37599 95 0 0
sp_lock_selected_doc 19370 19370 0 0.00 0 9881 8 0 0
sp_make_cost_storno 25864 25864 0 0.00 0 18561 82 0 0
sp_multiply_rows_for_pdistr 25863 25863 0 0.00 0 21601 40 0 0
sp_multiply_rows_for_qdistr 19182 19182 0 0.00 0 61063 183 0 0
sp_pay_from_customer 6544 6544 0 0.00 6 13072 172 0 0
sp_pay_to_supplier 5377 5377 0 0.00 7 14107 182 0 0
sp_payment_common 11920 11920 0 0.00 2 7841 47 0 0
sp_qd_handle_on_cancel_clo 1631 1631 0 0.00 1 6025 318 0 0
sp_qd_handle_on_invoice_upd_sts 9927 9927 0 0.00 0 27405 475 0 0
sp_qd_handle_on_reserve_upd_sts 9346 9346 0 0.00 0 27950 68 0 0
sp_reserve_write_off 9346 9346 0 0.00 9 27982 283 0 0
sp_supplier_invoice 5123 5123 0 0.00 10 96386 3203 0 0
sp_supplier_order 5018 5018 0 0.00 7 30089 1351 0 0
srv_aggregate_perf_data 111 111 0 0.00 2074 146315 15192 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 689 211237 41988 0 0
srv_find_qd_qs_mism 982999 982999 0 0.00 0 12220 10 0 0
srv_make_invnt_saldo 444 444 0 0.00 70 75373 4055 0 0
srv_make_money_saldo 858 858 0 0.00 1 13362 356 0 0
srv_recalc_idx_stat 4 4 0 0.00 102420 486468 305296 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4665 4665 0 0.00 1 16181 924 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 1 6826 536 0 0
t$perf-norm:sp_cancel_client_order 1631 1631 0 0.00 0 4105 28 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 1161 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 14206 43 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 301 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 860 35 0 0
t$perf-norm:sp_cancel_supplier_order 371 371 0 0.00 0 90417 6380 0 0
t$perf-norm:sp_cancel_write_off 934 934 0 0.00 0 7116 23 0 0
t$perf-norm:sp_client_order 19183 19183 0 0.00 0 7674 36 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 1369 15 0 0
t$perf-norm:sp_pay_from_customer 6544 6544 0 0.00 0 2914 16 0 0
t$perf-norm:sp_pay_to_supplier 5377 5377 0 0.00 0 14200 25 0 0
t$perf-norm:sp_reserve_write_off 9346 9346 0 0.00 0 8864 25 0 0
t$perf-norm:sp_supplier_invoice 5123 5123 0 0.00 0 15774 37 0 0
t$perf-norm:sp_supplier_order 5019 5019 0 0.00 0 4141 28 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 229 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 877 20 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 14976 48 0 0
t$perf-norm:srv_make_money_saldo 858 858 0 0.00 0 546 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 130 48 0 0
v_add_invoice_to_stock 9456 9184 272 2.87 0 4967 23 0 272
v_all_customers 35090 35090 0 0.00 0 123 3 0 0
v_all_suppliers 27970 27970 0 0.00 0 72 4 0 0
v_all_wares 38360 38360 0 0.00 0 90 4 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 4203 18 0 0
v_cancel_client_order 3262 3262 0 0.00 0 2993 10 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 1599 10 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 410 9 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1590 11 0 0
v_cancel_supplier_invoice 828 798 30 3.62 0 4866 29 0 30
v_cancel_supplier_order 742 742 0 0.00 0 787 12 0 0
v_cancel_write_off 1868 1868 0 0.00 0 1598 23 0 0
v_max_id_clo_ord 5013 5013 0 0.00 0 464 5 0 0
v_max_id_clo_res 965 965 0 0.00 0 3066 15 0 0
v_max_id_ord_sup 5118 5118 0 0.00 0 4859 6 0 0
v_max_non_paid_invoice 1523 1523 0 0.00 0 150 7 0 0
v_max_non_paid_realizn 3354 3354 0 0.00 0 676 7 0 0
v_min_id_clo_ord 5013 5013 0 0.00 0 2941 10 0 0
v_min_id_clo_res 965 965 0 0.00 0 5839 19 0 0
v_min_id_ord_sup 5118 5118 0 0.00 0 6721 11 0 0
v_min_non_paid_invoice 5377 5377 0 0.00 0 7835 21 0 0
v_min_non_paid_realizn 6543 6543 0 0.00 0 7724 20 0 0
v_our_firm 9648 9648 0 0.00 0 56 4 0 0
v_reserve_write_off 18692 18692 0 0.00 0 2874 9 0 0
x_lock_depdocs_on_canc_invoice 10783 10783 0 0.00 0 21660 126 0 0
x_lock_depdocs_on_canc_sup_ord 371 371 0 0.00 0 3148 303 0 0
x_make_qstorno_1000_1200 5018 5018 0 0.00 2 29923 1187 0 0
x_make_qstorno_1200_2000 5123 5123 0 0.00 5 96351 2968 0 0
x_make_qstorno_2100_3300 427846 427846 0 0.00 0 169704 219 0 0

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

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:20 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:20 26606 8192000000 9724499328 10024034304 798587120 8.882 100 84 168 0 826135792 7283472 20265200
05:09:51 24477 8192000000 9771156848 10079723520 843330032 9.333 100 90 180 0 878336656 13460288 21546336
05:11:21 12986 8192000000 9807124704 10127626240 871145712 9.611 100 87 174 0 912331152 20844736 20340704
05:12:36 9690 8192000000 9863453456 10200174592 920822432 10.104 100 81 162 1 975918976 37398896 17697648
05:13:51 6548 8192000000 9933219568 10281410560 965886576 10.547 100 87 174 0 1048053296 63525680 18641040
05:15:02 1972 8192000000 9968256192 10331262976 998531760 10.864 100 88 167 0 1092799632 77324176 16943696
05:16:07 1253 8192000000 9992771152 10356150272 1026185408 11.132 100 95 184 0 1105262704 59617184 19460112
05:17:10 1553 8192000000 10010991520 10382475264 1046442192 11.327 100 89 174 0 1137573408 73646096 17485120
05:18:14 1039 8192000000 10039538208 10417954816 1060515680 11.461 100 96 188 2 1160615088 81489744 18609664
05:19:16 1320 8192000000 10040477152 10422325248 1070481520 11.557 100 94 177 1 1163272064 75014928 17775616
05:20:19 1049 8192000000 10074929648 10458464256 1075293856 11.603 100 93 183 3 1190280560 96442384 18544320
05:21:21 1310 8192000000 10067173552 10446327808 1078324704 11.632 100 90 171 0 1187032576 91969920 16737952
05:22:24 1206 8192000000 10049902944 10437591040 1087982048 11.723 100 94 181 0 1172714832 65289616 19443168
05:23:27 888 8192000000 10063414800 10448228352 1089271264 11.736 100 99 181 1 1180289984 72967792 18050928
05:24:29 1099 8192000000 10069257712 10458927104 1096757792 11.807 100 94 175 0 1192442016 77278336 18405888
05:25:31 1396 8192000000 10059046768 10450649088 1101046256 11.848 100 95 181 0 1180857968 61590944 18220768
05:26:33 1072 8192000000 10057075520 10448822272 1100951888 11.847 100 92 187 1 1176804208 57409824 18442496
05:27:35 1542 8192000000 10061461168 10450112512 1088920064 11.732 100 97 185 1 1183616336 76354912 18341360
05:28:38 1392 8192000000 10050559600 10443571200 1093699520 11.778 100 100 186 4 1171506000 59495744 18310736
05:29:40 1191 8192000000 10037423600 10423468032 1085051296 11.696 100 92 166 2 1162503248 59125696 18326256
05:30:44 1520 8192000000 10027634240 10413359104 1078317616 11.631 100 85 152 1 1149398256 56571248 14509392
05:31:46 1045 8192000000 10032913584 10411122688 1070827952 11.560 100 94 184 0 1154120464 63893216 19399296
05:32:47 849 8192000000 10004481136 10386386944 1059159168 11.448 100 78 155 1 1126511040 50833600 16518272
05:33:49 968 8192000000 10012696832 10391564288 1052483968 11.384 100 89 172 0 1132555440 62477984 17593488
05:34:50 1054 8192000000 9976488432 10354380800 1037821728 11.244 100 84 168 2 1100053696 44768944 17463024
05:35:52 892 8192000000 9987850976 10366865408 1039598432 11.261 99 85 151 2 1106132400 48459360 18074608
05:36:54 951 8192000000 9979948800 10357481472 1042228384 11.286 100 93 176 0 1100987824 40204800 18554640
05:38:03 21867 8192000000 10047097120 10435952640 1062871520 11.484 100 64 123 1 1152835088 78189168 11774400
05:39:36 8262 8192000000 10032446208 10413834240 1075532288 11.605 100 74 144 1 1145231920 54755552 14944080
05:40:51 1123 8192000000 10082498800 10467336192 1090030912 11.743 100 97 189 2 1196159264 86415536 19712816
05:41:56 685 8192000000 10086907328 10478530560 1109187504 11.925 100 88 165 0 1208056000 78912112 19956384
05:43:00 2033 8192000000 10107700576 10504314880 1124252800 12.067 100 84 161 3 1226050288 84403568 17393920
05:44:06 5639 8192000000 10165799056 10564513792 1139603968 12.212 100 68 134 0 1283324160 129448608 14271584
05:45:20 1392 8192000000 10214065648 10605596672 1150792560 12.317 100 92 176 0 1324213232 154774112 18646560
05:46:24 762 8192000000 10254608800 10650284032 1158125200 12.386 100 93 178 1 1365584000 188223536 19235264
05:47:30 3309 8192000000 10277670784 10679656448 1160692448 12.410 100 76 149 0 1389014240 211741952 16579840
05:48:39 2283 8192000000 10282640784 10704838656 1163821376 12.439 100 78 154 0 1392989920 212313184 16855360
05:49:46 2788 8192000000 10313072624 10712289280 1171392736 12.510 100 82 156 1 1420476416 231846688 17236992
05:50:54 924 8192000000 10355327808 10764521472 1188476688 12.669 100 97 190 2 1468931136 259762672 20691776
05:51:58 1170 8192000000 10365833424 10778296320 1191879872 12.701 100 85 167 0 1478711584 267660400 19171312
05:53:01 1356 8192000000 10423681808 10842472448 1202107424 12.796 100 92 177 0 1533785440 311396384 20281632
05:54:05 1489 8192000000 10480335488 10895421440 1207342384 12.844 100 96 186 3 1585841904 356362304 22137216
05:55:10 880 8192000000 10498995824 10920714240 1197789040 12.756 100 90 176 1 1600876048 381210192 21876816
05:56:16 1529 8192000000 10507876880 10937147392 1191721056 12.699 100 97 185 0 1612324160 397850560 22752544
05:57:20 986 8192000000 10501644512 10935623680 1187529648 12.660 100 98 194 0 1600638416 389922576 23186192
05:58:24 933 8192000000 10518717008 10942640128 1191750592 12.700 100 91 177 1 1617792048 404355712 21685744
05:59:26 831 8192000000 10515883264 10957119488 1184215904 12.629 100 96 190 0 1624504768 417431744 22857120
06:00:28 2987 8192000000 10492784736 10970284032 1192604288 12.708 100 61 120 0 1603184640 395699952 14880400
06:01:33 18357 8192000000 10511865392 10955624448 1173390992 12.529 99 39 78 1 1599030000 415417184 10221824
06:02:55 15907 8192000000 10423821280 10921566208 1151183024 12.321 99 34 68 0 1524261952 364401376 8677552
06:04:12 26500 8192000000 10398950464 10888069120 1134636704 12.165 99 24 48 1 1517178720 375966960 6575056
06:05:41 47864 8192000000 10286656256 10780393472 1151246800 12.321 100 19 38 1 1381747840 225497840 5003200
06:07:33 85512 8192000000 10071338352 10570862592 1119025328 12.018 97 10 20 1 1217856640 96826256 2005056
06:09:59 79711 8192000000 10106587632 10538938368 1133286192 12.152 99 55 94 4 1193593792 49644688 10662912
06:12:20 75824 8192000000 10058603376 10516164608 1094208800 11.783 96 12 24 0 1124065024 27162144 2694080
06:14:37 76755 8192000000 10119848768 10571362304 1145261664 12.265 100 24 48 0 1221091648 70435152 5394832
06:17:08 63243 8192000000 10106424512 10592911360 1120777168 12.034 98 18 36 0 1212721568 87466064 4478336
06:19:19 63020 8192000000 10151790832 10605199360 1142531040 12.239 100 14 28 1 1234593456 88785008 3277408
06:21:23 118352 8192000000 10098882784 10584223744 1111210624 11.944 98 10 20 0 1203373168 89865440 2297104
06:25:17 76014 8192000000 10082519040 10505474048 1090535952 11.748 97 25 47 1 1108502368 12925856 5040560
06:27:39 189624 8192000000 10046595200 10494341120 1106082128 11.895 98 15 30 0 1199678560 89682272 3914160
06:31:49 136551 8192000000 10077318896 10514096128 1045219712 11.315 93 41 82 2 1128538624 73992832 9326080
06:35:10 55365 8192000000 10024168352 10458566656 1078218448 11.630 97 19 38 1 1104947984 22526560 4202976
06:37:07 211237 8192000000 10122596448 10580348928 1121877712 12.045 99 32 58 2 1187462112 59127376 6457024
06:41:56 66733 8192000000 10058161264 10553057280 1107533312 11.909 98 9 18 0 1147004048 37910160 1560576
06:44:03 44689 8192000000 10063576368 10506731520 1095865824 11.798 97 22 44 1 1123383024 23375584 4141616
06:45:49 27773 8192000000 10096702608 10539601920 1135122800 12.170 100 32 64 0 1213220080 70900016 7197264
06:47:26 14816 8192000000 10145247584 10581381120 1137263728 12.190 99 42 84 1 1236493296 90114544 9115024
06:48:44 27161 8192000000 10144904592 10590126080 1110118752 11.934 97 32 64 1 1236052560 118938080 6995728
06:50:21 40524 8192000000 10199360432 10640818176 1145658000 12.269 100 31 62 1 1283929680 131406928 6864752
06:52:02 57811 8192000000 10192291792 10642120704 1135399680 12.172 99 28 54 1 1264548272 123095456 6053136
06:54:14 61584 8192000000 10089188560 10535784448 1106638944 11.901 97 21 42 0 1134534432 23750640 4144848
06:56:17 81181 8192000000 10100236544 10561896448 1092291728 11.764 96 20 40 0 1142612752 46485888 3835136
06:59:09 46190 8192000000 10092281968 10532945920 1122373744 12.049 99 33 66 0 1191298128 62367200 6557184
07:01:00 69367 8192000000 10069878704 10552213504 1107241328 11.906 98 11 22 0 1156771168 47021200 2508640
07:03:09 77238 8192000000 10077180256 10530869248 1096104640 11.801 97 17 34 0 1149146448 49563520 3478288
07:05:38 74906 8192000000 10100312480 10589601792 1120384160 12.031 99 12 24 0 1201410000 78304704 2721136
07:08:03 51445 8192000000 10117360320 10558042112 1115786576 11.987 98 16 32 0 1196020032 76487552 3745904
07:09:58 37099 8192000000 10125648208 10569891840 1135147456 12.170 99 18 36 2 1204342672 65210192 3985024
07:11:35 29282 8192000000 10169657360 10621988864 1125290432 12.077 97 39 77 4 1249342304 115298256 8753616
07:13:10 18167 8192000000 10204274800 10642182144 1131388848 12.134 98 41 82 0 1269752496 129265312 9098336
07:14:43 17218 8192000000 10245673184 10675519488 1145762736 12.270 100 42 84 3 1329038096 174008576 9266784
07:16:05 36262 8192000000 10167201872 10676920320 1124300208 12.068 99 29 56 1 1294639008 164237840 6100960
07:18:11 48588 8192000000 10070000720 10573398016 1085096112 11.696 96 28 55 4 1162674960 72080400 5498448
07:20:05 12586 8192000000 10091869200 10519773184 1124042224 12.065 99 61 119 1 1174545824 37067552 13436048
07:21:18 35176 8192000000 10106973424 10542284800 1126519696 12.089 100 52 102 0 1207049264 70309280 10220288
07:22:53 68013 8192000000 10065575296 10543374336 1133889440 12.158 100 14 27 2 1196506768 59810144 2807184
07:25:02 99728 8192000000 10063000656 10529882112 1112491776 11.956 98 7 14 0 1167839168 53507760 1839632
07:28:45 31434 8192000000 10064886768 10524086272 1134411312 12.163 100 15 30 0 1164836176 26829872 3594992
07:30:25 82291 8192000000 10078994720 10542678016 1113772128 11.968 98 13 26 0 1166727584 50350096 2605360
07:33:15 76630 8192000000 10042245152 10503725056 1116445712 11.993 98 8 11 0 1150923824 33804432 673680
07:35:37 23030 8192000000 10104820560 10538115072 1117318944 12.002 98 44 88 0 1188531376 62280272 8932160
07:37:12 10979 8192000000 9178241424 9459802112 325957936 3.826 29 13 23 1 346496048 18037008 2501104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-05-2022 07:38:34 to 26-05-2022 07:38:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-05-2022 07:38:51 to 26-05-2022 07:38:53.

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          212840
MON$OLDEST_ACTIVE               212841
MON$OLDEST_SNAPSHOT             212841
MON$NEXT_TRANSACTION            212841
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                       2064544
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             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 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 ---
--- end of diff output ---

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

26.05.22 07:38:54. 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-26 07:38:56 No severe PSQL-related problems occured
26.05.22 07:38:57. End of report.