Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.09.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15970244 kB
MemAvailable 30877984 kB
Buffers 0 kB
Cached 15705372 kB
SwapCached 340 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.1
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 2021-09-06 10:37:08
DTS_END                         2021-09-06 10:37:08.8400
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/42184
TRN_ID                          235938
ATT_ID                          673
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: 8859.63 23396 1063156
customer order: creation 171.18 441 20542
customer order: refuse 14.79 321 1775
order to supplier: creation 45.83 1497 5499
order to supplier: removal 3.41 120860 409
invoice (draft): creation 45.86 3764 5503
invoice (draft): removal 51.68 1006 6201
invoice accept: apply 39.94 19324 4793
invoice accept: cancel 46.43 873 5571
customer reserve: creation 3614.63 193 433755
customer reserve: removal 4535.49 71 544259
realization accept: apply 82.88 161 9945
realization accept: cancel 78.70 128 9444
payment from customer: creation 58.89 113 7067
payment from customer: removal 5.79 84 695
payment to supplier: creation 49.90 128 5988
payment to supplier: removal 3.68 122 441
service: total inventory turnovers 3.97 3635 476
service: total monetary turnovers 5.74 573 689
service: aggregate perf. data 0.83 23204 99
service: refresh index statistics 0.04 291423 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-09-2021 10:38:00 to 06-09-2021 10:38:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3226
TEST_TIME 2 3818
TEST_TIME 3 4260
TEST_TIME 4 4762
TEST_TIME 5 4698
TEST_TIME 6 5786
TEST_TIME 7 5631
TEST_TIME 8 5774
TEST_TIME 9 6157
TEST_TIME 10 6075
TEST_TIME 11 6285
TEST_TIME 12 6945
TEST_TIME 13 7424
TEST_TIME 14 7467
TEST_TIME 15 7652
TEST_TIME 16 7567
TEST_TIME 17 8153
TEST_TIME 18 8109
TEST_TIME 19 8307
TEST_TIME 20 8762
TEST_TIME 21 8928
TEST_TIME 22 8996
TEST_TIME 23 8865
TEST_TIME 24 9295
TEST_TIME 25 9857
TEST_TIME 26 10788
TEST_TIME 27 11139
TEST_TIME 28 11124
TEST_TIME 29 10787
TEST_TIME 30 10556
TEST_TIME 31 10416
TEST_TIME 32 10216
TEST_TIME 33 10130
TEST_TIME 34 10104
TEST_TIME 35 9970
TEST_TIME 36 9784
TEST_TIME 37 9831
TEST_TIME 38 9685
TEST_TIME 39 9646
TEST_TIME 40 9458
TEST_TIME 41 9447
TEST_TIME 42 9360
TEST_TIME 43 9176
TEST_TIME 44 9114
TEST_TIME 45 8998
TEST_TIME 46 9019
TEST_TIME 47 9054
TEST_TIME 48 9034
TEST_TIME 49 9041
TEST_TIME 50 9023
TEST_TIME 51 8931
TEST_TIME 52 8948
TEST_TIME 53 8990
TEST_TIME 54 9030
TEST_TIME 55 8941
TEST_TIME 56 9148
TEST_TIME 57 9157
TEST_TIME 58 9263
TEST_TIME 59 9211
TEST_TIME 60 9107
TEST_TIME 61 9088
TEST_TIME 62 9063
TEST_TIME 63 9046
TEST_TIME 64 9014
TEST_TIME 65 8922
TEST_TIME 66 8902
TEST_TIME 67 8929
TEST_TIME 68 8882
TEST_TIME 69 8906
TEST_TIME 70 8870
TEST_TIME 71 8872
TEST_TIME 72 8995
TEST_TIME 73 8943
TEST_TIME 74 8933
TEST_TIME 75 9115
TEST_TIME 76 9093
TEST_TIME 77 9090
TEST_TIME 78 9112
TEST_TIME 79 9227
TEST_TIME 80 9122
TEST_TIME 81 9063
TEST_TIME 82 9003
TEST_TIME 83 9033
TEST_TIME 84 8964
TEST_TIME 85 8897
TEST_TIME 86 8882
TEST_TIME 87 8878
TEST_TIME 88 8863
TEST_TIME 89 8879
TEST_TIME 90 8907
TEST_TIME 91 8904
TEST_TIME 92 8883
TEST_TIME 93 8929
TEST_TIME 94 8964
TEST_TIME 95 8953
TEST_TIME 96 9082
TEST_TIME 97 9101
TEST_TIME 98 9098
TEST_TIME 99 9099
TEST_TIME 100 9125
TEST_TIME 101 9045
TEST_TIME 102 8997
TEST_TIME 103 8999
TEST_TIME 104 8994
TEST_TIME 105 8949
TEST_TIME 106 8937
TEST_TIME 107 8883
TEST_TIME 108 8853
TEST_TIME 109 8845
TEST_TIME 110 8831
TEST_TIME 111 8811
TEST_TIME 112 8797
TEST_TIME 113 8821
TEST_TIME 114 8810
TEST_TIME 115 8803
TEST_TIME 116 8837
TEST_TIME 117 8851
TEST_TIME 118 8935
TEST_TIME 119 8898
TEST_TIME 120 8863

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-09-2021 10:38:03 to 06-09-2021 10:38:07.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1468914 1468914 0 0.00 0 21697 7 0 0
doc_list_biud 1468914 1468914 0 0.00 0 65347 33 0 0
sp_add_invoice_to_stock 4942 4793 149 3.01 36 164825 19324 0 149
sp_cancel_adding_invoice 5571 5571 0 0.00 5 100690 873 0 0
sp_cancel_client_order 1775 1775 0 0.00 5 8270 322 0 0
sp_cancel_customer_reserve 544259 544259 0 0.00 1 46894 71 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 6168 84 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 5135 123 0 0
sp_cancel_supplier_invoice 6213 6201 12 0.19 2 65356 1006 0 12
sp_cancel_supplier_order 409 409 0 0.00 10 1326940 120861 0 0
sp_cancel_write_off 9444 9444 0 0.00 1 9157 128 0 0
sp_client_order 20542 20542 0 0.00 4 31139 442 0 0
sp_customer_reserve 433755 433755 0 0.00 0 69722 193 0 0
sp_fill_shopping_cart 31524 31524 0 0.00 1 22819 57 0 0
sp_fill_shopping_cart_clo_res 433743 433743 0 0.00 0 18536 17 0 0
sp_get_clo_for_invoice 4781 4781 0 0.00 0 21981 438 0 0
sp_kill_cost_storno 16151 16151 0 0.00 0 21658 62 0 0
sp_kill_qstorno_ret_qs2qd 1104572 1104572 0 0.00 0 65345 39 0 0
sp_kill_qty_storno 582385 582385 0 0.00 0 65347 83 0 0
sp_lock_selected_doc 20501 20501 0 0.00 0 5731 4 0 0
sp_make_cost_storno 27781 27781 0 0.00 0 11874 49 0 0
sp_multiply_rows_for_pdistr 27781 27781 0 0.00 0 10035 30 0 0
sp_multiply_rows_for_qdistr 20542 20542 0 0.00 0 22448 222 0 0
sp_pay_from_customer 7067 7067 0 0.00 4 8932 114 0 0
sp_pay_to_supplier 5988 5988 0 0.00 4 11727 128 0 0
sp_payment_common 13055 13055 0 0.00 1 8351 23 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 8261 294 0 0
sp_qd_handle_on_invoice_upd_sts 10352 10352 0 0.00 0 52693 383 0 0
sp_qd_handle_on_reserve_upd_sts 9945 9945 0 0.00 0 8994 52 0 0
sp_reserve_write_off 9945 9945 0 0.00 6 10601 162 0 0
sp_supplier_invoice 5503 5503 0 0.00 8 72351 3764 0 0
sp_supplier_order 5499 5499 0 0.00 5 39418 1498 0 0
srv_aggregate_perf_data 99 99 0 0.00 2569 491372 23204 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1992 137431 42707 0 0
srv_find_qd_qs_mism 1018569 1018569 0 0.00 0 11397 6 0 0
srv_make_invnt_saldo 476 476 0 0.00 51 95447 3635 0 0
srv_make_money_saldo 689 689 0 0.00 1 14307 573 0 0
srv_recalc_idx_stat 5 5 0 0.00 148169 479807 291423 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 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 4793 4793 0 0.00 0 19416 196 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 16063 111 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 3431 11 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 1035 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 167 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 474 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 1325 24 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 37971 1735 0 0
t$perf-norm:sp_cancel_write_off 931 931 0 0.00 0 869 4 0 0
t$perf-norm:sp_client_order 20542 20542 0 0.00 0 7824 18 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 8079 16 0 0
t$perf-norm:sp_pay_from_customer 7068 7068 0 0.00 0 19786 14 0 0
t$perf-norm:sp_pay_to_supplier 5988 5988 0 0.00 0 4277 8 0 0
t$perf-norm:sp_reserve_write_off 9945 9945 0 0.00 0 7722 11 0 0
t$perf-norm:sp_supplier_invoice 5503 5503 0 0.00 0 10366 22 0 0
t$perf-norm:sp_supplier_order 5499 5499 0 0.00 0 6612 19 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 53 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1805 29 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 1628 15 0 0
t$perf-norm:srv_make_money_saldo 689 689 0 0.00 0 5805 15 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 9 5 0 0
v_add_invoice_to_stock 9858 9560 298 3.02 0 6654 19 0 298
v_all_customers 37152 37152 0 0.00 0 215 1 0 0
v_all_suppliers 30502 30502 0 0.00 0 21 1 0 0
v_all_wares 41084 41084 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 406 6 0 0
v_cancel_client_order 3550 3550 0 0.00 0 3588 5 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 314 3 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 3602 7 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 5118 16 0 0
v_cancel_supplier_invoice 892 868 24 2.69 0 3729 19 0 24
v_cancel_supplier_order 818 818 0 0.00 0 1149 6 0 0
v_cancel_write_off 1862 1862 0 0.00 0 5910 16 0 0
v_max_id_clo_ord 5499 5499 0 0.00 0 263 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 6470 25 0 0
v_max_id_ord_sup 5483 5483 0 0.00 0 5436 6 0 0
v_max_non_paid_invoice 1719 1719 0 0.00 0 329 2 0 0
v_max_non_paid_realizn 4026 4026 0 0.00 0 945 4 0 0
v_min_id_clo_ord 5499 5499 0 0.00 0 3265 7 0 0
v_min_id_clo_res 992 992 0 0.00 0 2971 9 0 0
v_min_id_ord_sup 5483 5483 0 0.00 0 10188 13 0 0
v_min_non_paid_invoice 5988 5988 0 0.00 0 5705 14 0 0
v_min_non_paid_realizn 7067 7067 0 0.00 0 5753 16 0 0
v_our_firm 10014 10014 0 0.00 0 21 1 0 0
v_reserve_write_off 19890 19890 0 0.00 0 4321 5 0 0
x_lock_depdocs_on_canc_invoice 11338 11338 0 0.00 0 11971 123 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 15023 439 0 0
x_make_qstorno_1000_1200 5499 5499 0 0.00 2 39395 1412 0 0
x_make_qstorno_1200_2000 5503 5503 0 0.00 3 72286 3524 0 0
x_make_qstorno_2100_3300 433755 433755 0 0.00 0 69721 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-09-2021 10:38:08 to 06-09-2021 10:38:12.

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:12 8 8192000000 8804970576 8818716672 9712512 0.118 3 3 5 0 9923088 113488 97088
08:08:14 25758 8192000000 9716112048 10014810112 796322352 8.859 100 84 168 0 824460704 7644208 20494144
08:09:43 20542 8192000000 9768862000 10077712384 841639040 9.316 100 84 168 1 874276000 12922736 19714224
08:11:14 23602 8192000000 9823824576 10145325056 884390768 9.743 100 89 178 0 933537472 28366576 20780128
08:12:42 13399 8192000000 9873179632 10208055296 927100976 10.166 100 76 152 2 978149632 34316112 16732544
08:14:03 6328 8192000000 9954698544 10298363904 969292784 10.580 100 83 166 1 1058712592 72651216 16768592
08:15:12 3382 8192000000 9990483520 10343292928 997421872 10.854 100 88 177 0 1102060176 86729392 17908912
08:16:17 2775 8192000000 10003360624 10365620224 1031479520 11.183 100 86 171 0 1121127168 72423280 17224368
08:17:21 2306 8192000000 10030363680 10398195712 1047700544 11.339 100 83 166 1 1139803216 74627952 17474720
08:18:24 19076 8192000000 10037733888 10413322240 1061131584 11.467 100 70 139 0 1146430576 71405216 13893776
08:19:44 4305 8192000000 9991167120 10374111232 1072258560 11.574 100 56 111 2 1105865184 20870896 12735728
08:20:50 5816 8192000000 9987415648 10389196800 1080035216 11.648 100 36 73 0 1114604720 25773120 8796384
08:22:00 7999 8192000000 9990693360 10393374720 1086543728 11.710 100 30 60 0 1113743280 19990464 7209088
08:23:09 27922 8192000000 10023466928 10415898624 1093417488 11.775 100 68 134 1 1121040096 13860704 13761904
08:24:37 4126 8192000000 10022823168 10418130944 1097666368 11.815 100 48 95 2 1136143760 27580528 10896864
08:25:43 7962 8192000000 10022205952 10434576384 1100715456 11.844 100 31 60 2 1127812752 20323008 6774288
08:26:53 6373 8192000000 10000231216 10386247680 1052936144 11.389 97 31 57 3 1081811120 22827776 6047200
08:28:01 18485 8192000000 9975378432 10378809344 1048696624 11.348 98 27 54 0 1067132368 12277680 6158064
08:29:23 5022 8192000000 9941067232 10322370560 997016400 10.850 95 41 83 3 1026284240 20744672 8523168
08:30:29 4615 8192000000 9938187024 10316398592 1006186192 10.938 98 41 82 0 1038703824 22919424 9598208
08:31:37 63692 8192000000 9945374624 10336522240 1000518688 10.884 97 34 68 1 1030087984 21994064 7575232
08:33:47 3908 8192000000 9976792016 10359066624 1051288768 11.373 100 48 98 2 1075484832 14484144 9711920
08:34:53 13617 8192000000 9971713984 10373935104 1063347056 11.489 100 34 68 0 1085316240 14451840 7517344
08:36:11 6428 8192000000 9983335968 10386071552 1072256272 11.574 100 44 86 3 1096961488 15378576 9326640
08:37:22 71348 8192000000 10009472896 10403667968 1087418352 11.718 100 33 66 1 1109250064 15257680 6574032
08:39:38 6011 8192000000 10066067312 10466344960 1112334176 11.955 100 63 126 0 1179120288 53789504 12996608
08:40:48 31830 8192000000 10094931792 10498662400 1120567552 12.032 100 41 82 0 1204259728 75109984 8582192
08:42:30 8685 8192000000 10169484416 10577002496 1138694160 12.203 100 68 133 1 1280610640 127402496 14513984
08:43:49 8656 8192000000 10153498176 10567372800 1150244320 12.312 100 43 85 0 1271690560 111586784 9859456
08:45:03 2179 8192000000 10215174704 10623950848 1160742128 12.410 100 77 152 0 1323338608 145934080 16662400
08:46:10 9350 8192000000 10239014000 10650107904 1162587600 12.427 100 67 135 1 1350450400 173175104 14687696
08:47:23 5433 8192000000 10258997984 10678317056 1176576464 12.558 100 55 111 0 1363529904 174922944 12030496
08:48:34 2362 8192000000 10311464656 10716753920 1172535520 12.521 100 83 166 1 1407438704 216173552 18729632
08:49:42 4422 8192000000 10303482224 10741723136 1156801088 12.373 99 63 127 0 1410894368 239578448 14514832
08:50:53 2262 8192000000 10288657504 10697084928 1167819040 12.476 100 76 151 0 1383781776 199009600 16953136
08:51:59 6772 8192000000 10325752784 10738724864 1166280112 12.462 100 70 138 0 1429367808 247230592 15857104
08:53:14 4613 8192000000 10347516160 10746613760 1142245856 12.237 99 78 153 1 1439434320 280250832 16937632
08:54:23 2846 8192000000 10423597376 10823299072 1153154272 12.339 100 92 184 1 1519892864 344696912 22041680
08:55:30 1984 8192000000 10389332608 10787315712 1144484288 12.258 99 87 174 0 1479162096 313285808 21392000
08:56:37 2401 8192000000 10429849840 10834481152 1157924144 12.384 100 80 161 2 1533297376 355456224 19917008
08:57:43 14087 8192000000 10362481856 10840907776 1166393152 12.463 100 42 82 0 1502165712 325368528 10404032
08:59:04 13012 8192000000 10404390704 10836852736 1167165584 12.470 100 44 87 2 1488872144 310821120 10885440
09:00:18 15107 8192000000 10449420032 10885996544 1162368880 12.425 100 42 84 1 1543135296 370152816 10613600
09:02:09 24409 8192000000 10339594144 10832236544 1132756880 12.147 99 28 56 0 1472266704 331862720 7647104
09:03:48 18815 8192000000 10115715984 10621075456 1101772336 11.854 98 15 30 0 1247640192 142349360 3518496
09:05:08 72953 8192000000 10035776272 10468868096 1093948096 11.780 99 23 45 2 1131903440 32038528 5916816
09:07:34 57148 8192000000 10072666480 10530537472 1112004368 11.951 100 29 59 0 1179986048 62142656 5839024
09:09:36 73392 8192000000 10078108192 10537132032 1102093088 11.857 98 21 42 0 1164386304 57868688 4424528
09:11:57 44519 8192000000 10071638416 10509512704 1118929408 12.017 99 26 51 0 1174309904 50534048 4846448
09:13:43 93736 8192000000 10100177248 10570809344 1114407840 11.974 98 11 21 0 1155496816 39083040 2005936
09:16:34 61143 8192000000 10073976208 10534080512 1145507088 12.267 100 19 38 0 1190380368 40946624 3926656
09:18:41 57957 8192000000 10069670560 10537717760 1142648144 12.240 100 16 32 0 1172160512 25972960 3539408
09:21:15 55562 8192000000 10108636112 10575499264 1154166128 12.349 100 13 26 0 1233223104 76294272 2762704
09:24:06 73604 8192000000 10086874000 10546208768 1134509824 12.164 98 15 30 0 1191594704 53914752 3170128
09:26:26 49434 8192000000 10073219648 10520567808 1128703920 12.109 98 15 30 0 1161680832 29978592 2998320
09:28:25 27570 8192000000 10112566784 10550206464 1152249536 12.331 100 38 76 0 1202837984 42519568 8068880
09:30:02 29913 8192000000 10151770608 10602123264 1157395888 12.379 100 15 30 0 1268081696 107377600 3308208
09:31:38 69189 8192000000 10110939120 10547695616 1103897776 11.875 95 19 38 1 1172117664 63720304 4499584
09:33:57 61471 8192000000 10097201776 10589798400 1135177616 12.170 99 10 20 0 1215737312 78156256 2403440
09:36:05 37156 8192000000 10096418880 10526617600 1114333744 11.973 98 23 46 2 1171051232 52399920 4317568
09:37:45 67824 8192000000 10057932896 10492645376 1094353408 11.784 97 24 48 0 1117351184 18194448 4803328
09:39:54 41345 8192000000 10084684224 10512617472 1117188080 12.000 98 41 82 3 1174299232 48220000 8891152
09:41:37 137431 8192000000 10076439504 10551742464 1095372736 11.794 96 17 33 1 1173422688 74640832 3409120
09:44:54 40773 8192000000 10076014848 10519523328 1113990960 11.970 98 6 12 0 1154190944 39041280 1158704
09:47:15 40996 8192000000 10093594720 10533081088 1099186624 11.830 97 23 46 0 1155716768 51711040 4819104
09:48:57 62707 8192000000 10095581632 10589089792 1121875744 12.045 99 13 26 0 1179854592 55435968 2542880
09:51:02 47498 8192000000 10123758592 10576371712 1094763504 11.788 96 25 51 2 1203091696 102635712 5692480
09:52:50 40223 8192000000 10064523616 10506174464 1117791200 12.006 100 26 52 0 1157274448 34390896 5092352
09:54:30 21787 8192000000 10102515200 10557358080 1120400736 12.031 99 27 53 2 1213141728 86728592 6012400
09:55:57 85701 8192000000 10051681744 10538704896 1102750448 11.864 98 8 16 0 1174887408 70553536 1583424
09:58:23 59568 8192000000 10064037520 10485841920 1102208848 11.859 98 39 80 0 1135090880 25278848 7603184
10:00:23 48360 8192000000 10072879840 10552860672 1110757136 11.940 99 17 34 1 1168889376 53981200 4151040
10:02:14 27722 8192000000 10097134672 10539016192 1135092544 12.169 100 19 38 3 1201450464 61962352 4395568
10:03:54 80019 8192000000 10110792848 10600218624 1138865360 12.205 100 15 30 0 1256327600 113773120 3689120
10:06:16 65200 8192000000 10097470880 10569662464 1120578944 12.032 98 22 44 2 1212660384 87237936 4843504
10:08:24 51358 8192000000 10098432640 10543419392 1135819984 12.176 100 17 34 0 1193755168 54426944 3508240
10:10:17 72136 8192000000 10114954208 10578673664 1135190528 12.170 99 18 36 0 1199889920 61245264 3454128
10:12:32 48616 8192000000 10135686272 10637848576 1129292160 12.115 98 22 42 2 1236234368 102336512 4605696
10:14:22 72169 8192000000 10076974048 10555174912 1060605408 11.462 92 11 22 0 1108613328 45848368 2159552
10:16:34 53759 8192000000 10129019520 10573918208 1109251872 11.925 97 32 64 2 1191208848 75457088 6499888
10:18:28 53791 8192000000 10098351008 10565730304 1144590672 12.259 100 27 54 1 1190415600 40632880 5192048
10:20:23 45990 8192000000 10108667872 10608504832 1148500560 12.295 100 14 28 0 1229651072 78044544 3105968
10:22:10 41689 8192000000 10086969216 10534965248 1143910000 12.252 100 18 35 1 1192716400 45311952 3494448
10:23:57 63659 8192000000 10083328304 10529673216 1137083360 12.188 99 16 32 0 1178660864 38264112 3313392
10:26:02 55268 8192000000 10059897696 10538156032 1128025328 12.103 98 10 20 0 1158972192 29141376 1805488
10:27:57 53160 8192000000 10053133472 10496368640 1112758448 11.959 97 13 26 0 1143130192 27589088 2782656
10:29:53 68404 8192000000 10097328736 10561822720 1122041904 12.046 97 29 58 1 1203082192 74394576 6645712
10:32:06 44744 8192000000 10143527616 10602422272 1138890528 12.205 99 35 69 2 1231379856 85224176 7265152
10:33:53 18751 8192000000 10101474032 10531221504 1121312544 12.039 98 27 54 2 1176869152 50062576 5494032
10:35:13 86608 8192000000 10077887568 10520031232 1063559552 11.491 94 25 51 1 1130873312 62441056 4872704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-09-2021 10:38:14 to 06-09-2021 10:38:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 149 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 06-09-2021 10:38:30 to 06-09-2021 10:38:32.

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          235039
MON$OLDEST_ACTIVE               236560
MON$OLDEST_SNAPSHOT             236560
MON$NEXT_TRANSACTION            236560
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                       2057952
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             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2580 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2580 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2580 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2580 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 ---
:131: 
:132: mscs-test-161.budzdorov.ru    Mon Sep  6 10:37:11 2021
:133:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:134: 
:135: 
:136: mscs-test-161.budzdorov.ru    Mon Sep  6 10:37:11 2021
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:138: 
:139: 
:140: mscs-test-161.budzdorov.ru    Mon Sep  6 10:37:13 2021
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:142: 
:143: 
:144: mscs-test-161.budzdorov.ru    Mon Sep  6 10:37:13 2021
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:146: 
:147: 
:148: mscs-test-161.budzdorov.ru    Mon Sep  6 10:37:21 2021
:149:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:150: 
:151: 
:152: mscs-test-161.budzdorov.ru    Mon Sep  6 10:37:23 2021
:153:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:154: 
--- end of diff output ---

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

06.09.21 10:38:33. 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
2021-09-06 10:38:35 No severe PSQL-related problems occured
06.09.21 10:38:37. End of report.