Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.07.2022 04: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 15762812 kB
MemAvailable 31049820 kB
Buffers 20 kB
Cached 15904916 kB
SwapCached 2492 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-20 04:37:19
DTS_END                         2022-07-20 04:37:19.5520
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/42568
TRN_ID                          223638
ATT_ID                          598
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: 8579.81 24185 1029577
customer order: creation 173.15 486 20778
customer order: refuse 14.43 431 1732
order to supplier: creation 46.25 1402 5550
order to supplier: removal 3.20 161811 384
invoice (draft): creation 45.94 3474 5513
invoice (draft): removal 47.53 1032 5704
invoice accept: apply 41.02 27045 4922
invoice accept: cancel 42.74 1191 5129
customer reserve: creation 3720.63 262 446476
customer reserve: removal 4159.29 97 499115
realization accept: apply 83.33 300 10000
realization accept: cancel 71.92 171 8630
payment from customer: creation 59.42 198 7130
payment from customer: removal 5.65 153 678
payment to supplier: creation 49.18 223 5901
payment to supplier: removal 3.53 131 423
service: total inventory turnovers 4.07 3919 488
service: total monetary turnovers 7.34 382 881
service: aggregate perf. data 1.14 12746 137
service: refresh index statistics 0.05 268244 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-07-2022 04:38:44 to 20-07-2022 04:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7667
TEST_TIME 2 6439
TEST_TIME 3 6483
TEST_TIME 4 6494
TEST_TIME 5 6441
TEST_TIME 6 7057
TEST_TIME 7 7028
TEST_TIME 8 7037
TEST_TIME 9 6858
TEST_TIME 10 6662
TEST_TIME 11 6885
TEST_TIME 12 6868
TEST_TIME 13 7535
TEST_TIME 14 7638
TEST_TIME 15 7784
TEST_TIME 16 7812
TEST_TIME 17 7798
TEST_TIME 18 8086
TEST_TIME 19 8296
TEST_TIME 20 8273
TEST_TIME 21 8455
TEST_TIME 22 8392
TEST_TIME 23 8468
TEST_TIME 24 8400
TEST_TIME 25 8346
TEST_TIME 26 8429
TEST_TIME 27 8615
TEST_TIME 28 8729
TEST_TIME 29 8819
TEST_TIME 30 9389
TEST_TIME 31 10201
TEST_TIME 32 10470
TEST_TIME 33 10853
TEST_TIME 34 10967
TEST_TIME 35 11163
TEST_TIME 36 10959
TEST_TIME 37 10754
TEST_TIME 38 10612
TEST_TIME 39 10434
TEST_TIME 40 10298
TEST_TIME 41 10155
TEST_TIME 42 10059
TEST_TIME 43 9998
TEST_TIME 44 9784
TEST_TIME 45 9724
TEST_TIME 46 9613
TEST_TIME 47 9528
TEST_TIME 48 9470
TEST_TIME 49 9352
TEST_TIME 50 9262
TEST_TIME 51 9216
TEST_TIME 52 9097
TEST_TIME 53 9085
TEST_TIME 54 9142
TEST_TIME 55 9080
TEST_TIME 56 9081
TEST_TIME 57 9139
TEST_TIME 58 9159
TEST_TIME 59 9118
TEST_TIME 60 9109
TEST_TIME 61 9129
TEST_TIME 62 9133
TEST_TIME 63 9090
TEST_TIME 64 9005
TEST_TIME 65 8971
TEST_TIME 66 9098
TEST_TIME 67 9127
TEST_TIME 68 9037
TEST_TIME 69 8940
TEST_TIME 70 8929
TEST_TIME 71 8974
TEST_TIME 72 8930
TEST_TIME 73 8864
TEST_TIME 74 8843
TEST_TIME 75 8833
TEST_TIME 76 8854
TEST_TIME 77 8822
TEST_TIME 78 8804
TEST_TIME 79 8802
TEST_TIME 80 8837
TEST_TIME 81 8852
TEST_TIME 82 8914
TEST_TIME 83 8971
TEST_TIME 84 9045
TEST_TIME 85 9034
TEST_TIME 86 9009
TEST_TIME 87 8951
TEST_TIME 88 8893
TEST_TIME 89 8902
TEST_TIME 90 8918
TEST_TIME 91 8915
TEST_TIME 92 8883
TEST_TIME 93 8844
TEST_TIME 94 8814
TEST_TIME 95 8772
TEST_TIME 96 8688
TEST_TIME 97 8758
TEST_TIME 98 8773
TEST_TIME 99 8781
TEST_TIME 100 8762
TEST_TIME 101 8800
TEST_TIME 102 8807
TEST_TIME 103 8795
TEST_TIME 104 8774
TEST_TIME 105 8804
TEST_TIME 106 8881
TEST_TIME 107 8851
TEST_TIME 108 8867
TEST_TIME 109 8868
TEST_TIME 110 8884
TEST_TIME 111 8859
TEST_TIME 112 8851
TEST_TIME 113 8797
TEST_TIME 114 8781
TEST_TIME 115 8739
TEST_TIME 116 8710
TEST_TIME 117 8673
TEST_TIME 118 8640
TEST_TIME 119 8670
TEST_TIME 120 8612

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-07-2022 04:38:48 to 20-07-2022 04:38:51.

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 1441736 1441736 0 0.00 0 23641 14 0 0
doc_list_biud 1441720 1441720 0 0.00 0 81538 40 0 0
sp_add_invoice_to_stock 5072 4922 150 2.95 81 202680 27046 0 150
sp_cancel_adding_invoice 5129 5129 0 0.00 7 83076 1191 0 0
sp_cancel_client_order 1732 1732 0 0.00 6 19646 431 0 0
sp_cancel_customer_reserve 499115 499115 0 0.00 1 25037 98 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 4 12774 153 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 4 2567 131 0 0
sp_cancel_supplier_invoice 5716 5704 12 0.20 2 81546 1032 0 12
sp_cancel_supplier_order 384 384 0 0.00 17 1372897 161811 0 0
sp_cancel_write_off 8630 8630 0 0.00 2 12591 171 0 0
sp_client_order 20778 20778 0 0.00 5 23405 486 0 0
sp_customer_reserve 446476 446476 0 0.00 1 58864 263 0 0
sp_fill_shopping_cart 31828 31828 0 0.00 1 16006 90 0 0
sp_fill_shopping_cart_clo_res 446440 446440 0 0.00 0 22781 20 0 0
sp_get_clo_for_invoice 4877 4877 0 0.00 0 30812 359 0 0
sp_kill_cost_storno 14860 14860 0 0.00 0 16397 73 0 0
sp_kill_qstorno_ret_qs2qd 1012948 1012948 0 0.00 0 81536 42 0 0
sp_kill_qty_storno 535568 535568 0 0.00 0 81537 98 0 0
sp_lock_selected_doc 20663 20663 0 0.00 0 5966 6 0 0
sp_make_cost_storno 27907 27907 0 0.00 0 23636 85 0 0
sp_multiply_rows_for_pdistr 27905 27905 0 0.00 0 18050 44 0 0
sp_multiply_rows_for_qdistr 20778 20778 0 0.00 0 13202 177 0 0
sp_pay_from_customer 7130 7130 0 0.00 5 15132 199 0 0
sp_pay_to_supplier 5901 5901 0 0.00 6 18068 224 0 0
sp_payment_common 13028 13028 0 0.00 2 5864 52 0 0
sp_qd_handle_on_cancel_clo 1732 1732 0 0.00 0 19633 345 0 0
sp_qd_handle_on_invoice_upd_sts 10003 10003 0 0.00 0 30873 524 0 0
sp_qd_handle_on_reserve_upd_sts 10000 10000 0 0.00 0 14687 63 0 0
sp_reserve_write_off 10000 10000 0 0.00 8 23653 301 0 0
sp_supplier_invoice 5513 5513 0 0.00 10 86202 3474 0 0
sp_supplier_order 5550 5550 0 0.00 8 35174 1402 0 0
srv_aggregate_perf_data 137 137 0 0.00 2474 141353 12747 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 752 152465 36123 0 0
srv_find_qd_qs_mism 981601 981601 0 0.00 0 13531 11 0 0
srv_make_invnt_saldo 488 488 0 0.00 53 50825 3920 0 0
srv_make_money_saldo 881 881 0 0.00 1 13189 382 0 0
srv_recalc_idx_stat 6 6 0 0.00 64848 493205 268245 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4930 4930 0 0.00 1 12242 1020 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 14748 505 0 0
t$perf-norm:sp_cancel_client_order 1732 1732 0 0.00 0 2260 28 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 1566 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 350 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 413 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 7974 73 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 70294 6718 0 0
t$perf-norm:sp_cancel_write_off 1057 1057 0 0.00 0 1058 16 0 0
t$perf-norm:sp_client_order 20779 20779 0 0.00 0 14596 39 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 872 14 0 0
t$perf-norm:sp_pay_from_customer 7130 7130 0 0.00 0 5306 23 0 0
t$perf-norm:sp_pay_to_supplier 5901 5901 0 0.00 0 4839 19 0 0
t$perf-norm:sp_reserve_write_off 10000 10000 0 0.00 0 4828 25 0 0
t$perf-norm:sp_supplier_invoice 5513 5513 0 0.00 0 7526 32 0 0
t$perf-norm:sp_supplier_order 5550 5550 0 0.00 0 3382 31 0 0
t$perf-norm:srv_aggregate_perf_data 137 137 0 0.00 0 898 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 165 8 0 0
t$perf-norm:srv_make_invnt_saldo 488 488 0 0.00 0 1400 21 0 0
t$perf-norm:srv_make_money_saldo 881 881 0 0.00 0 1329 7 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 2 178 92 0 0
v_add_invoice_to_stock 10038 9738 300 2.98 0 5873 32 0 300
v_all_customers 38002 38002 0 0.00 0 121 4 0 0
v_all_suppliers 30416 30416 0 0.00 0 74 4 0 0
v_all_wares 41552 41552 0 0.00 0 70 4 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 3625 18 0 0
v_cancel_client_order 3464 3464 0 0.00 0 5654 17 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 6391 22 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 5226 21 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1304 14 0 0
v_cancel_supplier_invoice 864 840 24 2.77 0 5842 41 0 24
v_cancel_supplier_order 768 768 0 0.00 0 3417 22 0 0
v_cancel_write_off 2114 2114 0 0.00 0 5958 27 0 0
v_max_id_clo_ord 5545 5545 0 0.00 0 438 5 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 4752 24 0 0
v_max_id_ord_sup 5504 5504 0 0.00 0 8118 10 0 0
v_max_non_paid_invoice 1741 1741 0 0.00 0 1419 9 0 0
v_max_non_paid_realizn 3772 3772 0 0.00 0 4102 11 0 0
v_min_id_clo_ord 5545 5545 0 0.00 0 2932 9 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 3099 11 0 0
v_min_id_ord_sup 5504 5504 0 0.00 0 3937 10 0 0
v_min_non_paid_invoice 5900 5900 0 0.00 0 5853 23 0 0
v_min_non_paid_realizn 7128 7128 0 0.00 0 4693 21 0 0
v_our_firm 10262 10262 0 0.00 0 70 5 0 0
v_reserve_write_off 19998 19998 0 0.00 0 5309 16 0 0
x_lock_depdocs_on_canc_invoice 10413 10413 0 0.00 0 16978 117 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 8202 362 0 0
x_make_qstorno_1000_1200 5550 5550 0 0.00 2 35086 1225 0 0
x_make_qstorno_1200_2000 5513 5513 0 0.00 5 84301 3215 0 0
x_make_qstorno_2100_3300 446476 446476 0 0.00 0 58860 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-07-2022 04:38:52 to 20-07-2022 04:38:56.

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
02:07:23 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:25 22198 8192000000 9729476000 10027073536 805790240 8.955 100 92 184 0 836073088 7676144 22606704
02:09:50 14144 8192000000 9757085984 10062856192 833421744 9.234 100 88 176 0 866144352 12200848 20521760
02:11:06 17907 8192000000 9788057712 10107101184 858851072 9.489 100 86 172 0 897241696 18657552 19733072
02:12:30 5853 8192000000 9856833280 10187210752 910367856 10.001 100 82 163 0 966945776 38698320 17879600
02:13:41 3138 8192000000 9915744464 10254319616 954091872 10.431 100 85 170 0 1025135744 52764880 18278992
02:14:47 2695 8192000000 9961777248 10316083200 992195536 10.803 100 81 162 0 1079962176 71198576 16568064
02:15:53 1658 8192000000 9989696128 10352410624 1015670256 11.030 100 84 163 1 1118723408 86847696 16205456
02:16:58 1274 8192000000 10009070240 10375442432 1035207984 11.219 100 81 143 3 1128010672 79892816 12909872
02:18:01 1168 8192000000 10036012688 10416492544 1062410928 11.480 100 92 176 1 1161473408 80998992 18063488
02:19:04 1134 8192000000 10043414192 10427707392 1074149872 11.592 100 97 183 0 1164976912 70423408 20403632
02:20:06 1160 8192000000 10068143872 10455158784 1084492480 11.690 100 89 173 0 1188457536 86431424 17533632
02:21:09 1007 8192000000 10060475664 10443059200 1087810432 11.722 100 98 191 0 1179739568 73548640 18380496
02:22:11 2036 8192000000 10042766752 10439495680 1085950672 11.704 100 65 127 0 1164231120 65717584 12562864
02:23:14 948 8192000000 10064202656 10455138304 1095104160 11.791 100 88 170 1 1186878400 74821840 16952400
02:24:17 1237 8192000000 10092580272 10481463296 1097002192 11.809 100 96 186 1 1211504464 96029776 18472496
02:25:19 1212 8192000000 10081599376 10471497728 1098238304 11.821 100 99 186 1 1204184704 87088992 18857408
02:26:21 1145 8192000000 10074951728 10466799616 1103587920 11.872 100 98 187 3 1196823984 73960640 19275424
02:27:23 2063 8192000000 10067276256 10464088064 1102510640 11.861 100 78 150 1 1194987840 77633312 14843888
02:28:26 1035 8192000000 10034670352 10423160832 1082743696 11.674 99 92 172 3 1157978128 57188704 18045728
02:29:29 3702 8192000000 10037135840 10430726144 1079341088 11.641 100 68 121 1 1163809424 71925696 12542640
02:30:35 1316 8192000000 10026488304 10404421632 1061433408 11.470 99 95 184 1 1148171744 68388928 18349408
02:31:37 1331 8192000000 10022557200 10407886848 1066195248 11.516 100 75 141 1 1147500080 66292832 15012000
02:32:39 902 8192000000 9984198416 10361888768 1053680832 11.396 100 85 162 4 1112414512 42762416 15971264
02:33:41 1054 8192000000 9979729600 10352979968 1048230432 11.344 100 94 174 3 1104747824 39122432 17394960
02:34:42 1077 8192000000 10014893072 10396315648 1048059136 11.342 100 93 179 2 1135137536 68244464 18833936
02:35:45 1130 8192000000 10002161440 10377932800 1040757200 11.272 99 89 175 0 1126232624 66645696 18829728
02:36:47 967 8192000000 9959343360 10333396992 1033863120 11.206 100 92 166 1 1086313696 34657616 17792960
02:37:54 23138 8192000000 9981749936 10363785216 1051718576 11.377 100 67 128 0 1108136800 43801440 12616784
02:39:23 2821 8192000000 10029609040 10404683776 1060356192 11.460 99 82 156 0 1138476064 62467760 15652112
02:40:34 11470 8192000000 10047084272 10442211328 1084190640 11.687 100 68 130 1 1175369840 77468944 13710256
02:41:49 1222 8192000000 10108324560 10493337600 1103135808 11.867 100 92 179 3 1230411888 107741952 19534128
02:42:53 4113 8192000000 10136011040 10534727680 1120116096 12.028 100 77 146 1 1254044208 118860480 15067632
02:44:01 1294 8192000000 10150637600 10553221120 1135614480 12.174 100 94 177 0 1263140192 108103840 19421872
02:45:04 5777 8192000000 10163101136 10568134656 1125907520 12.083 100 80 157 1 1276775600 133838528 17029552
02:46:14 7701 8192000000 10175309040 10582061056 1137965632 12.196 100 55 108 1 1287999712 138202016 11832064
02:47:29 1410 8192000000 10239420832 10634571776 1151478304 12.323 100 90 172 0 1345346448 174763472 19104672
02:48:33 2018 8192000000 10259496864 10657816576 1149664400 12.306 100 95 182 1 1372951696 202910832 20376464
02:49:39 2469 8192000000 10273796096 10680033280 1151342960 12.322 100 80 156 1 1384483728 214957616 18183152
02:50:44 997 8192000000 10265120448 10710110208 1154020608 12.347 100 93 183 0 1376398832 202328560 20049664
02:51:47 2217 8192000000 10276836448 10690273280 1169726928 12.494 100 91 176 3 1382831456 194350016 18754512
02:52:52 967 8192000000 10268339456 10677706752 1161132560 12.414 100 96 172 1 1375458384 194164048 20161776
02:53:57 987 8192000000 10310270832 10713321472 1177952720 12.571 100 96 188 2 1417913200 218069024 21891456
02:55:01 945 8192000000 10326800288 10756329472 1172667456 12.522 100 99 186 2 1434918928 241455872 20795600
02:56:04 777 8192000000 10331437616 10747113472 1188050176 12.665 100 95 185 0 1437661776 228284608 21326992
02:57:07 864 8192000000 10389063152 10798231552 1205728224 12.829 100 99 186 0 1496729568 269470720 21530624
02:58:11 1160 8192000000 10391422512 10811154432 1193143648 12.713 100 97 188 2 1499613632 283969024 22500960
02:59:16 744 8192000000 10443032944 10861117440 1211162336 12.880 100 95 185 1 1547545216 314817584 21565296
03:00:20 953 8192000000 10516033360 10925289472 1219485552 12.957 100 99 198 0 1619080272 377015024 22579696
03:01:23 1264 8192000000 10543944704 10955022336 1216513504 12.929 100 98 192 0 1650929008 411586880 22828624
03:02:28 1192 8192000000 10616157616 11032571904 1203687600 12.811 100 89 165 1 1715261536 492146368 19427568
03:03:33 1065 8192000000 10609168720 11021479936 1202160800 12.796 100 98 189 0 1709787360 483347728 24278832
03:04:37 3997 8192000000 10587224240 11056439296 1188474000 12.669 100 60 120 0 1692608112 488135968 15998144
03:05:43 8240 8192000000 10576218320 11040997376 1183319264 12.621 100 48 94 0 1677807840 481922224 12566352
03:07:07 11832 8192000000 10471658848 11007467520 1138669360 12.203 98 44 89 1 1598707744 447984352 12054032
03:08:26 13321 8192000000 10351310096 10911948800 1150122176 12.311 100 34 68 0 1491103376 332120640 8860560
03:09:45 18148 8192000000 10196421776 10675597312 1129270096 12.114 98 26 52 0 1292013664 156789280 5954288
03:11:04 66835 8192000000 10111982224 10630422528 1122132832 12.047 98 11 22 0 1291428272 166662480 2632960
03:13:11 41193 8192000000 10051969168 10478637056 1112344304 11.955 98 43 81 4 1135946352 14240640 9361408
03:14:53 30352 8192000000 10079069200 10512056320 1127677552 12.099 99 46 82 0 1171722208 36680064 7364592
03:16:50 93630 8192000000 10134964128 10615160832 1143423840 12.248 100 14 26 0 1212309408 66114240 2771328
03:19:24 82483 8192000000 10072753568 10537316352 1101070208 11.848 96 17 35 1 1183806592 79193536 3542848
03:21:51 78092 8192000000 10074104624 10559082496 1130299216 12.124 99 17 34 0 1166119872 32748176 3072480
03:24:11 33630 8192000000 10104787568 10544635904 1151670160 12.325 100 56 106 0 1190689248 26616224 12402864
03:25:51 22278 8192000000 10100536656 10551771136 1155747264 12.363 100 60 110 2 1203821376 36424928 11649184
03:27:28 71227 8192000000 10103914160 10565443584 1116502736 11.994 97 20 38 0 1190999760 70907920 3589104
03:29:56 97487 8192000000 10066422304 10548768768 1128424768 12.107 99 17 31 0 1184407216 52317440 3665008
03:32:34 37472 8192000000 10104489552 10552283136 1109384704 11.927 98 22 37 1 1182372688 68211120 4776864
03:34:12 69226 8192000000 10086958256 10545180672 1121832576 12.044 99 20 41 0 1215901808 89355296 4713936
03:36:22 40900 8192000000 10125856016 10577739776 1087991696 11.724 96 39 78 1 1176139872 80541680 7606496
03:38:03 127396 8192000000 10056018416 10528763904 1123860592 12.063 99 22 42 0 1176663296 48788976 4013728
03:41:11 18943 8192000000 10077037984 10506641408 1108831088 11.921 97 37 71 1 1149703952 33457552 7415312
03:42:33 35422 8192000000 10164800656 10620661760 1142309232 12.237 100 32 64 0 1249192000 100118432 6764336
03:44:12 52778 8192000000 10096246560 10534256640 1118601152 12.014 97 22 44 1 1153622128 30932528 4088448
03:46:06 95362 8192000000 10122700880 10601009152 1137725040 12.194 99 39 78 0 1204236208 57880160 8631008
03:48:42 62937 8192000000 10069703696 10538999808 1138140912 12.198 99 15 28 0 1177621152 36933520 2546720
03:52:57 26144 8192000000 10071307872 10499706880 1070063120 11.553 95 23 46 0 1114476048 39774480 4638448
03:54:26 34058 8192000000 10107661504 10546544640 1114087376 11.971 98 32 62 0 1177915856 56979648 6848832
03:56:20 32970 8192000000 10142622592 10599874560 1117427664 12.003 98 42 81 1 1216097616 89863680 8806272
03:57:54 77482 8192000000 10149576816 10613166080 1116870512 11.997 98 12 24 0 1252876720 133161664 2844544
04:00:11 57226 8192000000 10134893504 10610925568 1130166912 12.123 99 18 36 0 1220983824 86633728 4183184
04:02:12 25229 8192000000 10084867680 10530570240 1123285920 12.058 99 39 72 1 1183218912 51472688 8460304
04:03:43 10540 8192000000 10126130816 10555387904 1127735120 12.100 99 53 99 2 1205985392 68819648 9430624
04:04:58 152465 8192000000 10046801632 10524786688 1058403472 11.441 94 24 44 0 1149278400 86039792 4835136
04:08:50 56168 8192000000 10052526384 10531155968 1100302576 11.841 98 14 28 0 1148812496 45657952 2851968
04:12:04 63382 8192000000 10089121984 10558513152 1123198544 12.057 100 11 22 1 1196201328 70395168 2607616
04:14:07 73183 8192000000 10076082432 10539503616 1110456864 11.937 98 21 41 2 1193495872 79016800 4022208
04:16:21 42159 8192000000 10057032576 10496790528 1108185680 11.915 98 25 50 0 1162573872 48816448 5571744
04:18:03 60205 8192000000 10078424544 10542247936 1126570896 12.089 99 29 57 0 1195558432 62634000 6353536
04:20:10 23061 8192000000 10106235536 10561183744 1143055728 12.244 100 45 88 1 1206638224 54445296 9137200
04:21:34 63402 8192000000 10129157888 10580328448 1138426240 12.201 99 29 58 0 1209137488 64491744 6219504
04:23:40 75485 8192000000 10107572016 10557779968 1104699104 11.882 97 24 48 0 1163707632 53867248 5141280
04:25:58 41351 8192000000 10055579888 10500861952 1087810160 11.722 97 39 78 0 1124802560 28808256 8184144
04:27:41 55706 8192000000 10086779328 10528030720 1075504368 11.605 96 31 62 0 1137515424 55986016 6025040
04:29:40 112405 8192000000 10059294208 10515144704 1095003360 11.790 98 30 56 3 1164206576 63762032 5441184
04:32:36 46195 8192000000 10047135024 10505273344 1104819648 11.883 99 8 16 0 1156053392 49474752 1758992
04:34:22 67540 8192000000 10084569472 10528391168 1120197856 12.029 100 15 30 0 1171803312 48812528 2792928
04:36:43 41179 8192000000 9353286512 9691983872 479251968 5.526 42 9 18 0 505182208 23855104 2075136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-07-2022 04:38:58 to 20-07-2022 04:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-07-2022 04:39:15 to 20-07-2022 04:39:17.

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          224202
MON$OLDEST_ACTIVE               224203
MON$OLDEST_SNAPSHOT             224203
MON$NEXT_TRANSACTION            224203
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                       2066528
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2799 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2799 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2799 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2799 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 ---
:173: 
:174: mscs-test-161.budzdorov.ru    Wed Jul 20 04:37:30 2022
:175:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:176: 
:177: 
:178: mscs-test-161.budzdorov.ru    Wed Jul 20 04:37:30 2022
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:180: 
:181: 
:182: mscs-test-161.budzdorov.ru    Wed Jul 20 04:37:30 2022
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:184: 
--- end of diff output ---

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

20.07.22 04:39:18. 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-20 04:39:21 No severe PSQL-related problems occured
20.07.22 04:39:22. End of report.