Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.09.2021 07:47
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 15995828 kB
MemAvailable 30810264 kB
Buffers 0 kB
Cached 15671420 kB
SwapCached 416 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-16 07:46:47
DTS_END                         2021-09-16 07:46:47.7240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54900
TRN_ID                          229376
ATT_ID                          672
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: 8809.04 30275 1057085
customer order: creation 159.65 459 19158
customer order: refuse 13.75 383 1650
order to supplier: creation 43.67 1535 5240
order to supplier: removal 3.53 122905 424
invoice (draft): creation 43.74 4406 5249
invoice (draft): removal 50.60 1140 6072
invoice accept: apply 39.42 19529 4731
invoice accept: cancel 45.38 1009 5446
customer reserve: creation 3681.83 188 441820
customer reserve: removal 4449.51 75 533941
realization accept: apply 79.20 188 9504
realization accept: cancel 76.58 142 9189
payment from customer: creation 56.13 127 6736
payment from customer: removal 5.88 102 705
payment to supplier: creation 46.25 143 5550
payment to supplier: removal 3.25 125 390
service: total inventory turnovers 3.48 4069 418
service: total monetary turnovers 6.23 476 748
service: aggregate perf. data 0.92 15370 110
service: refresh index statistics 0.03 433128 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-09-2021 07:47:52 to 16-09-2021 07:47:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3238
TEST_TIME 2 2382
TEST_TIME 3 3871
TEST_TIME 4 3942
TEST_TIME 5 3924
TEST_TIME 6 4787
TEST_TIME 7 4997
TEST_TIME 8 5503
TEST_TIME 9 5419
TEST_TIME 10 5777
TEST_TIME 11 5893
TEST_TIME 12 5921
TEST_TIME 13 6124
TEST_TIME 14 6252
TEST_TIME 15 6162
TEST_TIME 16 6500
TEST_TIME 17 6622
TEST_TIME 18 6658
TEST_TIME 19 6735
TEST_TIME 20 7031
TEST_TIME 21 7491
TEST_TIME 22 7358
TEST_TIME 23 7558
TEST_TIME 24 7824
TEST_TIME 25 8269
TEST_TIME 26 8750
TEST_TIME 27 8806
TEST_TIME 28 9463
TEST_TIME 29 10290
TEST_TIME 30 10644
TEST_TIME 31 10451
TEST_TIME 32 10260
TEST_TIME 33 10163
TEST_TIME 34 10083
TEST_TIME 35 9867
TEST_TIME 36 9811
TEST_TIME 37 9719
TEST_TIME 38 9559
TEST_TIME 39 9529
TEST_TIME 40 9587
TEST_TIME 41 9525
TEST_TIME 42 9464
TEST_TIME 43 9321
TEST_TIME 44 9239
TEST_TIME 45 9193
TEST_TIME 46 9135
TEST_TIME 47 9109
TEST_TIME 48 9178
TEST_TIME 49 9085
TEST_TIME 50 9091
TEST_TIME 51 9139
TEST_TIME 52 9155
TEST_TIME 53 9105
TEST_TIME 54 9086
TEST_TIME 55 9116
TEST_TIME 56 9024
TEST_TIME 57 9192
TEST_TIME 58 9141
TEST_TIME 59 9173
TEST_TIME 60 9232
TEST_TIME 61 9193
TEST_TIME 62 9262
TEST_TIME 63 9132
TEST_TIME 64 9072
TEST_TIME 65 8974
TEST_TIME 66 9012
TEST_TIME 67 8956
TEST_TIME 68 8940
TEST_TIME 69 8900
TEST_TIME 70 8870
TEST_TIME 71 8805
TEST_TIME 72 8734
TEST_TIME 73 8743
TEST_TIME 74 8701
TEST_TIME 75 8723
TEST_TIME 76 8764
TEST_TIME 77 8711
TEST_TIME 78 8770
TEST_TIME 79 8809
TEST_TIME 80 8782
TEST_TIME 81 8841
TEST_TIME 82 8888
TEST_TIME 83 8911
TEST_TIME 84 8943
TEST_TIME 85 8924
TEST_TIME 86 8974
TEST_TIME 87 8908
TEST_TIME 88 8821
TEST_TIME 89 8804
TEST_TIME 90 8739
TEST_TIME 91 8711
TEST_TIME 92 8720
TEST_TIME 93 8709
TEST_TIME 94 8684
TEST_TIME 95 8682
TEST_TIME 96 8725
TEST_TIME 97 8731
TEST_TIME 98 8700
TEST_TIME 99 8764
TEST_TIME 100 8727
TEST_TIME 101 8784
TEST_TIME 102 8821
TEST_TIME 103 8848
TEST_TIME 104 8890
TEST_TIME 105 8858
TEST_TIME 106 8782
TEST_TIME 107 8772
TEST_TIME 108 8728
TEST_TIME 109 8711
TEST_TIME 110 8664
TEST_TIME 111 8638
TEST_TIME 112 8610
TEST_TIME 113 8588
TEST_TIME 114 8659
TEST_TIME 115 8688
TEST_TIME 116 8706
TEST_TIME 117 8707
TEST_TIME 118 8688
TEST_TIME 119 8755
TEST_TIME 120 8816

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-09-2021 07:47:56 to 16-09-2021 07:47:59.

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 1465428 1465428 0 0.00 0 18165 7 0 0
doc_list_biud 1465426 1465426 0 0.00 0 45264 35 0 0
sp_add_invoice_to_stock 4816 4731 85 1.76 40 212095 19530 0 85
sp_cancel_adding_invoice 5446 5446 0 0.00 6 71013 1009 0 0
sp_cancel_client_order 1650 1650 0 0.00 5 21202 384 0 0
sp_cancel_customer_reserve 533941 533941 0 0.00 1 30256 75 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 4414 102 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 13519 126 0 0
sp_cancel_supplier_invoice 6080 6072 8 0.13 2 45349 1140 0 8
sp_cancel_supplier_order 424 424 0 0.00 11 1190806 122906 0 0
sp_cancel_write_off 9189 9189 0 0.00 1 6933 143 0 0
sp_client_order 19158 19158 0 0.00 4 29120 459 0 0
sp_customer_reserve 441820 441820 0 0.00 0 53980 189 0 0
sp_fill_shopping_cart 29632 29632 0 0.00 1 44810 54 0 0
sp_fill_shopping_cart_clo_res 441811 441811 0 0.00 0 18833 17 0 0
sp_get_clo_for_invoice 4720 4720 0 0.00 0 30203 424 0 0
sp_kill_cost_storno 15730 15730 0 0.00 0 6071 73 0 0
sp_kill_qstorno_ret_qs2qd 1083567 1083567 0 0.00 0 45262 41 0 0
sp_kill_qty_storno 570946 570946 0 0.00 0 45264 88 0 0
sp_lock_selected_doc 19828 19828 0 0.00 0 6440 4 0 0
sp_make_cost_storno 26510 26510 0 0.00 0 18151 59 0 0
sp_multiply_rows_for_pdistr 26509 26509 0 0.00 0 7265 36 0 0
sp_multiply_rows_for_qdistr 19158 19158 0 0.00 0 19190 221 0 0
sp_pay_from_customer 6736 6736 0 0.00 4 11403 128 0 0
sp_pay_to_supplier 5550 5550 0 0.00 5 21210 144 0 0
sp_payment_common 12285 12285 0 0.00 1 8944 23 0 0
sp_qd_handle_on_cancel_clo 1650 1650 0 0.00 0 5789 343 0 0
sp_qd_handle_on_invoice_upd_sts 10166 10166 0 0.00 0 30119 424 0 0
sp_qd_handle_on_reserve_upd_sts 9504 9504 0 0.00 0 13519 53 0 0
sp_reserve_write_off 9504 9504 0 0.00 7 20192 188 0 0
sp_supplier_invoice 5249 5249 0 0.00 6 135192 4406 0 0
sp_supplier_order 5240 5240 0 0.00 5 29119 1536 0 0
srv_aggregate_perf_data 110 110 0 0.00 2480 228477 15370 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1414 158014 43252 0 0
srv_find_qd_qs_mism 1011505 1011505 0 0.00 0 14983 6 0 0
srv_make_invnt_saldo 418 418 0 0.00 49 110213 4069 0 0
srv_make_money_saldo 748 748 0 0.00 1 14018 477 0 0
srv_recalc_idx_stat 4 4 0 0.00 280793 632549 433128 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 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-norm:sp_add_invoice_to_stock 4731 4731 0 0.00 0 32823 195 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 21576 169 0 0
t$perf-norm:sp_cancel_client_order 1650 1650 0 0.00 0 7379 17 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 1969 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 1075 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 32283 86 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 1135 26 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 22335 1695 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 1151 5 0 0
t$perf-norm:sp_client_order 19158 19158 0 0.00 0 31486 22 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 936 7 0 0
t$perf-norm:sp_pay_from_customer 6736 6736 0 0.00 0 7193 9 0 0
t$perf-norm:sp_pay_to_supplier 5550 5550 0 0.00 0 8261 12 0 0
t$perf-norm:sp_reserve_write_off 9504 9504 0 0.00 0 27780 15 0 0
t$perf-norm:sp_supplier_invoice 5249 5249 0 0.00 0 11564 24 0 0
t$perf-norm:sp_supplier_order 5240 5240 0 0.00 0 2790 19 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 419 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 97 4 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 29653 85 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 1068 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9606 9436 170 1.76 0 17002 19 0 170
v_all_customers 35412 35412 0 0.00 0 27 1 0 0
v_all_suppliers 29188 29188 0 0.00 0 30 1 0 0
v_all_wares 38316 38316 0 0.00 0 78 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 4103 15 0 0
v_cancel_client_order 3300 3300 0 0.00 0 20102 12 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 2718 7 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 3035 7 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 13485 24 0 0
v_cancel_supplier_invoice 790 774 16 2.02 0 3676 17 0 16
v_cancel_supplier_order 848 848 0 0.00 0 591 5 0 0
v_cancel_write_off 1956 1956 0 0.00 0 643 13 0 0
v_max_id_clo_ord 5231 5231 0 0.00 0 588 1 0 0
v_max_id_clo_res 982 982 0 0.00 0 3871 17 0 0
v_max_id_ord_sup 5242 5242 0 0.00 0 6648 7 0 0
v_max_non_paid_invoice 1428 1428 0 0.00 0 413 2 0 0
v_max_non_paid_realizn 3493 3493 0 0.00 0 3848 5 0 0
v_min_id_clo_ord 5231 5231 0 0.00 0 6487 8 0 0
v_min_id_clo_res 982 982 0 0.00 0 9183 26 0 0
v_min_id_ord_sup 5242 5242 0 0.00 0 14758 13 0 0
v_min_non_paid_invoice 5549 5549 0 0.00 0 3882 14 0 0
v_min_non_paid_realizn 6736 6736 0 0.00 0 6909 17 0 0
v_our_firm 9390 9390 0 0.00 0 25 1 0 0
v_reserve_write_off 19008 19008 0 0.00 0 20097 10 0 0
x_lock_depdocs_on_canc_invoice 11131 11131 0 0.00 0 11416 127 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 3104 390 0 0
x_make_qstorno_1000_1200 5240 5240 0 0.00 2 29109 1440 0 0
x_make_qstorno_1200_2000 5249 5249 0 0.00 3 135128 4195 0 0
x_make_qstorno_2100_3300 441820 441820 0 0.00 0 49956 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-09-2021 07:48:00 to 16-09-2021 07:48:04.

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:16:51 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:52 20567 8192000000 9718361936 10013900800 798545808 8.882 100 89 178 0 827856864 7482000 21829056
05:19:17 22118 8192000000 9756694800 10060443648 828928016 9.188 100 87 170 1 859749744 10484016 20337712
05:20:44 18850 8192000000 9790815312 10106298368 860312928 9.503 100 79 158 0 895401520 16669856 18418736
05:22:08 22238 8192000000 9845315504 10173763584 898675632 9.885 100 78 155 3 956322848 40854288 16792928
05:23:43 8529 8192000000 9897148496 10239369216 943969600 10.332 100 81 162 0 1023434752 62159312 17305840
05:24:54 6768 8192000000 9954056096 10308136960 993884848 10.819 100 77 154 0 1067935520 58411920 15638752
05:26:03 3081 8192000000 10007590768 10366070784 1015881792 11.032 100 89 176 2 1120151136 86789952 17479392
05:27:08 2659 8192000000 9988993728 10351824896 1036362368 11.230 100 82 164 1 1101231456 48581440 16287648
05:28:13 1235 8192000000 10033419024 10404552704 1067301104 11.526 100 97 195 3 1155273728 67733520 20239104
05:29:15 17127 8192000000 9984830176 10378801152 1070355760 11.555 100 60 122 1 1110037104 26915232 12766112
05:30:36 3822 8192000000 9999182096 10395971584 1074400544 11.594 100 41 83 0 1120037680 37055664 8581472
05:31:42 6378 8192000000 9997805216 10400972800 1086110432 11.706 100 36 72 0 1122254416 27587984 8556000
05:32:50 6833 8192000000 9995267632 10402910208 1092613312 11.768 100 30 61 0 1114664016 15755072 6295632
05:33:59 24048 8192000000 10016415792 10408738816 1094358240 11.784 100 65 130 1 1120301904 11239392 14704272
05:35:24 7399 8192000000 10003200128 10411954176 1098418720 11.823 100 32 64 2 1126368448 21119168 6830560
05:36:34 5776 8192000000 10002131952 10408103936 1076525600 11.614 98 22 45 1 1097437648 16412608 4499440
05:37:41 7461 8192000000 9995494336 10389336064 1079075136 11.639 99 19 38 0 1099237840 15397424 4765280
05:38:52 10106 8192000000 9976681376 10356699136 1058866576 11.446 99 58 116 1 1082461472 9704976 13889920
05:40:03 3198 8192000000 9951167088 10332057600 1026351344 11.133 99 48 94 2 1057156032 19709040 11095648
05:41:08 6728 8192000000 9945763008 10332676096 1022363024 11.095 100 39 78 0 1055483504 24824112 8296368
05:42:17 9215 8192000000 9964137152 10356170752 1038876992 11.254 100 38 76 1 1063959056 16098944 8983120
05:43:30 23842 8192000000 9943513072 10345263104 1027058608 11.140 99 21 42 2 1052420576 21324496 4037472
05:44:56 4446 8192000000 9991131984 10370162688 1053161504 11.391 100 48 94 4 1086606688 23457760 9987424
05:46:05 5850 8192000000 9964746736 10362359808 1061311632 11.469 100 32 64 1 1088235632 19422032 7501968
05:47:20 11861 8192000000 10013586048 10409586688 1077632240 11.625 100 50 99 2 1120323088 32253248 10437600
05:48:34 118198 8192000000 10059302544 10479554560 1096070544 11.800 100 42 84 0 1184311232 78527728 9712960
05:51:36 14110 8192000000 10122966064 10528989184 1115148432 11.981 100 56 112 0 1223434976 96122112 12164432
05:52:53 4745 8192000000 10152858432 10556424192 1123315328 12.058 100 60 120 2 1259903408 123068672 13519408
05:54:04 32442 8192000000 10153807360 10588479488 1136249424 12.180 100 50 98 2 1267372352 120152176 10970752
05:55:50 6763 8192000000 10163374640 10571440128 1149827840 12.308 100 53 106 1 1261079632 99150224 12101568
05:57:14 4669 8192000000 10176089520 10585092096 1151726112 12.326 99 59 117 0 1273873376 108753872 13393392
05:58:25 1474 8192000000 10244132416 10654310400 1181529040 12.604 100 76 152 0 1347385136 148888384 16967712
05:59:30 4428 8192000000 10265392720 10681352192 1189030832 12.674 100 76 151 2 1365775312 160151072 16593408
06:00:43 2498 8192000000 10293386672 10705076224 1196575872 12.745 100 75 145 2 1401176112 187553232 17047008
06:01:49 3161 8192000000 10339590880 10753781760 1174219280 12.536 98 87 171 1 1432231200 239224192 18787728
06:02:57 7235 8192000000 10373472512 10788536320 1191214944 12.695 100 79 156 0 1474757680 266106832 17435904
06:04:13 4115 8192000000 10409452128 10820755456 1191246944 12.695 100 88 177 0 1515743664 304074992 20421728
06:05:23 2952 8192000000 10437745920 10840354816 1175239552 12.546 100 93 186 0 1534636784 338311520 21085712
06:06:30 1409 8192000000 10465245600 10875023360 1175585648 12.549 99 92 184 1 1561259088 364233888 21439552
06:07:36 3497 8192000000 10521769392 10926612480 1184646320 12.634 100 86 172 0 1616491584 410529232 21316032
06:08:45 4507 8192000000 10530062432 10929238016 1155847872 12.364 98 88 176 1 1617955680 441206784 20901024
06:09:53 5817 8192000000 10524175744 10958110720 1146117488 12.273 99 60 120 1 1618727088 456907968 15701632
06:11:09 11681 8192000000 10481488384 10948567040 1160958784 12.412 100 39 78 0 1618487936 446849696 10679456
06:12:28 23628 8192000000 10392127072 10902618112 1128566736 12.108 99 31 62 0 1557398576 420562784 8269056
06:13:56 28364 8192000000 10346294368 10862043136 1110196928 11.934 99 20 40 1 1484707136 368780880 5729328
06:15:25 29824 8192000000 10145866368 10669867008 1131101232 12.132 100 20 40 1 1308686720 172775040 4810448
06:16:56 61732 8192000000 10065863072 10514026496 1117370064 12.002 99 21 42 0 1162590000 40862528 4357408
06:19:00 29827 8192000000 10083790496 10546872320 1122118976 12.047 99 36 71 2 1181352688 52249888 6983824
06:20:38 53356 8192000000 10081371856 10525298688 1099180624 11.830 97 10 20 1 1144143392 42669008 2293760
06:22:42 67719 8192000000 10100858560 10586226688 1131786656 12.138 100 10 19 0 1210382624 76808064 1787904
06:24:50 53893 8192000000 10103513104 10570309632 1103306832 11.869 98 25 50 0 1188878208 79981984 5589392
06:26:50 158014 8192000000 10036441200 10481156096 1073093248 11.582 95 28 55 1 1130023136 50972016 5957872
06:30:30 7391 8192000000 10068354592 10513649664 1132147888 12.142 100 17 34 1 1169306992 33645488 3513616
06:31:38 44790 8192000000 10126844736 10578165760 1130149584 12.123 99 32 64 0 1197443728 59975248 7318896
06:33:59 91569 8192000000 10111475584 10565218304 1118164928 12.010 98 30 58 2 1173464496 48271552 7028016
06:36:45 45787 8192000000 10126968720 10579460096 1133985648 12.159 100 13 25 0 1205405040 68650368 2769024
06:38:42 36662 8192000000 10084309376 10514939904 1116781024 11.997 98 39 78 0 1160140096 33840976 9518096
06:40:23 30586 8192000000 10105160848 10538901504 1134157072 12.161 99 30 60 1 1192035600 52025856 5852672
06:41:54 89536 8192000000 10099762432 10603114496 1100043808 11.838 96 15 26 0 1148545568 45468816 3032944
06:45:17 68502 8192000000 10132309792 10601697280 1123018160 12.055 98 23 45 3 1196032896 68159936 4854800
06:47:26 80887 8192000000 10082677872 10560733184 1106711232 11.901 97 9 18 1 1178650032 69859088 2079712
06:49:49 82930 8192000000 10073240512 10518110208 1122317792 12.049 98 32 64 1 1154778992 25859392 6601808
06:52:28 41569 8192000000 10041626064 10512560128 1117319392 12.002 99 37 74 1 1176748352 51022304 8406656
06:54:21 43532 8192000000 10030960000 10484572160 1112266608 11.954 99 10 20 0 1131473056 17200368 2006080
06:56:05 38377 8192000000 10034197728 10477256704 1078582064 11.634 96 24 48 0 1106447280 23174240 4690976
06:58:17 95716 8192000000 10068805360 10579714048 1122693008 12.052 99 15 30 0 1184455408 58413712 3348688
07:00:53 34427 8192000000 10044764832 10512179200 1110455344 11.937 99 10 20 0 1149288784 36942912 1890528
07:02:33 47915 8192000000 10056885392 10508021760 1095583712 11.796 97 15 30 1 1132958240 33985040 3389488
07:04:21 44273 8192000000 10086249328 10525474816 1101946656 11.856 97 16 32 0 1153790768 48165696 3678416
07:06:08 51032 8192000000 10108083296 10542534656 1106134176 11.896 98 22 44 0 1178106096 67638400 4333520
07:07:59 43581 8192000000 10093937216 10546597888 1094609392 11.786 97 31 62 1 1174445440 73028176 6807872
07:09:51 37646 8192000000 10073117520 10508042240 1094008896 11.781 98 42 83 1 1158965056 56473648 8482512
07:11:53 13126 8192000000 10076363616 10492096512 1105779456 11.892 99 59 117 0 1149413872 32005328 11629088
07:13:08 81966 8192000000 10038080896 10504884224 1115983808 11.989 100 20 40 0 1168800192 48826752 3989632
07:15:50 57083 8192000000 10034794688 10497507328 1109467040 11.927 99 6 12 0 1142928736 32111824 1349872
07:17:56 56417 8192000000 10068363792 10542866432 1131234848 12.133 100 16 32 0 1193994752 59348432 3411472
07:20:01 68763 8192000000 10111437408 10570838016 1120745264 12.034 98 27 54 1 1177122544 50806832 5570448
07:22:14 69570 8192000000 10076433008 10565201920 1113869664 11.969 98 10 20 0 1159082496 43352352 1860480
07:24:28 61639 8192000000 10123487376 10581344256 1104266272 11.878 97 20 40 1 1173552176 65169024 4116880
07:26:41 28934 8192000000 10125601552 10557046784 1126088016 12.084 99 44 88 1 1207564240 72560720 8915504
07:28:10 45641 8192000000 10090107328 10571378688 1114611248 11.976 98 26 52 0 1178727728 58952064 5164416
07:29:56 103509 8192000000 10075630384 10549075968 1112889568 11.960 97 14 28 0 1141228848 25793808 2545472
07:33:20 68796 8192000000 10048705952 10520367104 1103065952 11.867 97 14 28 0 1141363328 35961056 2336320
07:35:34 53199 8192000000 10057009136 10529247232 1104431552 11.880 97 3 6 0 1128851056 23916048 503456
07:37:28 43073 8192000000 10116222144 10550046720 1131195232 12.133 98 29 58 0 1168089664 29826864 7067568
07:39:14 80147 8192000000 10189483648 10702336000 1144602496 12.259 99 20 40 2 1293805216 144461376 4741344
07:42:11 87874 8192000000 10106130128 10596876288 1107830768 11.912 96 18 36 1 1195975104 84584048 3560288
07:44:46 45950 8192000000 10160341872 10653466624 1142026640 12.235 100 13 26 0 1248641648 103589712 3025296
07:46:36 36939 8192000000 8992338752 9212018688 141891648 1.702 13 1 2 0 153859392 11932336 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-09-2021 07:48:06 to 16-09-2021 07:48:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 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 16-09-2021 07:48:22 to 16-09-2021 07:48:24.

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          229941
MON$OLDEST_ACTIVE               229942
MON$OLDEST_SNAPSHOT             229942
MON$NEXT_TRANSACTION            229942
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                       2048288
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.2602 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2602 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2602 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2602 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Sep 16 07:47:17 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Sep 16 07:47:17 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Sep 16 07:47:17 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

16.09.21 07:48:25. 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-16 07:48:28 No severe PSQL-related problems occured
16.09.21 07:48:29. End of report.