Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.08.2021 07: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 16259216 kB
MemAvailable 30962464 kB
Buffers 0 kB
Cached 15424868 kB
SwapCached 3644 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-22 07:37:05
DTS_END                         2021-08-22 07:37:05.4370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56590
TRN_ID                          224891
ATT_ID                          580
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: 8687.11 23659 1042453
customer order: creation 161.95 437 19434
customer order: refuse 13.67 372 1640
order to supplier: creation 43.48 1611 5218
order to supplier: removal 3.41 121487 409
invoice (draft): creation 43.77 3815 5252
invoice (draft): removal 48.96 1087 5875
invoice accept: apply 40.02 20315 4802
invoice accept: cancel 44.16 864 5299
customer reserve: creation 3685.27 199 442232
customer reserve: removal 4322.30 74 518676
realization accept: apply 80.56 213 9667
realization accept: cancel 76.29 121 9155
payment from customer: creation 56.90 134 6828
payment from customer: removal 5.83 110 699
payment to supplier: creation 47.19 144 5663
payment to supplier: removal 3.50 134 420
service: total inventory turnovers 3.42 3921 410
service: total monetary turnovers 5.60 654 672
service: aggregate perf. data 0.82 17978 98
service: refresh index statistics 0.03 299515 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-08-2021 07:37:56 to 22-08-2021 07:37:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5359
TEST_TIME 2 5094
TEST_TIME 3 4169
TEST_TIME 4 4352
TEST_TIME 5 4598
TEST_TIME 6 4549
TEST_TIME 7 4823
TEST_TIME 8 4987
TEST_TIME 9 5364
TEST_TIME 10 5873
TEST_TIME 11 6251
TEST_TIME 12 6690
TEST_TIME 13 6671
TEST_TIME 14 6804
TEST_TIME 15 6911
TEST_TIME 16 6946
TEST_TIME 17 7118
TEST_TIME 18 7200
TEST_TIME 19 7832
TEST_TIME 20 7884
TEST_TIME 21 8081
TEST_TIME 22 8231
TEST_TIME 23 8341
TEST_TIME 24 8378
TEST_TIME 25 9041
TEST_TIME 26 9099
TEST_TIME 27 9070
TEST_TIME 28 9545
TEST_TIME 29 10016
TEST_TIME 30 10927
TEST_TIME 31 10797
TEST_TIME 32 10623
TEST_TIME 33 10448
TEST_TIME 34 10251
TEST_TIME 35 10217
TEST_TIME 36 10093
TEST_TIME 37 9923
TEST_TIME 38 9836
TEST_TIME 39 9714
TEST_TIME 40 9638
TEST_TIME 41 9533
TEST_TIME 42 9408
TEST_TIME 43 9510
TEST_TIME 44 9355
TEST_TIME 45 9203
TEST_TIME 46 9081
TEST_TIME 47 9099
TEST_TIME 48 8987
TEST_TIME 49 8939
TEST_TIME 50 8788
TEST_TIME 51 8833
TEST_TIME 52 8807
TEST_TIME 53 8778
TEST_TIME 54 8713
TEST_TIME 55 8749
TEST_TIME 56 8887
TEST_TIME 57 8921
TEST_TIME 58 8948
TEST_TIME 59 8995
TEST_TIME 60 8957
TEST_TIME 61 9085
TEST_TIME 62 9117
TEST_TIME 63 9127
TEST_TIME 64 9081
TEST_TIME 65 9050
TEST_TIME 66 9001
TEST_TIME 67 8923
TEST_TIME 68 8877
TEST_TIME 69 8891
TEST_TIME 70 8886
TEST_TIME 71 8845
TEST_TIME 72 8808
TEST_TIME 73 8748
TEST_TIME 74 8721
TEST_TIME 75 8669
TEST_TIME 76 8724
TEST_TIME 77 8673
TEST_TIME 78 8685
TEST_TIME 79 8749
TEST_TIME 80 8839
TEST_TIME 81 8801
TEST_TIME 82 8821
TEST_TIME 83 8789
TEST_TIME 84 8873
TEST_TIME 85 8919
TEST_TIME 86 8965
TEST_TIME 87 8963
TEST_TIME 88 8887
TEST_TIME 89 8823
TEST_TIME 90 8799
TEST_TIME 91 8786
TEST_TIME 92 8727
TEST_TIME 93 8665
TEST_TIME 94 8612
TEST_TIME 95 8723
TEST_TIME 96 8698
TEST_TIME 97 8704
TEST_TIME 98 8737
TEST_TIME 99 8751
TEST_TIME 100 8761
TEST_TIME 101 8755
TEST_TIME 102 8759
TEST_TIME 103 8809
TEST_TIME 104 8851
TEST_TIME 105 8849
TEST_TIME 106 8852
TEST_TIME 107 8876
TEST_TIME 108 8829
TEST_TIME 109 8763
TEST_TIME 110 8802
TEST_TIME 111 8779
TEST_TIME 112 8732
TEST_TIME 113 8709
TEST_TIME 114 8697
TEST_TIME 115 8756
TEST_TIME 116 8736
TEST_TIME 117 8728
TEST_TIME 118 8704
TEST_TIME 119 8764
TEST_TIME 120 8692

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-08-2021 07:38:00 to 22-08-2021 07:38:04.

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 1449958 1449958 0 0.00 0 24470 8 0 0
doc_list_biud 1449958 1449958 0 0.00 0 25897 34 0 0
sp_add_invoice_to_stock 4924 4802 122 2.47 50 240311 20316 0 122
sp_cancel_adding_invoice 5299 5299 0 0.00 6 46191 864 0 0
sp_cancel_client_order 1640 1640 0 0.00 4 8523 373 0 0
sp_cancel_customer_reserve 518676 518676 0 0.00 1 21157 74 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 5589 110 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 5408 135 0 0
sp_cancel_supplier_invoice 5883 5875 8 0.13 2 25899 1087 0 8
sp_cancel_supplier_order 409 409 0 0.00 6 1229430 121487 0 0
sp_cancel_write_off 9155 9155 0 0.00 1 7986 122 0 0
sp_client_order 19434 19434 0 0.00 4 25557 437 0 0
sp_customer_reserve 442232 442232 0 0.00 0 77151 199 0 0
sp_fill_shopping_cart 29888 29888 0 0.00 1 24230 51 0 0
sp_fill_shopping_cart_clo_res 442221 442221 0 0.00 0 18982 17 0 0
sp_get_clo_for_invoice 4791 4791 0 0.00 0 30971 423 0 0
sp_kill_cost_storno 15573 15573 0 0.00 0 7794 70 0 0
sp_kill_qstorno_ret_qs2qd 1052791 1052791 0 0.00 0 25895 40 0 0
sp_kill_qty_storno 555512 555512 0 0.00 0 25895 87 0 0
sp_lock_selected_doc 20048 20048 0 0.00 0 5848 4 0 0
sp_make_cost_storno 26949 26949 0 0.00 0 20473 69 0 0
sp_multiply_rows_for_pdistr 26949 26949 0 0.00 0 12928 42 0 0
sp_multiply_rows_for_qdistr 19434 19434 0 0.00 0 23918 217 0 0
sp_pay_from_customer 6828 6828 0 0.00 4 34463 135 0 0
sp_pay_to_supplier 5663 5663 0 0.00 5 13631 144 0 0
sp_payment_common 12491 12491 0 0.00 1 34454 25 0 0
sp_qd_handle_on_cancel_clo 1640 1640 0 0.00 0 8517 337 0 0
sp_qd_handle_on_invoice_upd_sts 10090 10090 0 0.00 0 24620 406 0 0
sp_qd_handle_on_reserve_upd_sts 9667 9667 0 0.00 0 9132 58 0 0
sp_reserve_write_off 9667 9667 0 0.00 7 19402 213 0 0
sp_supplier_invoice 5252 5252 0 0.00 5 77997 3815 0 0
sp_supplier_order 5218 5218 0 0.00 5 40875 1612 0 0
srv_aggregate_perf_data 99 98 1 1.01 2953 142418 17979 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1964 125064 43247 0 0
srv_find_qd_qs_mism 995941 995941 0 0.00 0 24468 6 0 0
srv_make_invnt_saldo 410 410 0 0.00 61 100834 3921 0 0
srv_make_money_saldo 672 672 0 0.00 1 15965 654 0 0
srv_recalc_idx_stat 4 4 0 0.00 131858 466726 299515 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 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 4802 4802 0 0.00 0 29735 171 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 1 5241 116 0 0
t$perf-norm:sp_cancel_client_order 1640 1640 0 0.00 0 6762 25 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 1425 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 942 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 1487 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1702 16 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 21602 1546 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 1414 9 0 0
t$perf-norm:sp_client_order 19434 19434 0 0.00 0 13678 22 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 3475 8 0 0
t$perf-norm:sp_pay_from_customer 6828 6828 0 0.00 0 5820 8 0 0
t$perf-norm:sp_pay_to_supplier 5663 5663 0 0.00 0 8578 11 0 0
t$perf-norm:sp_reserve_write_off 9667 9667 0 0.00 0 15683 12 0 0
t$perf-norm:sp_supplier_invoice 5252 5252 0 0.00 0 3590 19 0 0
t$perf-norm:sp_supplier_order 5218 5218 0 0.00 0 1962 18 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 635 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 9198 174 0 0
t$perf-norm:srv_make_invnt_saldo 410 410 0 0.00 0 1292 15 0 0
t$perf-norm:srv_make_money_saldo 672 672 0 0.00 0 2455 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9824 9580 244 2.48 0 4510 21 0 244
v_all_customers 35444 35444 0 0.00 0 117 1 0 0
v_all_suppliers 29134 29134 0 0.00 0 23 1 0 0
v_all_wares 38864 38864 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 1299 8 0 0
v_cancel_client_order 3280 3280 0 0.00 0 3956 7 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 4199 11 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 4139 6 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 5379 19 0 0
v_cancel_supplier_invoice 766 750 16 2.08 0 2258 17 0 16
v_cancel_supplier_order 818 818 0 0.00 0 2985 11 0 0
v_cancel_write_off 1964 1964 0 0.00 0 1916 14 0 0
v_max_id_clo_ord 5212 5212 0 0.00 0 8397 5 0 0
v_max_id_clo_res 984 984 0 0.00 0 3396 14 0 0
v_max_id_ord_sup 5244 5244 0 0.00 0 11186 8 0 0
v_max_non_paid_invoice 1552 1552 0 0.00 0 1406 3 0 0
v_max_non_paid_realizn 3628 3628 0 0.00 0 3780 7 0 0
v_min_id_clo_ord 5212 5212 0 0.00 0 5063 12 0 0
v_min_id_clo_res 984 984 0 0.00 0 2382 10 0 0
v_min_id_ord_sup 5244 5244 0 0.00 0 5079 6 0 0
v_min_non_paid_invoice 5663 5663 0 0.00 0 12825 12 0 0
v_min_non_paid_realizn 6828 6828 0 0.00 0 34450 19 0 0
v_our_firm 9820 9820 0 0.00 0 21 1 0 0
v_reserve_write_off 19334 19334 0 0.00 0 4139 7 0 0
x_lock_depdocs_on_canc_invoice 10799 10799 0 0.00 0 17299 126 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 22619 466 0 0
x_make_qstorno_1000_1200 5218 5218 0 0.00 2 40870 1509 0 0
x_make_qstorno_1200_2000 5252 5252 0 0.00 2 77984 3626 0 0
x_make_qstorno_2100_3300 442232 442232 0 0.00 0 77146 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-08-2021 07:38:05 to 22-08-2021 07:38:09.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:09 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:09 35146 8192000000 9735681968 10037059584 808167552 8.979 100 89 178 0 837994656 8388448 21438656
05:09:47 8070 8192000000 9766216256 10073141248 842195440 9.322 100 88 175 1 875074400 12225824 20653136
05:11:13 26156 8192000000 9817205744 10138238976 885269520 9.752 100 87 174 0 924200368 19073936 19856912
05:12:42 11598 8192000000 9862800256 10192175104 910556480 10.003 100 82 164 0 969685600 41085440 18043680
05:13:58 7755 8192000000 9920437488 10265931776 947312320 10.365 100 78 156 1 1023558784 59823840 16422624
05:15:16 5254 8192000000 9950463264 10304585728 984483808 10.728 100 80 156 4 1059634688 59041888 16108992
05:16:24 4508 8192000000 9988147296 10353102848 1018167136 11.054 100 79 157 0 1107001568 73252576 15581856
05:17:31 2421 8192000000 10020285600 10387521536 1045082880 11.313 100 90 180 0 1132283664 69039344 18161440
05:18:37 1626 8192000000 10041229760 10411683840 1059067712 11.448 100 92 185 0 1151802192 73900960 18833520
05:19:42 1181 8192000000 10052270320 10428420096 1069737424 11.550 100 94 189 2 1175788112 86088336 19962352
05:20:48 6180 8192000000 10065469152 10439753728 1078775600 11.636 100 92 184 0 1170870400 72433120 19661680
05:21:55 3837 8192000000 10018497104 10423885824 1078367264 11.632 100 33 66 0 1136364736 51138448 6859024
05:23:00 6154 8192000000 9999216064 10411159552 1087330768 11.717 100 31 62 0 1130067248 36193744 6542736
05:24:08 6387 8192000000 10000955040 10394411008 1093328048 11.774 100 26 52 0 1126100160 27069664 5702448
05:25:15 32353 8192000000 10019951312 10419781632 1096502128 11.804 100 33 65 1 1119176016 15908256 6765632
05:26:48 3507 8192000000 10033650320 10427506688 1099843040 11.836 100 65 128 2 1139609360 25899376 13866944
05:27:55 4825 8192000000 10018536784 10416635904 1089401792 11.737 100 46 92 0 1139646352 39362672 10881888
05:29:03 14810 8192000000 9969656784 10376241152 1018575840 11.058 95 24 47 3 1042098384 18448768 5073776
05:30:19 27923 8192000000 9970354112 10346946560 1013369392 11.008 95 63 129 0 1039209472 10458064 15382016
05:31:47 5720 8192000000 9961317152 10352615424 1010398976 10.979 97 37 75 1 1058782528 40922352 7461200
05:32:57 9317 8192000000 9961507328 10356527104 1024371072 11.114 99 30 60 3 1056140448 25005616 6763760
05:34:09 61286 8192000000 9933020928 10324680704 987409216 10.756 95 30 58 3 1022831280 27677632 7744432
05:36:13 6379 8192000000 9972325392 10360610816 1043473024 11.298 100 38 76 3 1083891152 32701280 7716848
05:37:25 27571 8192000000 9990407584 10387787776 1075950352 11.609 100 44 88 0 1103520496 16992784 10577360
05:39:19 15304 8192000000 10068360144 10465017856 1105818768 11.893 100 57 114 0 1181006000 62443392 12743840
05:40:44 82051 8192000000 10099005680 10509467648 1115917328 11.988 100 34 66 3 1205233728 81803440 7512960
05:43:14 15249 8192000000 10120375360 10532966400 1126710192 12.090 100 40 78 0 1233572144 98378176 8483776
05:44:32 5660 8192000000 10149054832 10559041536 1133062048 12.150 100 50 100 1 1258331040 114430640 10838352
05:45:43 5053 8192000000 10201443056 10615660544 1143215680 12.246 100 66 132 2 1308064752 149599456 15249616
05:46:53 3039 8192000000 10218345008 10622717952 1154526864 12.352 100 65 130 0 1315643856 146596128 14520864
05:48:00 7130 8192000000 10237340560 10646839296 1158369616 12.388 100 73 146 0 1337216992 162355024 16492352
05:49:13 11857 8192000000 10236096288 10669150208 1170352272 12.500 100 63 126 0 1353893712 169546624 13994816
05:50:31 2624 8192000000 10284943520 10693484544 1184862224 12.636 100 83 168 0 1382058640 178180928 19015488
05:51:40 2390 8192000000 10300171664 10709393408 1183760272 12.625 100 73 140 2 1397727808 197209840 16757696
05:52:47 1963 8192000000 10328946832 10742022144 1178826048 12.579 100 70 139 0 1434495152 239437616 16231488
05:53:53 2641 8192000000 10395177056 10801242112 1169467008 12.492 100 79 157 1 1493008560 305574336 17967216
05:55:01 3593 8192000000 10395822304 10808864768 1165719920 12.457 100 78 156 1 1497923472 314006128 18197424
05:56:11 2613 8192000000 10442366912 10868670464 1163934928 12.440 100 58 115 0 1544200320 365840560 14424832
05:57:19 9397 8192000000 10348909552 10784755712 1139226416 12.208 99 39 77 2 1465637088 316073584 10337088
05:58:35 10093 8192000000 10386161888 10809085952 1133275824 12.152 99 46 92 2 1480647040 336536560 10834656
05:59:57 19051 8192000000 10422791408 10866356224 1137020208 12.187 99 41 82 0 1509334000 361600640 10713152
06:01:33 7301 8192000000 10452713712 10897571840 1140697536 12.222 100 57 113 2 1547259504 392978400 13583568
06:02:46 12737 8192000000 10380393840 10881675264 1142682336 12.241 100 42 85 1 1504579776 351135280 10762160
06:04:05 19650 8192000000 10425869136 10869714944 1128587072 12.108 99 36 73 1 1504532336 366179776 9765488
06:05:38 20506 8192000000 10361349344 10881490944 1133637328 12.156 100 29 58 1 1487946592 346450656 7858608
06:07:16 40391 8192000000 10081247968 10617356288 1110297248 11.935 99 23 46 0 1328793968 212859936 5636784
06:08:59 29840 8192000000 10106666112 10538024960 1123906544 12.064 99 37 74 0 1174665200 43089504 7669152
06:10:34 81584 8192000000 10082910208 10529832960 1119576064 12.023 99 21 42 0 1179444192 55210560 4657568
06:12:57 68546 8192000000 10051629456 10501128192 1119533712 12.023 99 18 36 0 1154301984 30666192 4102080
06:15:13 52140 8192000000 10080484208 10521763840 1131755792 12.138 100 33 66 1 1177015472 38093200 7166480
06:17:09 111436 8192000000 10103940688 10536206336 1111303248 11.945 98 16 30 2 1187837984 72427264 4107472
06:20:04 70403 8192000000 10078300256 10585124864 1123102432 12.056 99 12 24 2 1158828896 33038848 2687616
06:22:16 112562 8192000000 10087205744 10573946880 1133769408 12.157 99 25 50 0 1214120432 73892528 6458496
06:25:39 29954 8192000000 10099747904 10533732352 1147314112 12.284 100 53 106 1 1196211616 38057456 10840048
06:27:12 42674 8192000000 10111716832 10574204928 1150801504 12.317 100 19 38 2 1230076528 75109696 4165328
06:28:55 30081 8192000000 10104338336 10562756608 1154502000 12.352 100 9 18 0 1204599248 48200736 1896512
06:30:37 64680 8192000000 10171857232 10635915264 1164436272 12.445 100 17 34 1 1241153280 72779376 3937632
06:32:56 62380 8192000000 10117026112 10591592448 1148640848 12.297 99 21 43 0 1237356768 84436560 4279360
06:35:00 76662 8192000000 10117061120 10605768704 1136101808 12.179 98 19 38 0 1184938592 44949920 3886864
06:37:17 87043 8192000000 10131746032 10636066816 1151083904 12.320 99 21 41 2 1228964480 73659872 4220704
06:39:46 60633 8192000000 10087570928 10556899328 1116450624 11.993 96 21 42 0 1145725952 25167264 4108064
06:41:47 8930 8192000000 10087249712 10517733376 1128842800 12.110 99 50 98 0 1160743792 21783472 10117520
06:43:03 102754 8192000000 10083626352 10542850048 1124243152 12.067 99 31 62 1 1189118272 57551888 7323232
06:45:53 25118 8192000000 10101477648 10537553920 1133150176 12.151 99 27 54 2 1194460608 55521360 5789072
06:47:21 39562 8192000000 10063759136 10537390080 1103637248 11.872 97 9 18 0 1159683792 54130960 1915584
06:49:28 71266 8192000000 10065067344 10549088256 1133435152 12.154 100 11 22 0 1192188672 56245312 2508208
06:52:18 46824 8192000000 10104080624 10547896320 1101785184 11.855 97 17 33 2 1157745296 52744224 3215888
06:54:06 68606 8192000000 10127166096 10568691712 1107735040 11.911 98 37 74 2 1195347680 79773552 7839088
06:56:22 59066 8192000000 10082296496 10516688896 1096144304 11.801 97 23 45 1 1155587872 53861280 5582288
06:59:02 19303 8192000000 10042587808 10483589120 1093308576 11.774 98 33 65 1 1133941152 33892560 6740016
07:00:23 29362 8192000000 10105111536 10545111040 1105692656 11.892 99 40 80 0 1183566816 69709232 8164928
07:02:47 125064 8192000000 10048569168 10537115648 1026052624 11.130 92 11 22 0 1068331440 40203792 2075024
07:05:53 77802 8192000000 10023157744 10461315072 1083920768 11.685 98 18 36 0 1114376704 27028576 3427360
07:08:12 46986 8192000000 10061315056 10533572608 1124064944 12.065 100 12 24 1 1181926544 55192432 2669168
07:11:52 72727 8192000000 10041852224 10526777344 1089833072 11.741 98 8 16 1 1132733456 41208512 1691872
07:14:05 99602 8192000000 10089492512 10542923776 1097897536 11.818 97 19 38 2 1160458208 58399840 4160832
07:16:45 41352 8192000000 10114282352 10565861376 1135008768 12.169 100 30 60 1 1194999056 54258608 5731680
07:18:34 30298 8192000000 10117880016 10553970688 1145154224 12.264 100 32 64 2 1193093248 41388384 6550640
07:20:10 28585 8192000000 10124679536 10585755648 1123737856 12.062 98 25 50 1 1192504416 63659120 5107440
07:21:43 45928 8192000000 10090906000 10523381760 1121281040 12.039 98 37 74 1 1165677232 37019728 7376464
07:23:44 73549 8192000000 10051202480 10500116480 1126079488 12.084 99 14 28 0 1201241488 72070048 3091952
07:26:05 85251 8192000000 10141878896 10590187520 1151881296 12.327 100 41 82 1 1236755296 76334352 8539648
07:28:32 69585 8192000000 10083201120 10580463616 1124864016 12.073 98 5 10 0 1172298160 46540848 893296
07:30:42 81794 8192000000 10090255872 10588307456 1141806640 12.233 99 13 26 0 1206000736 61005504 3188592
07:33:11 44337 8192000000 10062407296 10511212544 1099627440 11.834 96 32 66 2 1136188976 29686624 6874912
07:35:04 58491 8192000000 10111525392 10557497344 1105727312 11.892 98 22 44 0 1191452720 81106816 4618592
07:37:04 7088 8192000000 8934186224 9113620480 94608944 1.141 9 5 9 0 110406272 15141888 655440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-08-2021 07:38:11 to 22-08-2021 07:38:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 122 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-08-2021 07:38:27 to 22-08-2021 07:38:29.

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          225968
MON$OLDEST_ACTIVE               225969
MON$OLDEST_SNAPSHOT             225969
MON$NEXT_TRANSACTION            225969
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                       2035424
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

22.08.21 07:38:30. 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-22 07:38:32 No severe PSQL-related problems occured
22.08.21 07:38:33. End of report.