Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.08.2021 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 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 16176136 kB
MemAvailable 30979196 kB
Buffers 20 kB
Cached 15491016 kB
SwapCached 2604 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-08-20 04:37:08
DTS_END                         2021-08-20 04:37:08.1560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51494
TRN_ID                          231408
ATT_ID                          639
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: 8652.91 22897 1038349
customer order: creation 165.67 439 19880
customer order: refuse 13.88 326 1666
order to supplier: creation 43.43 1444 5211
order to supplier: removal 3.26 114689 391
invoice (draft): creation 43.21 3429 5185
invoice (draft): removal 49.04 940 5885
invoice accept: apply 39.14 19733 4697
invoice accept: cancel 44.33 916 5319
customer reserve: creation 3632.75 193 435930
customer reserve: removal 4332.58 69 519910
realization accept: apply 78.82 190 9458
realization accept: cancel 84.60 121 10152
payment from customer: creation 56.13 123 6735
payment from customer: removal 5.55 91 666
payment to supplier: creation 47.36 125 5683
payment to supplier: removal 3.06 74 367
service: total inventory turnovers 3.50 3570 420
service: total monetary turnovers 5.67 546 680
service: aggregate perf. data 0.91 13175 109
service: refresh index statistics 0.04 297738 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-08-2021 04:38:42 to 20-08-2021 04:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6253
TEST_TIME 2 4909
TEST_TIME 3 4446
TEST_TIME 4 4848
TEST_TIME 5 5027
TEST_TIME 6 4837
TEST_TIME 7 5064
TEST_TIME 8 5121
TEST_TIME 9 5236
TEST_TIME 10 5449
TEST_TIME 11 5787
TEST_TIME 12 5706
TEST_TIME 13 5917
TEST_TIME 14 6412
TEST_TIME 15 6459
TEST_TIME 16 6881
TEST_TIME 17 7367
TEST_TIME 18 8105
TEST_TIME 19 8099
TEST_TIME 20 8195
TEST_TIME 21 8171
TEST_TIME 22 8390
TEST_TIME 23 8974
TEST_TIME 24 9163
TEST_TIME 25 9515
TEST_TIME 26 9849
TEST_TIME 27 10203
TEST_TIME 28 10628
TEST_TIME 29 10345
TEST_TIME 30 10165
TEST_TIME 31 10120
TEST_TIME 32 9971
TEST_TIME 33 9930
TEST_TIME 34 9820
TEST_TIME 35 9726
TEST_TIME 36 9556
TEST_TIME 37 9437
TEST_TIME 38 9231
TEST_TIME 39 9071
TEST_TIME 40 9388
TEST_TIME 41 9294
TEST_TIME 42 9277
TEST_TIME 43 9190
TEST_TIME 44 9184
TEST_TIME 45 9198
TEST_TIME 46 9212
TEST_TIME 47 9251
TEST_TIME 48 9281
TEST_TIME 49 9209
TEST_TIME 50 9200
TEST_TIME 51 9119
TEST_TIME 52 9103
TEST_TIME 53 9055
TEST_TIME 54 9067
TEST_TIME 55 9210
TEST_TIME 56 9082
TEST_TIME 57 8968
TEST_TIME 58 8905
TEST_TIME 59 8894
TEST_TIME 60 8808
TEST_TIME 61 8786
TEST_TIME 62 8714
TEST_TIME 63 8713
TEST_TIME 64 8752
TEST_TIME 65 8808
TEST_TIME 66 8824
TEST_TIME 67 8786
TEST_TIME 68 8779
TEST_TIME 69 8895
TEST_TIME 70 8818
TEST_TIME 71 8975
TEST_TIME 72 9120
TEST_TIME 73 9070
TEST_TIME 74 9052
TEST_TIME 75 9122
TEST_TIME 76 9156
TEST_TIME 77 9117
TEST_TIME 78 9029
TEST_TIME 79 8981
TEST_TIME 80 8976
TEST_TIME 81 8941
TEST_TIME 82 8881
TEST_TIME 83 8830
TEST_TIME 84 8763
TEST_TIME 85 8822
TEST_TIME 86 8825
TEST_TIME 87 8805
TEST_TIME 88 8790
TEST_TIME 89 8814
TEST_TIME 90 8817
TEST_TIME 91 8865
TEST_TIME 92 8858
TEST_TIME 93 8904
TEST_TIME 94 8968
TEST_TIME 95 8957
TEST_TIME 96 8962
TEST_TIME 97 8934
TEST_TIME 98 8957
TEST_TIME 99 8881
TEST_TIME 100 8875
TEST_TIME 101 8846
TEST_TIME 102 8807
TEST_TIME 103 8764
TEST_TIME 104 8719
TEST_TIME 105 8689
TEST_TIME 106 8755
TEST_TIME 107 8734
TEST_TIME 108 8712
TEST_TIME 109 8716
TEST_TIME 110 8725
TEST_TIME 111 8682
TEST_TIME 112 8728
TEST_TIME 113 8789
TEST_TIME 114 8794
TEST_TIME 115 8797
TEST_TIME 116 8762
TEST_TIME 117 8826
TEST_TIME 118 8769
TEST_TIME 119 8726
TEST_TIME 120 8654

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-08-2021 04:38:46 to 20-08-2021 04:38:49.

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 1443486 1443486 0 0.00 0 20077 7 0 0
doc_list_biud 1443483 1443483 0 0.00 0 76967 31 0 0
sp_add_invoice_to_stock 4797 4697 100 2.08 30 184425 19734 0 100
sp_cancel_adding_invoice 5319 5319 0 0.00 5 51023 916 0 0
sp_cancel_client_order 1666 1666 0 0.00 4 10595 327 0 0
sp_cancel_customer_reserve 519910 519910 0 0.00 1 76969 69 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 2 6625 91 0 0
sp_cancel_pay_to_supplier 367 367 0 0.00 3 1592 74 0 0
sp_cancel_supplier_invoice 5891 5885 6 0.10 2 24490 940 0 6
sp_cancel_supplier_order 391 391 0 0.00 23 947893 114690 0 0
sp_cancel_write_off 10152 10152 0 0.00 1 22159 121 0 0
sp_client_order 19880 19880 0 0.00 4 34748 440 0 0
sp_customer_reserve 435930 435930 0 0.00 0 50572 193 0 0
sp_fill_shopping_cart 30265 30265 0 0.00 1 13085 45 0 0
sp_fill_shopping_cart_clo_res 435926 435926 0 0.00 0 24993 17 0 0
sp_get_clo_for_invoice 4693 4693 0 0.00 0 34119 465 0 0
sp_kill_cost_storno 16504 16504 0 0.00 0 10427 57 0 0
sp_kill_qstorno_ret_qs2qd 1056248 1056248 0 0.00 0 76965 37 0 0
sp_kill_qty_storno 557471 557471 0 0.00 0 76967 80 0 0
sp_lock_selected_doc 19624 19624 0 0.00 0 7651 6 0 0
sp_make_cost_storno 26568 26568 0 0.00 0 17859 53 0 0
sp_multiply_rows_for_pdistr 26568 26568 0 0.00 0 14224 32 0 0
sp_multiply_rows_for_qdistr 19880 19880 0 0.00 0 18794 217 0 0
sp_pay_from_customer 6735 6735 0 0.00 4 14468 124 0 0
sp_pay_to_supplier 5683 5683 0 0.00 5 12515 126 0 0
sp_payment_common 12418 12418 0 0.00 1 14028 26 0 0
sp_qd_handle_on_cancel_clo 1666 1666 0 0.00 0 10569 290 0 0
sp_qd_handle_on_invoice_upd_sts 10009 10009 0 0.00 0 36565 370 0 0
sp_qd_handle_on_reserve_upd_sts 9458 9458 0 0.00 0 24316 59 0 0
sp_reserve_write_off 9458 9458 0 0.00 7 24360 190 0 0
sp_supplier_invoice 5185 5185 0 0.00 6 97245 3429 0 0
sp_supplier_order 5211 5211 0 0.00 6 50632 1444 0 0
srv_aggregate_perf_data 110 109 1 0.90 2552 119280 13176 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 970 122063 45535 0 0
srv_find_qd_qs_mism 993760 993760 0 0.00 0 20075 6 0 0
srv_make_invnt_saldo 420 420 0 0.00 54 84989 3570 0 0
srv_make_money_saldo 680 680 0 0.00 1 15643 547 0 0
srv_recalc_idx_stat 5 5 0 0.00 178254 426166 297739 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4697 4697 0 0.00 0 18336 160 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 3827 102 0 0
t$perf-norm:sp_cancel_client_order 1666 1666 0 0.00 0 7842 13 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 12320 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 6050 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 367 367 0 0.00 0 2871 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 688 11 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 20089 1546 0 0
t$perf-norm:sp_cancel_write_off 901 901 0 0.00 0 2848 8 0 0
t$perf-norm:sp_client_order 19880 19880 0 0.00 0 11764 18 0 0
t$perf-norm:sp_customer_reserve 950 950 0 0.00 0 5821 10 0 0
t$perf-norm:sp_pay_from_customer 6735 6735 0 0.00 0 8453 11 0 0
t$perf-norm:sp_pay_to_supplier 5683 5683 0 0.00 0 8415 12 0 0
t$perf-norm:sp_reserve_write_off 9458 9458 0 0.00 0 9251 13 0 0
t$perf-norm:sp_supplier_invoice 5185 5185 0 0.00 0 5412 15 0 0
t$perf-norm:sp_supplier_order 5211 5211 0 0.00 0 9039 19 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 285 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 18 1 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 383 6 0 0
t$perf-norm:srv_make_money_saldo 680 680 0 0.00 0 435 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9572 9372 200 2.08 0 5373 17 0 200
v_all_customers 36064 36064 0 0.00 0 126 1 0 0
v_all_suppliers 28912 28912 0 0.00 0 128 1 0 0
v_all_wares 39756 39756 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 165 5 0 0
v_cancel_client_order 3332 3332 0 0.00 0 4163 6 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 2729 10 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 687 4 0 0
v_cancel_payment_to_supplier 734 734 0 0.00 0 1486 6 0 0
v_cancel_supplier_invoice 802 790 12 1.49 0 4420 20 0 12
v_cancel_supplier_order 782 782 0 0.00 0 1612 6 0 0
v_cancel_write_off 1802 1802 0 0.00 0 881 12 0 0
v_max_id_clo_ord 5210 5210 0 0.00 0 683 2 0 0
v_max_id_clo_res 950 950 0 0.00 0 3489 21 0 0
v_max_id_ord_sup 5176 5176 0 0.00 0 3543 4 0 0
v_max_non_paid_invoice 1613 1613 0 0.00 0 411 2 0 0
v_max_non_paid_realizn 3643 3643 0 0.00 0 910 3 0 0
v_min_id_clo_ord 5210 5210 0 0.00 0 7775 12 0 0
v_min_id_clo_res 950 950 0 0.00 0 7007 15 0 0
v_min_id_ord_sup 5176 5176 0 0.00 0 7151 5 0 0
v_min_non_paid_invoice 5683 5683 0 0.00 0 12289 13 0 0
v_min_non_paid_realizn 6735 6735 0 0.00 0 13854 23 0 0
v_our_firm 9876 9876 0 0.00 0 15 1 0 0
v_reserve_write_off 18916 18916 0 0.00 0 5965 5 0 0
x_lock_depdocs_on_canc_invoice 10809 10809 0 0.00 0 22416 112 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 8847 319 0 0
x_make_qstorno_1000_1200 5211 5211 0 0.00 2 50622 1358 0 0
x_make_qstorno_1200_2000 5185 5185 0 0.00 2 97237 3263 0 0
x_make_qstorno_2100_3300 435930 435930 0 0.00 0 50569 174 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 20-08-2021 04:38:50 to 20-08-2021 04:38:55.

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:11 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 35926 8192000000 9720922672 10016854016 790984128 8.805 100 86 172 0 820438240 7737984 21716128
02:09:55 28910 8192000000 9766522944 10069401600 834058448 9.240 100 87 174 0 868311904 12961888 21291568
02:11:28 19164 8192000000 9807081744 10128596992 865560368 9.556 100 70 140 0 905854912 24535440 15759104
02:12:51 13426 8192000000 9894883968 10226839552 927281936 10.168 100 87 175 0 999576544 53346240 18948368
02:14:08 7264 8192000000 9922159072 10269130752 963024448 10.519 100 81 161 0 1043349152 63227632 17097072
02:15:18 5607 8192000000 9990212800 10345406464 1000110400 10.880 100 87 172 3 1100844992 83399904 17334688
02:16:27 2874 8192000000 9998542000 10359640064 1026785648 11.137 100 83 163 1 1112125120 69321952 16017520
02:17:30 1564 8192000000 10022538480 10391113728 1056454080 11.423 100 85 171 1 1127268720 53701264 17113376
02:18:33 1159 8192000000 10049341376 10424655872 1072450064 11.575 100 88 176 1 1160675696 70336096 17889536
02:19:36 7393 8192000000 9975387920 10369150976 1073405776 11.585 100 34 66 1 1120227216 39506368 7315072
02:20:44 9374 8192000000 9991236832 10390167552 1081783872 11.664 100 25 51 1 1106858112 19050240 6024000
02:21:53 9695 8192000000 9991721376 10395320320 1087606592 11.720 100 39 75 1 1118015680 22073600 8335488
02:23:04 12686 8192000000 9992565312 10404753408 1094217568 11.783 100 31 61 1 1128424864 26544736 7662560
02:24:17 11745 8192000000 10015397616 10412847104 1096735488 11.807 100 53 110 1 1121639712 12064544 12839680
02:25:29 7218 8192000000 10032541584 10440318976 1101570048 11.853 100 35 70 2 1152882624 44428400 6884176
02:26:37 7491 8192000000 10015740816 10416590848 1087889344 11.723 99 29 58 1 1133751600 39350128 6512128
02:27:45 8296 8192000000 10008185520 10414845952 1073196000 11.583 99 38 76 2 1102810576 21871936 7742640
02:28:54 7458 8192000000 9996194496 10403803136 1031135072 11.179 97 33 66 0 1090930672 53718448 6077152
02:30:02 5614 8192000000 9956857760 10348572672 1010051088 10.976 97 40 80 0 1049558336 30820064 8687184
02:31:08 10459 8192000000 9947219536 10338029568 1001370976 10.892 97 25 49 1 1039286736 32264864 5650896
02:32:20 4256 8192000000 9951833232 10333331456 1016948432 11.043 98 49 97 3 1043701504 15122064 11631008
02:33:25 21826 8192000000 9960784864 10367782912 1043598160 11.299 100 36 69 1 1066192912 15221776 7372976
02:34:51 4231 8192000000 10003076416 10395238400 1063307296 11.488 100 51 101 1 1110039872 35868464 10864112
02:35:56 8566 8192000000 9982803264 10384809984 1068431472 11.537 100 40 77 1 1100740288 22808576 9500240
02:37:07 16968 8192000000 10008004912 10402824192 1086675872 11.711 100 36 73 3 1114728448 20722160 7330416
02:38:44 43320 8192000000 10039746688 10447396864 1103652816 11.872 100 32 64 1 1158442992 47768768 7021408
02:40:48 19641 8192000000 10073464608 10486669312 1119134304 12.019 100 50 98 1 1186526496 57122528 10269664
02:42:13 16687 8192000000 10109386048 10523455488 1132609904 12.146 100 44 88 0 1204076080 62297152 9169024
02:43:39 29091 8192000000 10166556464 10580717568 1147272912 12.284 100 48 96 0 1260426176 102977328 10175936
02:45:22 4126 8192000000 10184405184 10599378944 1160103088 12.404 100 51 102 1 1290392144 119102208 11186848
02:46:32 14163 8192000000 10237367904 10653229056 1166098112 12.460 100 52 103 0 1337123120 159637568 11387440
02:47:53 4475 8192000000 10268115536 10679881728 1166148576 12.461 100 63 126 0 1369745872 189532192 14065104
02:49:00 9286 8192000000 10286425008 10696323072 1169412800 12.491 100 75 148 1 1387467760 200858464 17196496
02:50:22 10888 8192000000 10304540496 10724134912 1170605488 12.502 100 58 116 0 1406123200 221892080 13625632
02:51:37 3012 8192000000 10279530016 10682544128 1159460160 12.398 100 67 133 0 1372132688 198089824 14582704
02:52:48 2132 8192000000 10349998784 10763296768 1172573088 12.521 100 71 142 0 1455679552 265534240 17572224
02:53:53 3105 8192000000 10323063632 10737074176 1139238992 12.208 100 94 188 2 1418515104 257519504 21756608
02:55:00 6384 8192000000 10293371152 10744991744 1146011792 12.272 100 67 131 0 1409607600 247082512 16513296
02:56:09 9766 8192000000 10275537024 10697408512 1128079168 12.103 98 42 83 1 1364984208 226856912 10048128
02:57:26 970 8192000000 10370128256 10779299840 1163118480 12.432 100 83 166 1 1463612848 281898080 18596288
02:58:29 6674 8192000000 10368343344 10772119552 1123983040 12.065 97 56 113 1 1430365312 292379568 14002704
02:59:38 3940 8192000000 10415455072 10827579392 1146246320 12.274 100 64 128 0 1509638672 347351744 16040608
03:00:47 7954 8192000000 10344975408 10801319936 1138011360 12.197 100 38 76 1 1437312720 289949136 9352224
03:02:04 16848 8192000000 10286049808 10738368512 1116674208 11.996 99 25 50 0 1387124864 263807968 6642688
03:03:23 22471 8192000000 10298022720 10737651712 1107456944 11.908 98 23 46 0 1361750448 248435984 5857520
03:04:49 29885 8192000000 10015041280 10459992064 1045135424 11.314 95 29 53 0 1105341728 54492512 5713792
03:06:22 90692 8192000000 10077457920 10496770048 1108943888 11.922 100 45 91 0 1153389232 34700432 9744912
03:09:18 35200 8192000000 10058244288 10503077888 1122038400 12.046 100 10 20 0 1166998528 43203584 1756544
03:11:01 30151 8192000000 10052344160 10514542592 1118750464 12.015 99 13 26 1 1155684112 34846864 2086784
03:12:32 43646 8192000000 10089983296 10542428160 1122493712 12.051 99 13 26 0 1174545504 49382816 2668976
03:14:16 107863 8192000000 10150840320 10606579712 1142349648 12.238 100 19 38 0 1264552016 118115520 4086848
03:17:19 56856 8192000000 10056153152 10562461696 1103041120 11.866 97 8 16 0 1158095136 53469744 1584272
03:19:20 89912 8192000000 10132138768 10584920064 1138610720 12.202 99 22 44 0 1216510080 73129088 4770272
03:21:55 62532 8192000000 10171075296 10616610816 1149656256 12.306 99 22 43 2 1240126688 85603584 4866848
03:24:02 50387 8192000000 10125076096 10617401344 1157478848 12.380 100 17 34 0 1212980720 51751600 3750272
03:26:07 87779 8192000000 10099481632 10564976640 1138862800 12.205 98 19 38 0 1172933664 30470304 3600560
03:28:55 33230 8192000000 10092667824 10522976256 1159076576 12.395 100 38 75 3 1203695520 36187520 8431424
03:30:31 26237 8192000000 10146476064 10584768512 1166723408 12.466 100 33 66 0 1209282176 35913840 6644928
03:31:58 106708 8192000000 10109453168 10644107264 1158413824 12.388 99 8 16 0 1241717760 81618464 1685472
03:35:00 76884 8192000000 10113587360 10584371200 1161698896 12.419 100 29 58 0 1215724272 47914064 6111312
03:37:43 62147 8192000000 10113284048 10604474368 1148524912 12.296 98 11 21 1 1200659328 49744032 2390384
03:39:48 72123 8192000000 10129685984 10621595648 1129525712 12.117 97 8 16 0 1206545184 75394960 1624512
03:42:00 65699 8192000000 10076860720 10555944960 1117668784 12.005 97 14 28 0 1178494112 58116016 2709312
03:44:14 58992 8192000000 10134247968 10567020544 1099409104 11.832 95 23 46 0 1184634768 80071296 5154368
03:46:23 76412 8192000000 10153483200 10584215552 1134118192 12.160 99 18 36 0 1223352624 85185568 4048864
03:48:41 74246 8192000000 10103284256 10648498176 1118998704 12.018 98 23 46 0 1207603456 84248448 4356304
03:51:01 15992 8192000000 10072007520 10501206016 1094118256 11.782 97 40 79 1 1135289808 33190160 7981392
03:52:19 38033 8192000000 10097409200 10586161152 1091379360 11.756 98 20 40 0 1141291744 46048928 3863456
03:53:57 66628 8192000000 10047174832 10477867008 1091280240 11.755 97 25 48 2 1133167632 36717552 5169840
03:56:04 80340 8192000000 10022693616 10481881088 1078616752 11.634 96 29 56 0 1118245360 33427600 6201008
03:58:25 48028 8192000000 10065300720 10523377664 1108083696 11.914 99 13 26 1 1155006720 44057696 2865328
04:00:15 68232 8192000000 10103309792 10554945536 1094497232 11.785 97 18 36 1 1169994992 71641408 3856352
04:02:25 60340 8192000000 10068675616 10537033728 1117906896 12.007 100 11 23 0 1166833312 46564176 2362240
04:04:32 41460 8192000000 10070856080 10500689920 1098552256 11.824 98 37 74 0 1158968496 52497232 7919008
04:06:20 80203 8192000000 10087342976 10573844480 1098855488 11.827 98 8 16 0 1166568032 66000512 1712032
04:08:40 76785 8192000000 10091957616 10535563264 1110179360 11.934 98 25 50 1 1154538624 39380592 4978672
04:11:00 63314 8192000000 10075557456 10565242880 1116956032 11.998 98 16 32 0 1192559328 72343984 3259312
04:13:05 63627 8192000000 10072062384 10511360000 1125172096 12.076 99 21 42 1 1171318016 42123360 4022560
04:15:09 71189 8192000000 10118946544 10596966400 1150111024 12.311 100 44 88 1 1219934192 61093376 8729792
04:17:44 60326 8192000000 10108197744 10570510336 1150080944 12.310 100 32 64 1 1190990224 34425536 6483744
04:19:47 56562 8192000000 10069719568 10520600576 1100787744 11.845 96 12 24 0 1144643136 41862928 1992464
04:21:44 56902 8192000000 10136327280 10610221056 1144978432 12.262 100 12 24 0 1230640480 82891904 2770144
04:23:43 60477 8192000000 10057716048 10517606400 1115878368 11.988 98 9 17 0 1169897616 52500864 1518384
04:25:49 19470 8192000000 10094222464 10535301120 1138346096 12.200 99 25 50 0 1184803040 41067376 5389568
04:27:18 73255 8192000000 10125797632 10581278720 1115930512 11.989 97 23 45 2 1184183568 62992256 5260800
04:29:37 122063 8192000000 10069522176 10544902144 1123110272 12.056 98 12 24 0 1209147232 83711360 2325600
04:32:39 44050 8192000000 10093594176 10537984000 1130587984 12.127 99 33 66 0 1186680640 49436384 6656272
04:34:26 41938 8192000000 10071406848 10504622080 1116718080 11.996 99 22 44 1 1155474160 34056432 4699648
04:36:17 61020 8192000000 9157169264 9430319104 305145824 3.591 27 12 22 1 326992944 19194016 2653104

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-08-2021 04:38:56 to 20-08-2021 04:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 100 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-08-2021 04:39:13 to 20-08-2021 04:39:15.

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          231715
MON$OLDEST_ACTIVE               231716
MON$OLDEST_SNAPSHOT             231716
MON$NEXT_TRANSACTION            231716
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                       2043360
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 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 ---
:285: 
:286: mscs-test-161.budzdorov.ru    Fri Aug 20 04:37:20 2021
:287:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:288: 
:289: 
:290: mscs-test-161.budzdorov.ru    Fri Aug 20 04:37:20 2021
:291:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:292: 
--- end of diff output ---

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

20.08.21 04:39:16. 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-08-20 04:39:18 No severe PSQL-related problems occured
20.08.21 04:39:19. End of report.