Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.09.2021 13: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 15722088 kB
MemAvailable 30816960 kB
Buffers 0 kB
Cached 15935444 kB
SwapCached 412 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-09-14 13:37:07
DTS_END                         2021-09-14 13:37:07.2710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(451:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56908
TRN_ID                          245991
ATT_ID                          642
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: 9344.42 24413 1121331
customer order: creation 179.08 368 21490
customer order: refuse 15.28 315 1834
order to supplier: creation 48.03 1469 5763
order to supplier: removal 3.79 105953 455
invoice (draft): creation 47.86 3435 5743
invoice (draft): removal 51.78 937 6214
invoice accept: apply 43.07 18995 5168
invoice accept: cancel 46.93 861 5632
customer reserve: creation 3936.08 190 472330
customer reserve: removal 4672.97 69 560756
realization accept: apply 88.10 191 10572
realization accept: cancel 77.96 128 9355
payment from customer: creation 61.31 106 7357
payment from customer: removal 6.07 68 728
payment to supplier: creation 51.23 114 6148
payment to supplier: removal 3.73 89 448
service: total inventory turnovers 3.88 3613 465
service: total monetary turnovers 6.33 576 759
service: aggregate perf. data 0.92 16047 110
service: refresh index statistics 0.03 334743 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-09-2021 13:37:55 to 14-09-2021 13:37:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4755
TEST_TIME 2 6188
TEST_TIME 3 6063
TEST_TIME 4 6266
TEST_TIME 5 6443
TEST_TIME 6 6618
TEST_TIME 7 6963
TEST_TIME 8 7175
TEST_TIME 9 7724
TEST_TIME 10 7788
TEST_TIME 11 7974
TEST_TIME 12 7855
TEST_TIME 13 8094
TEST_TIME 14 7935
TEST_TIME 15 7978
TEST_TIME 16 8083
TEST_TIME 17 8602
TEST_TIME 18 8681
TEST_TIME 19 8843
TEST_TIME 20 9313
TEST_TIME 21 9504
TEST_TIME 22 9513
TEST_TIME 23 9367
TEST_TIME 24 9767
TEST_TIME 25 10270
TEST_TIME 26 10510
TEST_TIME 27 10654
TEST_TIME 28 11960
TEST_TIME 29 11808
TEST_TIME 30 11536
TEST_TIME 31 11313
TEST_TIME 32 11257
TEST_TIME 33 11495
TEST_TIME 34 11232
TEST_TIME 35 11290
TEST_TIME 36 11176
TEST_TIME 37 11113
TEST_TIME 38 10956
TEST_TIME 39 10936
TEST_TIME 40 10847
TEST_TIME 41 10775
TEST_TIME 42 10679
TEST_TIME 43 10579
TEST_TIME 44 10506
TEST_TIME 45 10549
TEST_TIME 46 10588
TEST_TIME 47 10527
TEST_TIME 48 10487
TEST_TIME 49 10499
TEST_TIME 50 10556
TEST_TIME 51 10466
TEST_TIME 52 10508
TEST_TIME 53 10467
TEST_TIME 54 10451
TEST_TIME 55 10503
TEST_TIME 56 10467
TEST_TIME 57 10540
TEST_TIME 58 10380
TEST_TIME 59 10326
TEST_TIME 60 10357
TEST_TIME 61 10248
TEST_TIME 62 10207
TEST_TIME 63 10129
TEST_TIME 64 10009
TEST_TIME 65 10037
TEST_TIME 66 9990
TEST_TIME 67 9989
TEST_TIME 68 9916
TEST_TIME 69 9868
TEST_TIME 70 9922
TEST_TIME 71 9916
TEST_TIME 72 9904
TEST_TIME 73 9863
TEST_TIME 74 9865
TEST_TIME 75 9940
TEST_TIME 76 9942
TEST_TIME 77 10005
TEST_TIME 78 10077
TEST_TIME 79 10011
TEST_TIME 80 9891
TEST_TIME 81 9915
TEST_TIME 82 9908
TEST_TIME 83 9881
TEST_TIME 84 9814
TEST_TIME 85 9761
TEST_TIME 86 9744
TEST_TIME 87 9673
TEST_TIME 88 9649
TEST_TIME 89 9601
TEST_TIME 90 9543
TEST_TIME 91 9552
TEST_TIME 92 9512
TEST_TIME 93 9503
TEST_TIME 94 9553
TEST_TIME 95 9584
TEST_TIME 96 9573
TEST_TIME 97 9601
TEST_TIME 98 9586
TEST_TIME 99 9609
TEST_TIME 100 9617
TEST_TIME 101 9611
TEST_TIME 102 9573
TEST_TIME 103 9655
TEST_TIME 104 9568
TEST_TIME 105 9503
TEST_TIME 106 9487
TEST_TIME 107 9441
TEST_TIME 108 9368
TEST_TIME 109 9385
TEST_TIME 110 9378
TEST_TIME 111 9338
TEST_TIME 112 9313
TEST_TIME 113 9277
TEST_TIME 114 9243
TEST_TIME 115 9264
TEST_TIME 116 9257
TEST_TIME 117 9244
TEST_TIME 118 9243
TEST_TIME 119 9421
TEST_TIME 120 9345

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-09-2021 13:37:59 to 14-09-2021 13:38:02.

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 1558224 1558224 0 0.00 0 35573 7 0 0
doc_list_biud 1558224 1558224 0 0.00 0 48653 31 0 0
sp_add_invoice_to_stock 5288 5168 120 2.26 44 222343 18995 0 120
sp_cancel_adding_invoice 5632 5632 0 0.00 5 77622 862 0 0
sp_cancel_client_order 1834 1834 0 0.00 5 11672 316 0 0
sp_cancel_customer_reserve 560756 560756 0 0.00 1 28507 69 0 0
sp_cancel_pay_from_customer 728 728 0 0.00 3 3479 68 0 0
sp_cancel_pay_to_supplier 448 448 0 0.00 3 3724 90 0 0
sp_cancel_supplier_invoice 6226 6214 12 0.19 2 48656 937 0 12
sp_cancel_supplier_order 455 455 0 0.00 9 1221417 105954 0 0
sp_cancel_write_off 9355 9355 0 0.00 1 12756 129 0 0
sp_client_order 21490 21490 0 0.00 4 37777 368 0 0
sp_customer_reserve 472330 472330 0 0.00 0 39384 191 0 0
sp_fill_shopping_cart 32983 32983 0 0.00 1 16285 39 0 0
sp_fill_shopping_cart_clo_res 472328 472328 0 0.00 0 12256 16 0 0
sp_get_clo_for_invoice 5166 5166 0 0.00 0 26124 335 0 0
sp_kill_cost_storno 16163 16163 0 0.00 0 35562 62 0 0
sp_kill_qstorno_ret_qs2qd 1137536 1137536 0 0.00 0 48651 37 0 0
sp_kill_qty_storno 599983 599983 0 0.00 0 48652 80 0 0
sp_lock_selected_doc 21816 21816 0 0.00 0 7926 5 0 0
sp_make_cost_storno 29242 29242 0 0.00 0 20235 54 0 0
sp_multiply_rows_for_pdistr 29242 29242 0 0.00 0 13199 29 0 0
sp_multiply_rows_for_qdistr 21490 21490 0 0.00 0 37739 196 0 0
sp_pay_from_customer 7357 7357 0 0.00 4 17709 107 0 0
sp_pay_to_supplier 6148 6148 0 0.00 5 23229 115 0 0
sp_payment_common 13505 13505 0 0.00 1 16586 23 0 0
sp_qd_handle_on_cancel_clo 1834 1834 0 0.00 0 11666 298 0 0
sp_qd_handle_on_invoice_upd_sts 10797 10797 0 0.00 0 13357 332 0 0
sp_qd_handle_on_reserve_upd_sts 10572 10572 0 0.00 0 15169 64 0 0
sp_reserve_write_off 10572 10572 0 0.00 6 20280 192 0 0
sp_supplier_invoice 5743 5743 0 0.00 7 79283 3436 0 0
sp_supplier_order 5763 5763 0 0.00 5 76393 1469 0 0
srv_aggregate_perf_data 110 110 0 0.00 2892 271657 16048 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1097 154780 42220 0 0
srv_find_qd_qs_mism 1071769 1071769 0 0.00 0 12912 6 0 0
srv_make_invnt_saldo 465 465 0 0.00 41 93810 3614 0 0
srv_make_money_saldo 759 759 0 0.00 0 13698 576 0 0
srv_recalc_idx_stat 4 4 0 0.00 170567 413111 334743 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5168 5168 0 0.00 0 14763 193 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 2494 82 0 0
t$perf-norm:sp_cancel_client_order 1834 1834 0 0.00 0 1046 6 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 1466 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 728 728 0 0.00 0 1096 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 448 448 0 0.00 0 1451 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 1204 20 0 0
t$perf-norm:sp_cancel_supplier_order 455 455 0 0.00 0 17526 1217 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 3362 11 0 0
t$perf-norm:sp_client_order 21490 21490 0 0.00 0 5442 17 0 0
t$perf-norm:sp_customer_reserve 1102 1102 0 0.00 0 3266 11 0 0
t$perf-norm:sp_pay_from_customer 7357 7357 0 0.00 0 6151 11 0 0
t$perf-norm:sp_pay_to_supplier 6148 6148 0 0.00 0 4494 10 0 0
t$perf-norm:sp_reserve_write_off 10572 10572 0 0.00 0 8782 11 0 0
t$perf-norm:sp_supplier_invoice 5743 5743 0 0.00 0 7045 26 0 0
t$perf-norm:sp_supplier_order 5763 5763 0 0.00 0 6633 19 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 79 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 109 4 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 407 5 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 1704 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10570 10330 240 2.27 0 7391 17 0 240
v_all_customers 38480 38480 0 0.00 0 38 1 0 0
v_all_suppliers 31686 31686 0 0.00 0 80 1 0 0
v_all_wares 42980 42980 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 1729 10 0 0
v_cancel_client_order 3668 3668 0 0.00 0 1660 4 0 0
v_cancel_customer_prepayment 1456 1456 0 0.00 0 2080 6 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 2863 7 0 0
v_cancel_payment_to_supplier 896 896 0 0.00 0 3370 13 0 0
v_cancel_supplier_invoice 908 884 24 2.64 0 587 12 0 24
v_cancel_supplier_order 910 910 0 0.00 0 563 4 0 0
v_cancel_write_off 1988 1988 0 0.00 0 1420 14 0 0
v_max_id_clo_ord 5763 5763 0 0.00 0 4384 3 0 0
v_max_id_clo_res 1102 1102 0 0.00 0 3903 26 0 0
v_max_id_ord_sup 5729 5729 0 0.00 0 3023 4 0 0
v_max_non_paid_invoice 1796 1796 0 0.00 0 7330 7 0 0
v_max_non_paid_realizn 4196 4196 0 0.00 0 3765 4 0 0
v_min_id_clo_ord 5763 5763 0 0.00 0 1077 4 0 0
v_min_id_clo_res 1102 1102 0 0.00 0 6286 22 0 0
v_min_id_ord_sup 5729 5729 0 0.00 0 5907 10 0 0
v_min_non_paid_invoice 6148 6148 0 0.00 0 9253 15 0 0
v_min_non_paid_realizn 7357 7357 0 0.00 0 6362 15 0 0
v_our_firm 10822 10822 0 0.00 0 40 1 0 0
v_reserve_write_off 21144 21144 0 0.00 0 3397 6 0 0
x_lock_depdocs_on_canc_invoice 11404 11404 0 0.00 0 36738 119 0 0
x_lock_depdocs_on_canc_sup_ord 455 455 0 0.00 0 5208 278 0 0
x_make_qstorno_1000_1200 5763 5763 0 0.00 2 76351 1390 0 0
x_make_qstorno_1200_2000 5743 5743 0 0.00 3 79174 3291 0 0
x_make_qstorno_2100_3300 472330 472330 0 0.00 0 39040 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-09-2021 13:38:03 to 14-09-2021 13:38:07.

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
11:07:11 2 8192000000 8797560800 8808484864 3901568 0.047 2 1 2 0 4000880 63536 35776
11:08:11 25837 8192000000 9717481856 10015088640 788686704 8.782 100 83 166 1 816631552 7742208 20202640
11:09:46 25405 8192000000 9776982448 10085400576 842791520 9.328 100 94 188 0 877808720 12981264 22035936
11:11:26 14367 8192000000 9805818976 10128220160 867714624 9.577 100 78 156 0 911572512 26185488 17672400
11:12:46 11508 8192000000 9875481728 10213855232 913729328 10.034 100 72 144 0 977138144 47438256 15970560
11:14:01 7123 8192000000 9927086720 10276397056 968523216 10.572 100 80 160 1 1033881232 48949424 16408592
11:15:12 3269 8192000000 9978000944 10337144832 994696128 10.827 100 75 147 0 1072375008 62797696 14881184
11:16:16 2522 8192000000 10010053728 10371678208 1018711168 11.060 100 89 174 1 1110475072 74048416 17715488
11:17:21 1939 8192000000 10035098768 10404331520 1050075952 11.361 100 89 177 2 1135530672 66911376 18543344
11:18:24 30701 8192000000 10009779344 10396524544 1065737776 11.511 100 59 116 2 1114371808 36216368 12417664
11:19:55 3863 8192000000 10015137360 10407071744 1075069696 11.600 100 55 107 1 1115065296 28085184 11910416
11:21:05 6057 8192000000 10002363712 10392141824 1077593776 11.625 100 49 100 0 1103721680 15458176 10669728
11:22:14 10414 8192000000 10007948688 10423656448 1083436896 11.680 100 26 51 1 1126028720 37156976 5434848
11:23:25 32144 8192000000 10035149344 10438778880 1086754832 11.712 100 37 75 1 1125607952 31345760 7507360
11:24:58 5163 8192000000 10017292560 10428379136 1090747872 11.750 100 42 82 1 1126605984 26437568 9420544
11:26:05 11285 8192000000 10012324672 10425696256 1082104112 11.668 100 31 62 1 1112738816 23140160 7494544
11:27:21 6057 8192000000 9975933456 10380935168 1056449984 11.422 98 31 62 2 1072601584 8774928 7376672
11:28:31 19967 8192000000 9963134656 10365460480 1005037936 10.927 94 27 54 1 1029570704 18649440 5883328
11:29:57 6331 8192000000 9899407584 10278719488 959226768 10.481 94 40 78 3 982345664 13736896 9382000
11:31:04 8833 8192000000 9959393792 10344976384 1015303104 11.027 99 31 62 1 1043706800 21832000 6571696
11:32:13 49668 8192000000 9940790656 10337488896 1030721152 11.175 99 23 46 0 1054439072 17343632 6374288
11:34:09 6680 8192000000 9974702032 10363469824 1051992176 11.380 100 41 79 2 1079975936 18983168 9000592
11:35:16 7273 8192000000 9973993424 10379866112 1061860272 11.474 100 23 46 1 1100796032 33875248 5060512
11:36:25 7560 8192000000 9978233424 10377863168 1066837904 11.522 100 35 68 0 1094793888 20286800 7669184
11:37:34 16746 8192000000 10003834304 10403475456 1080384256 11.651 100 46 91 2 1106470896 16855248 9231392
11:39:08 6841 8192000000 10023623776 10422607872 1097376032 11.813 100 44 88 0 1135263120 28730016 9157072
11:40:30 5325 8192000000 10085643632 10492960768 1113060368 11.961 100 63 123 2 1195518912 69663840 12794704
11:41:40 18981 8192000000 10113185664 10526326784 1124024480 12.065 100 45 88 0 1220260000 86236368 9999152
11:43:15 4169 8192000000 10168931616 10582581248 1133795664 12.157 100 58 114 0 1275749136 129879328 12074144
11:44:23 6107 8192000000 10178532544 10584162304 1138729056 12.204 100 65 130 1 1286780176 133903872 14147248
11:45:33 7378 8192000000 10153571712 10575187968 1127884400 12.101 99 53 105 0 1265959296 126494912 11579984
11:46:43 3131 8192000000 10183607776 10603479040 1139024512 12.206 100 57 113 0 1292522848 140898080 12600256
11:47:49 5140 8192000000 10198501664 10594033664 1131447760 12.135 98 81 160 4 1293679568 143927200 18304608
11:48:58 3064 8192000000 10215009184 10619449344 1140742352 12.223 99 73 146 1 1307795984 150714976 16338656
11:50:06 2785 8192000000 10246942272 10647461888 1155032192 12.357 100 82 165 0 1354220288 180863104 18324992
11:51:16 5702 8192000000 10264944992 10674077696 1141884896 12.233 98 58 118 3 1355905504 200305648 13714960
11:52:27 3084 8192000000 10282514800 10688806912 1143230848 12.246 100 57 114 0 1390004192 232516816 14256528
11:53:36 3575 8192000000 10300348432 10710511616 1136219600 12.180 100 68 135 1 1400065024 248600448 15244976
11:54:47 1410 8192000000 10302687888 10693181440 1124102656 12.066 100 96 191 1 1395219984 249189024 21928304
11:55:52 4861 8192000000 10325768944 10724675584 1123162928 12.057 100 72 142 2 1437000912 297222592 16615392
11:56:59 12908 8192000000 10304545376 10742120448 1121649968 12.043 100 34 68 0 1420721584 290446528 8625088
11:58:18 1095 8192000000 10336808672 10736324608 1129453312 12.116 100 90 181 1 1432657120 281973024 21230784
11:59:20 10132 8192000000 10315676352 10729762816 1105707424 11.892 97 39 78 1 1406159680 289953600 10498656
12:00:32 6269 8192000000 10376793280 10794311680 1130519776 12.126 100 44 89 1 1478562080 336427440 11614864
12:01:41 17371 8192000000 10358808816 10786115584 1132178240 12.142 100 33 66 1 1454978592 314143040 8657312
12:03:06 29768 8192000000 10349673216 10815950848 1137631712 12.193 100 23 46 0 1444002096 299931008 6439376
12:04:46 34775 8192000000 10192350384 10775592960 1099744672 11.835 98 17 33 0 1395499552 291351536 4403344
12:06:27 25728 8192000000 10040798496 10458656768 1094774304 11.788 98 35 72 2 1124759664 21287808 8697552
12:08:04 58121 8192000000 10097334928 10529771520 1111085072 11.943 99 43 78 0 1188027552 68178816 8763664
12:10:04 98937 8192000000 10101379392 10530492416 1133577344 12.155 99 25 50 0 1193622608 54792320 5252944
12:13:14 45130 8192000000 10082766400 10523389952 1130313008 12.124 99 17 34 0 1162164304 28411056 3440240
12:15:02 44964 8192000000 10127023616 10576883712 1152916576 12.337 100 17 34 0 1230018608 73315216 3786816
12:17:34 54765 8192000000 10121010976 10568146944 1153557008 12.343 100 21 41 1 1230498192 72036048 4905136
12:19:54 94906 8192000000 10118852768 10601095168 1144568448 12.258 99 23 45 0 1222471792 71789472 6113872
12:22:35 47151 8192000000 10085094400 10570641408 1144563456 12.258 100 10 20 0 1207294960 60598576 2132928
12:24:22 57248 8192000000 10098200448 10552827904 1117575264 12.004 96 21 42 0 1171226576 49670400 3980912
12:26:28 56019 8192000000 10107086800 10553626624 1123395904 12.059 98 22 44 0 1178201344 49750400 5055040
12:28:35 49701 8192000000 10114302016 10555547648 1116596576 11.995 97 32 63 0 1192048768 69066944 6385248
12:30:25 13519 8192000000 10079594960 10504347648 1123340128 12.059 99 49 95 0 1164549200 30712912 10496160
12:31:40 64719 8192000000 10081666416 10549542912 1048462800 11.346 93 26 50 1 1097403136 44130816 4809520
12:33:50 131726 8192000000 10129154736 10613325824 1109706480 11.930 98 15 30 0 1180556544 67915040 2935024
12:37:19 83690 8192000000 10062473920 10542542848 1096238320 11.802 97 17 34 0 1138312224 39196592 2877312
12:39:43 54369 8192000000 10042837776 10507857920 1079652000 11.644 96 10 20 0 1144665296 63087056 1926240
12:41:39 62629 8192000000 10055812992 10517733376 1088387776 11.727 97 12 24 0 1151521872 60899072 2235024
12:43:42 85955 8192000000 10044049840 10494357504 1058376864 11.441 94 19 39 0 1091238512 28793904 4067744
12:46:08 23572 8192000000 10101548928 10517155840 1111097680 11.943 99 49 97 1 1169727952 48695840 9934432
12:47:33 47526 8192000000 10056525168 10536312832 1092524640 11.767 98 15 30 0 1190714944 94744208 3446096
12:49:30 65403 8192000000 10121974096 10570821632 1113157856 11.962 99 29 58 0 1201072736 82105584 5809296
12:51:37 49455 8192000000 10069321744 10546515968 1107389072 11.908 98 22 44 1 1197224544 84948144 4887328
12:53:27 89943 8192000000 10134206224 10593923072 1109455376 11.927 98 16 32 2 1187621920 74971280 3195264
12:56:04 154780 8192000000 10087857984 10551926784 1100740288 11.845 97 14 28 0 1193623408 89454128 3428992
13:01:17 69135 8192000000 10072613472 10532474880 1126774576 12.091 99 6 12 0 1161326176 33527552 1024048
13:03:35 55249 8192000000 10082143936 10560757760 1140592336 12.221 100 19 39 0 1167958384 23158880 4207168
13:05:58 24263 8192000000 10062028912 10542051328 1134466224 12.163 99 20 39 1 1159400128 20358336 4575568
13:07:33 74525 8192000000 10075733360 10572288000 1145873984 12.271 100 11 22 0 1173899280 26071504 1953792
13:09:49 53967 8192000000 10105081264 10568720384 1098274736 11.821 95 14 28 0 1160607008 59383264 2949008
13:11:59 25237 8192000000 10091374864 10553360384 1115290624 11.982 97 17 35 0 1170443776 51761600 3391552
13:13:25 53637 8192000000 10101471296 10562785280 1105102256 11.886 96 27 54 0 1164794576 54217056 5475264
13:15:23 39642 8192000000 10106399040 10533875712 1129996400 12.121 98 50 100 3 1174080480 33857200 10226880
13:17:06 65376 8192000000 10078653008 10543128576 1120110528 12.028 98 30 60 1 1172981760 47039520 5831712
13:19:21 23372 8192000000 10112948592 10553204736 1121480784 12.041 99 51 102 0 1195650944 64289376 9880784
13:20:51 100088 8192000000 10062834160 10510397440 1117147328 12.000 99 23 46 1 1176538256 54437072 4953856
13:23:31 60025 8192000000 10039675552 10496401408 1082578336 11.672 97 34 68 1 1137097344 48120912 6398096
13:25:34 54999 8192000000 10078083056 10548117504 1110514320 11.937 100 12 24 1 1174496336 61174752 2807264
13:27:59 43949 8192000000 10060359952 10536931328 1087695648 11.721 98 11 22 1 1144663232 54554832 2412752
13:29:44 47651 8192000000 10116773088 10560258048 1104980304 11.885 98 22 44 0 1194403120 84494608 4928208
13:31:37 45882 8192000000 10170668224 10648502272 1118079760 12.009 99 33 66 0 1267041104 140528816 8432528
13:33:24 79282 8192000000 10195027264 10638807040 1096371952 11.803 97 24 48 0 1264397392 162239920 5785520
13:35:45 57197 8192000000 10082340864 10641166336 1080707600 11.654 97 11 22 0 1193473552 110261040 2504912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-09-2021 13:38:25 to 14-09-2021 13:38:27.

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          245671
MON$OLDEST_ACTIVE               246267
MON$OLDEST_SNAPSHOT             246267
MON$NEXT_TRANSACTION            246267
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                       2075040
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             827
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

14.09.21 13:38:28. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-09-14 13:38:30 No severe PSQL-related problems occured
14.09.21 13:38:31. End of report.