Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.10.2021 04:40
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 16206592 kB
MemAvailable 30731524 kB
Buffers 20 kB
Cached 15480852 kB
SwapCached 4696 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-10-04 04:37:07
DTS_END                         2021-10-04 04:37:07.4190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(151:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51210
TRN_ID                          229430
ATT_ID                          572
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: 8558.08 22469 1026969
customer order: creation 166.83 423 20019
customer order: refuse 13.88 390 1666
order to supplier: creation 43.85 1663 5262
order to supplier: removal 3.03 125232 363
invoice (draft): creation 44.19 3855 5303
invoice (draft): removal 46.58 1106 5590
invoice accept: apply 40.60 21442 4872
invoice accept: cancel 41.63 1047 4996
customer reserve: creation 3772.48 209 452697
customer reserve: removal 4105.61 71 492673
realization accept: apply 81.43 191 9771
realization accept: cancel 72.43 139 8692
payment from customer: creation 57.63 122 6915
payment from customer: removal 5.72 104 686
payment to supplier: creation 47.75 140 5730
payment to supplier: removal 3.42 107 410
service: total inventory turnovers 4.04 3500 485
service: total monetary turnovers 6.14 564 737
service: aggregate perf. data 0.81 20057 97
service: refresh index statistics 0.04 269015 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-10-2021 04:40:57 to 04-10-2021 04:40:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6885
TEST_TIME 2 6417
TEST_TIME 3 6644
TEST_TIME 4 6353
TEST_TIME 5 6378
TEST_TIME 6 6260
TEST_TIME 7 6411
TEST_TIME 8 6569
TEST_TIME 9 6864
TEST_TIME 10 6908
TEST_TIME 11 6884
TEST_TIME 12 6843
TEST_TIME 13 6852
TEST_TIME 14 6921
TEST_TIME 15 7010
TEST_TIME 16 6989
TEST_TIME 17 7705
TEST_TIME 18 7830
TEST_TIME 19 7989
TEST_TIME 20 8525
TEST_TIME 21 9097
TEST_TIME 22 9197
TEST_TIME 23 9283
TEST_TIME 24 9551
TEST_TIME 25 10452
TEST_TIME 26 10512
TEST_TIME 27 10265
TEST_TIME 28 10327
TEST_TIME 29 10213
TEST_TIME 30 10131
TEST_TIME 31 9949
TEST_TIME 32 9754
TEST_TIME 33 9622
TEST_TIME 34 9647
TEST_TIME 35 9497
TEST_TIME 36 9618
TEST_TIME 37 9608
TEST_TIME 38 9515
TEST_TIME 39 9343
TEST_TIME 40 9232
TEST_TIME 41 9119
TEST_TIME 42 9024
TEST_TIME 43 8864
TEST_TIME 44 8740
TEST_TIME 45 8793
TEST_TIME 46 8846
TEST_TIME 47 8896
TEST_TIME 48 8896
TEST_TIME 49 8893
TEST_TIME 50 8883
TEST_TIME 51 8915
TEST_TIME 52 8870
TEST_TIME 53 9000
TEST_TIME 54 9128
TEST_TIME 55 9053
TEST_TIME 56 9184
TEST_TIME 57 9180
TEST_TIME 58 9124
TEST_TIME 59 9004
TEST_TIME 60 8924
TEST_TIME 61 8879
TEST_TIME 62 8837
TEST_TIME 63 8723
TEST_TIME 64 8700
TEST_TIME 65 8804
TEST_TIME 66 8819
TEST_TIME 67 8794
TEST_TIME 68 8807
TEST_TIME 69 8754
TEST_TIME 70 8898
TEST_TIME 71 8880
TEST_TIME 72 8852
TEST_TIME 73 8838
TEST_TIME 74 8905
TEST_TIME 75 8866
TEST_TIME 76 8873
TEST_TIME 77 8890
TEST_TIME 78 9006
TEST_TIME 79 8930
TEST_TIME 80 8878
TEST_TIME 81 8857
TEST_TIME 82 8785
TEST_TIME 83 8774
TEST_TIME 84 8788
TEST_TIME 85 8732
TEST_TIME 86 8660
TEST_TIME 87 8641
TEST_TIME 88 8637
TEST_TIME 89 8656
TEST_TIME 90 8666
TEST_TIME 91 8639
TEST_TIME 92 8676
TEST_TIME 93 8669
TEST_TIME 94 8672
TEST_TIME 95 8728
TEST_TIME 96 8677
TEST_TIME 97 8741
TEST_TIME 98 8714
TEST_TIME 99 8768
TEST_TIME 100 8728
TEST_TIME 101 8728
TEST_TIME 102 8697
TEST_TIME 103 8669
TEST_TIME 104 8619
TEST_TIME 105 8554
TEST_TIME 106 8534
TEST_TIME 107 8531
TEST_TIME 108 8555
TEST_TIME 109 8557
TEST_TIME 110 8593
TEST_TIME 111 8564
TEST_TIME 112 8594
TEST_TIME 113 8587
TEST_TIME 114 8607
TEST_TIME 115 8589
TEST_TIME 116 8621
TEST_TIME 117 8646
TEST_TIME 118 8662
TEST_TIME 119 8631
TEST_TIME 120 8564

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-10-2021 04:41:01 to 04-10-2021 04:41: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 1442729 1442729 0 0.00 0 16116 8 0 0
doc_list_biud 1442729 1442729 0 0.00 0 54238 32 0 0
sp_add_invoice_to_stock 5011 4872 139 2.77 67 243251 21442 0 139
sp_cancel_adding_invoice 4996 4996 0 0.00 5 70133 1047 0 0
sp_cancel_client_order 1666 1666 0 0.00 5 9801 391 0 0
sp_cancel_customer_reserve 492673 492673 0 0.00 1 37495 72 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 10974 104 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 4836 107 0 0
sp_cancel_supplier_invoice 5600 5590 10 0.17 2 54368 1106 0 10
sp_cancel_supplier_order 363 363 0 0.00 7 1105928 125233 0 0
sp_cancel_write_off 8692 8692 0 0.00 1 12119 140 0 0
sp_client_order 20019 20019 0 0.00 4 40359 423 0 0
sp_customer_reserve 452697 452697 0 0.00 0 45174 210 0 0
sp_fill_shopping_cart 30572 30572 0 0.00 1 16507 57 0 0
sp_fill_shopping_cart_clo_res 452689 452689 0 0.00 0 37506 19 0 0
sp_get_clo_for_invoice 4849 4849 0 0.00 0 32582 449 0 0
sp_kill_cost_storno 14784 14784 0 0.00 0 12115 70 0 0
sp_kill_qstorno_ret_qs2qd 999991 999991 0 0.00 0 54236 40 0 0
sp_kill_qty_storno 528595 528595 0 0.00 0 54238 85 0 0
sp_lock_selected_doc 20141 20141 0 0.00 0 9400 3 0 0
sp_make_cost_storno 27260 27260 0 0.00 0 15297 55 0 0
sp_multiply_rows_for_pdistr 27260 27260 0 0.00 0 8240 36 0 0
sp_multiply_rows_for_qdistr 20019 20019 0 0.00 0 40340 215 0 0
sp_pay_from_customer 6915 6915 0 0.00 4 11048 122 0 0
sp_pay_to_supplier 5730 5730 0 0.00 5 16121 140 0 0
sp_payment_common 12645 12645 0 0.00 1 9704 20 0 0
sp_qd_handle_on_cancel_clo 1666 1666 0 0.00 0 8036 354 0 0
sp_qd_handle_on_invoice_upd_sts 9840 9840 0 0.00 0 23007 380 0 0
sp_qd_handle_on_reserve_upd_sts 9771 9771 0 0.00 0 13089 58 0 0
sp_reserve_write_off 9771 9771 0 0.00 7 19222 191 0 0
sp_supplier_invoice 5303 5303 0 0.00 7 74972 3855 0 0
sp_supplier_order 5262 5262 0 0.00 5 42854 1664 0 0
srv_aggregate_perf_data 98 97 1 1.02 2907 408809 20057 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 856 168237 51048 0 0
srv_find_qd_qs_mism 978800 978800 0 0.00 0 16038 7 0 0
srv_make_invnt_saldo 485 485 0 0.00 41 107224 3500 0 0
srv_make_money_saldo 737 737 0 0.00 1 13451 565 0 0
srv_recalc_idx_stat 5 5 0 0.00 144630 408800 269015 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 4872 4872 0 0.00 0 14227 185 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 8250 125 0 0
t$perf-norm:sp_cancel_client_order 1666 1666 0 0.00 0 6330 16 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 5174 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 4186 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1314 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1389 13 0 0
t$perf-norm:sp_cancel_supplier_order 363 363 0 0.00 0 31696 1740 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 3587 8 0 0
t$perf-norm:sp_client_order 20019 20019 0 0.00 0 8175 19 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 783 4 0 0
t$perf-norm:sp_pay_from_customer 6915 6915 0 0.00 0 5135 11 0 0
t$perf-norm:sp_pay_to_supplier 5730 5730 0 0.00 0 8255 12 0 0
t$perf-norm:sp_reserve_write_off 9771 9771 0 0.00 0 21260 17 0 0
t$perf-norm:sp_supplier_invoice 5303 5303 0 0.00 0 8684 25 0 0
t$perf-norm:sp_supplier_order 5262 5262 0 0.00 0 5865 22 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 400 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 81 4 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 1428 12 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 14248 25 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 12 6 0 0
v_add_invoice_to_stock 9966 9688 278 2.78 0 10804 20 0 278
v_all_customers 36378 36378 0 0.00 0 22 1 0 0
v_all_suppliers 29470 29470 0 0.00 0 21 1 0 0
v_all_wares 40038 40038 0 0.00 0 73 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 4168 13 0 0
v_cancel_client_order 3332 3332 0 0.00 0 9766 12 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 10886 19 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 9763 18 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 4754 15 0 0
v_cancel_supplier_invoice 842 822 20 2.37 0 985 14 0 20
v_cancel_supplier_order 726 726 0 0.00 0 1754 7 0 0
v_cancel_write_off 1872 1872 0 0.00 0 986 12 0 0
v_max_id_clo_ord 5262 5262 0 0.00 0 4077 2 0 0
v_max_id_clo_res 965 965 0 0.00 0 10719 30 0 0
v_max_id_ord_sup 5291 5291 0 0.00 0 8862 10 0 0
v_max_non_paid_invoice 1548 1548 0 0.00 0 173 2 0 0
v_max_non_paid_realizn 3761 3761 0 0.00 0 4985 5 0 0
v_min_id_clo_ord 5262 5262 0 0.00 0 4432 9 0 0
v_min_id_clo_res 965 965 0 0.00 0 7183 13 0 0
v_min_id_ord_sup 5291 5291 0 0.00 0 7958 12 0 0
v_min_non_paid_invoice 5730 5730 0 0.00 0 6332 13 0 0
v_min_non_paid_realizn 6915 6915 0 0.00 0 4171 13 0 0
v_our_firm 9968 9968 0 0.00 0 18 1 0 0
v_reserve_write_off 19542 19542 0 0.00 0 10879 8 0 0
x_lock_depdocs_on_canc_invoice 10175 10175 0 0.00 0 22917 123 0 0
x_lock_depdocs_on_canc_sup_ord 363 363 0 0.00 0 6678 385 0 0
x_make_qstorno_1000_1200 5262 5262 0 0.00 2 42847 1569 0 0
x_make_qstorno_1200_2000 5303 5303 0 0.00 4 74938 3622 0 0
x_make_qstorno_2100_3300 452697 452697 0 0.00 0 45168 188 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-10-2021 04:41:05 to 04-10-2021 04:41: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
02:07:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 25508 8192000000 9721155312 10018852864 798410192 8.880 100 86 172 0 827433296 8040736 20982368
02:09:45 23055 8192000000 9761005936 10069434368 835297328 9.253 100 82 164 1 867202832 12604608 19300896
02:11:14 10225 8192000000 9796974576 10114916352 865294720 9.553 100 84 168 0 907469440 22609104 19565616
02:12:28 15652 8192000000 9863446448 10193027072 904866768 9.947 100 75 150 0 962641936 40821056 16954112
02:13:55 12470 8192000000 9920366944 10260201472 948225328 10.374 100 80 160 0 1020546176 55433440 16887408
02:15:09 9207 8192000000 9945701824 10299052032 980120288 10.685 100 82 163 0 1057149088 60786272 16242528
02:16:23 3879 8192000000 10006261872 10366373888 1010773536 10.983 100 82 163 0 1117654000 90694512 16185952
02:17:29 9164 8192000000 10004023536 10375458816 1038682032 11.252 100 73 146 0 1132803120 79637440 14483648
02:18:41 1840 8192000000 10038001344 10411507712 1064231552 11.497 100 86 171 0 1148896192 66862592 17802048
02:19:44 2925 8192000000 10007981664 10404331520 1072653040 11.577 100 41 82 0 1128231904 47343328 8235536
02:20:49 6342 8192000000 10009809280 10399600640 1082697216 11.673 100 60 119 0 1128629296 32778096 13153984
02:21:58 9003 8192000000 9985743968 10391015424 1087470816 11.719 100 26 52 0 1114898912 22059504 5368592
02:23:13 35630 8192000000 9999185904 10411446272 1093637376 11.777 100 31 62 1 1110030016 9530560 6862080
02:24:51 5390 8192000000 10005329696 10417434624 1096768432 11.807 100 38 76 2 1124412272 20529776 7114064
02:25:58 6477 8192000000 10030955152 10439716864 1099756816 11.835 100 30 60 3 1140243648 34392592 6094240
02:27:04 13098 8192000000 9985874224 10384019456 1060564704 11.462 97 29 56 2 1090787424 23028240 7194480
02:28:18 24896 8192000000 9987263696 10394619904 1053922832 11.398 97 12 23 2 1080214064 23356016 2935216
02:29:43 2876 8192000000 9949834720 10330431488 1021829616 11.090 98 53 107 0 1054221440 20418160 11973664
02:30:46 4058 8192000000 9939276816 10319572992 991264672 10.794 95 46 93 1 1017955936 16320224 10371040
02:31:51 54921 8192000000 9902103328 10283749376 967759632 10.565 96 32 62 1 990942912 15219376 7963904
02:33:46 5540 8192000000 9951483744 10335461376 1022219008 11.093 99 46 92 0 1051827168 19827152 9781008
02:34:52 7062 8192000000 9969226992 10353831936 1038445040 11.250 99 31 61 1 1069543712 24306064 6792608
02:36:01 5202 8192000000 9988634480 10393792512 1068351984 11.536 100 25 49 1 1091733984 18463232 4918768
02:37:08 15900 8192000000 10009110704 10403397632 1078340016 11.632 100 43 84 1 1106260192 19727584 8192592
02:38:31 5981 8192000000 10048681920 10444500992 1097795088 11.817 100 62 123 0 1160436000 50416400 12224512
02:39:40 27809 8192000000 10074367984 10497634304 1117941824 12.008 100 45 89 1 1191152384 64190256 9020304
02:41:13 18711 8192000000 10136524784 10540019712 1128151040 12.104 100 65 130 0 1228542352 86246400 14144912
02:42:46 29096 8192000000 10118678400 10546155520 1142476384 12.239 100 45 89 3 1236208352 84084656 9647312
02:44:47 3116 8192000000 10165156336 10581286912 1160601200 12.409 100 72 142 1 1275647488 99330096 15716192
02:45:54 16444 8192000000 10239300416 10647322624 1166621424 12.465 100 66 130 0 1331363664 150277120 14465120
02:47:16 6841 8192000000 10225291392 10634571776 1168775216 12.485 100 70 139 1 1324657008 140329120 15552672
02:48:30 4384 8192000000 10277205040 10691457024 1180516672 12.595 100 65 130 0 1381312112 186340448 14454992
02:49:40 2686 8192000000 10315482704 10720813056 1189838000 12.682 100 86 170 2 1415648736 206476240 19334496
02:50:47 2819 8192000000 10333017008 10746753024 1178974992 12.581 100 74 148 0 1435264320 238954160 17335168
02:51:55 3917 8192000000 10403585120 10809876480 1183518816 12.623 100 86 166 2 1504971344 301563424 19889104
02:53:05 2092 8192000000 10440751552 10847924224 1182932016 12.618 100 96 190 1 1543802624 338358688 22511920
02:54:11 856 8192000000 10482235424 10884165632 1179877520 12.589 100 94 189 1 1577332560 375524752 21930288
02:55:17 2994 8192000000 10459626448 10865487872 1174629616 12.540 100 80 160 0 1552612944 359626080 18357248
02:56:27 4359 8192000000 10446685696 10869927936 1151071328 12.320 100 73 146 2 1551578736 382766304 17741104
02:57:37 10138 8192000000 10394254608 10890440704 1136566528 12.183 100 38 76 0 1516413312 368937040 10909744
02:59:22 5530 8192000000 10322956272 10758799360 1141253984 12.227 100 43 87 0 1422426784 270547408 10625392
03:00:33 17864 8192000000 10301761200 10743537664 1113391072 11.965 99 22 44 0 1391583200 272060256 6131872
03:01:55 15394 8192000000 10248037120 10700845056 1112252096 11.954 100 31 62 0 1354151520 233932032 7967392
03:03:12 105342 8192000000 10063397344 10508046336 1070365376 11.556 95 32 64 0 1144809472 67526784 6917312
03:06:09 75281 8192000000 10102056752 10558132224 1125047744 12.075 100 31 62 2 1180844144 49038656 6757744
03:08:31 90270 8192000000 10138621152 10577440768 1128607360 12.108 99 18 36 0 1200496336 68228976 3660000
03:11:02 55691 8192000000 10125446976 10619854848 1097522256 11.814 97 13 27 0 1218546448 118134784 2889408
03:13:01 90956 8192000000 10044779312 10502950912 1118727792 12.015 99 19 38 0 1211245824 88433776 4084256
03:15:38 70931 8192000000 10065888896 10495488000 1111966096 11.951 98 35 70 0 1156615440 36892608 7756736
03:17:55 60227 8192000000 10124529504 10565881856 1120437904 12.031 99 35 70 0 1215419488 87579344 7402240
03:20:04 61005 8192000000 10134640080 10577592320 1118763600 12.015 99 10 20 0 1226486544 105413504 2309440
03:22:09 49928 8192000000 10119046208 10631606272 1129242464 12.114 100 19 38 0 1239414336 105657136 4514736
03:24:02 63536 8192000000 10100919936 10566639616 1126350592 12.087 99 21 40 1 1176491776 45642848 4498336
03:26:06 42717 8192000000 10077920032 10496753664 1125524480 12.079 99 25 48 1 1166606448 35891824 5190144
03:27:51 60254 8192000000 10119885888 10569805824 1131943632 12.140 99 29 58 0 1222485120 84544928 5996560
03:29:51 122962 8192000000 10074405584 10555187200 1115321024 11.983 97 24 48 0 1201962960 81694272 4947664
03:32:55 40468 8192000000 10129794912 10608103424 1146379968 12.276 100 23 44 1 1223400336 72401136 4619232
03:35:13 168237 8192000000 10101496816 10555678720 1135389568 12.172 99 26 52 0 1180310528 39957872 4963088
03:39:03 82100 8192000000 10111126704 10573074432 1132032432 12.141 98 18 36 0 1202129328 66509760 3587136
03:41:57 53046 8192000000 10121396544 10615836672 1145591504 12.268 99 13 26 0 1209926560 61416608 2918448
03:43:50 30251 8192000000 10050719376 10506997760 1133511008 12.154 99 20 37 1 1159644912 22101792 4032112
03:45:21 35586 8192000000 10093937872 10537455616 1122497968 12.051 98 22 44 0 1181159248 54283504 4377776
03:47:02 48267 8192000000 10111061952 10594869248 1127680816 12.099 99 30 60 0 1186011184 51943632 6386736
03:48:51 27798 8192000000 10064199776 10489307136 1091333296 11.755 97 28 56 1 1127436736 29877072 6226368
03:50:21 80537 8192000000 10082962112 10530131968 1110408560 11.936 98 35 70 0 1171071728 53676512 6986656
03:52:43 24411 8192000000 10090132928 10510422016 1059860160 11.455 94 31 62 1 1126760016 60768304 6131552
03:54:58 137489 8192000000 10039036032 10472529920 1095573984 11.796 98 12 24 0 1138618672 40826624 2218064
03:58:18 78266 8192000000 10045614752 10465869824 1079751360 11.645 97 27 54 1 1119268800 34493152 5024288
04:00:37 61230 8192000000 10061263360 10535243776 1126030704 12.084 100 20 40 0 1194937008 64403632 4502672
04:02:40 82434 8192000000 10064485664 10522714112 1107479456 11.909 98 13 26 1 1138803360 27434672 3889232
04:05:18 55366 8192000000 10056579952 10515894272 1113149632 11.962 99 14 28 0 1162653072 46118560 3384880
04:07:49 44487 8192000000 10091465088 10526556160 1133057360 12.150 100 40 80 0 1176612096 35156672 8398064
04:09:40 44382 8192000000 10054922352 10494205952 1080261376 11.650 96 21 42 0 1113664272 28992880 4410016
04:11:30 68525 8192000000 10083607872 10555441152 1109843984 11.931 98 29 58 1 1174075856 57915504 6316368
04:13:43 78555 8192000000 10078001696 10538037248 1127810576 12.101 99 18 36 0 1180141776 48850848 3480352
04:16:04 55935 8192000000 10090418864 10562936832 1143439200 12.248 100 29 57 1 1224341952 75059488 5843264
04:18:03 136068 8192000000 10048614672 10500489216 1114657984 11.976 98 19 36 2 1169655824 50998288 3999552
04:21:24 44956 8192000000 10087277248 10544902144 1142913888 12.243 100 14 28 1 1191916080 45999184 3003008
04:24:20 100235 8192000000 10120226240 10604544000 1126614816 12.089 98 29 58 1 1225060544 91687600 6758128
04:27:04 65038 8192000000 10076807712 10518941696 1132890880 12.149 99 21 42 0 1169195664 31672592 4632192
04:29:17 73354 8192000000 10112493056 10597994496 1142847936 12.242 99 18 36 4 1204608336 57407264 4353136
04:31:37 102638 8192000000 10108072688 10587365376 1112628368 11.957 97 19 38 1 1185220208 68471744 4120096
04:34:21 30341 8192000000 10105228368 10576142336 1095866672 11.798 96 41 82 0 1186662032 82228384 8566976
04:35:53 117804 8192000000 9684834944 10137968640 771532688 8.607 69 4 8 0 818335536 46313568 489280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-10-2021 04:41:11 to 04-10-2021 04:41:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 139 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-10-2021 04:41:27 to 04-10-2021 04:41: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          229667
MON$OLDEST_ACTIVE               229668
MON$OLDEST_SNAPSHOT             229668
MON$NEXT_TRANSACTION            229668
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                       2010048
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2622 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2622 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2622 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2622 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 ---
:29: 
:30: mscs-test-161.budzdorov.ru    Mon Oct  4 04:38:48 2021
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
--- end of diff output ---

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

04.10.21 04:41: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-10-04 04:41:32 No severe PSQL-related problems occured
04.10.21 04:41:33. End of report.