Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.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 15877220 kB
MemAvailable 30759436 kB
Buffers 0 kB
Cached 15808772 kB
SwapCached 3072 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-27 13:37:03
DTS_END                         2021-09-27 13:37:03.6290
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33032
TRN_ID                          229790
ATT_ID                          619
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: 8909.48 29059 1069137
customer order: creation 159.30 414 19116
customer order: refuse 13.82 376 1658
order to supplier: creation 43.57 1506 5228
order to supplier: removal 3.23 124815 387
invoice (draft): creation 43.58 4161 5229
invoice (draft): removal 52.70 965 6324
invoice accept: apply 38.36 18534 4603
invoice accept: cancel 47.37 843 5684
customer reserve: creation 3603.16 178 432379
customer reserve: removal 4625.25 67 555030
realization accept: apply 78.17 245 9380
realization accept: cancel 81.19 124 9743
payment from customer: creation 54.86 138 6583
payment from customer: removal 5.13 132 616
payment to supplier: creation 45.83 178 5499
payment to supplier: removal 3.29 86 395
service: total inventory turnovers 3.85 3465 462
service: total monetary turnovers 6.04 484 725
service: aggregate perf. data 0.77 17307 92
service: refresh index statistics 0.03 407168 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-09-2021 13:38:00 to 27-09-2021 13:38:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4823
TEST_TIME 2 4254
TEST_TIME 3 4162
TEST_TIME 4 4352
TEST_TIME 5 5227
TEST_TIME 6 5254
TEST_TIME 7 6166
TEST_TIME 8 6088
TEST_TIME 9 6166
TEST_TIME 10 5999
TEST_TIME 11 5949
TEST_TIME 12 6037
TEST_TIME 13 6354
TEST_TIME 14 6640
TEST_TIME 15 7061
TEST_TIME 16 7293
TEST_TIME 17 7320
TEST_TIME 18 7445
TEST_TIME 19 7805
TEST_TIME 20 7920
TEST_TIME 21 8869
TEST_TIME 22 9124
TEST_TIME 23 9498
TEST_TIME 24 9808
TEST_TIME 25 9736
TEST_TIME 26 10990
TEST_TIME 27 11400
TEST_TIME 28 11079
TEST_TIME 29 10822
TEST_TIME 30 10565
TEST_TIME 31 10625
TEST_TIME 32 10354
TEST_TIME 33 10299
TEST_TIME 34 10146
TEST_TIME 35 10183
TEST_TIME 36 10180
TEST_TIME 37 9978
TEST_TIME 38 9890
TEST_TIME 39 10031
TEST_TIME 40 9892
TEST_TIME 41 9780
TEST_TIME 42 9709
TEST_TIME 43 9646
TEST_TIME 44 9649
TEST_TIME 45 9562
TEST_TIME 46 9446
TEST_TIME 47 9497
TEST_TIME 48 9456
TEST_TIME 49 9339
TEST_TIME 50 9574
TEST_TIME 51 9506
TEST_TIME 52 9523
TEST_TIME 53 9638
TEST_TIME 54 9552
TEST_TIME 55 9464
TEST_TIME 56 9353
TEST_TIME 57 9303
TEST_TIME 58 9204
TEST_TIME 59 9164
TEST_TIME 60 9136
TEST_TIME 61 9103
TEST_TIME 62 9016
TEST_TIME 63 9070
TEST_TIME 64 9080
TEST_TIME 65 9052
TEST_TIME 66 9016
TEST_TIME 67 8978
TEST_TIME 68 9154
TEST_TIME 69 9164
TEST_TIME 70 9119
TEST_TIME 71 9168
TEST_TIME 72 9244
TEST_TIME 73 9221
TEST_TIME 74 9182
TEST_TIME 75 9220
TEST_TIME 76 9235
TEST_TIME 77 9204
TEST_TIME 78 9149
TEST_TIME 79 9133
TEST_TIME 80 9102
TEST_TIME 81 9167
TEST_TIME 82 9122
TEST_TIME 83 9103
TEST_TIME 84 9079
TEST_TIME 85 9018
TEST_TIME 86 8987
TEST_TIME 87 8912
TEST_TIME 88 8977
TEST_TIME 89 9014
TEST_TIME 90 8975
TEST_TIME 91 8965
TEST_TIME 92 8977
TEST_TIME 93 9025
TEST_TIME 94 9046
TEST_TIME 95 9057
TEST_TIME 96 9198
TEST_TIME 97 9155
TEST_TIME 98 9145
TEST_TIME 99 9171
TEST_TIME 100 9161
TEST_TIME 101 9182
TEST_TIME 102 9233
TEST_TIME 103 9150
TEST_TIME 104 9096
TEST_TIME 105 9165
TEST_TIME 106 9152
TEST_TIME 107 9102
TEST_TIME 108 9062
TEST_TIME 109 9043
TEST_TIME 110 9035
TEST_TIME 111 9031
TEST_TIME 112 8965
TEST_TIME 113 8961
TEST_TIME 114 8941
TEST_TIME 115 8931
TEST_TIME 116 8966
TEST_TIME 117 8955
TEST_TIME 118 8972
TEST_TIME 119 8986
TEST_TIME 120 8915

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-09-2021 13:38:03 to 27-09-2021 13:38:07.

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 1468165 1468165 0 0.00 0 31838 7 0 0
doc_list_biud 1468165 1468165 0 0.00 0 75925 33 0 0
sp_add_invoice_to_stock 4697 4603 94 2.00 46 205975 18535 0 94
sp_cancel_adding_invoice 5684 5684 0 0.00 6 113210 843 0 0
sp_cancel_client_order 1658 1658 0 0.00 5 18550 376 0 0
sp_cancel_customer_reserve 555030 555030 0 0.00 1 75927 68 0 0
sp_cancel_pay_from_customer 616 616 0 0.00 3 12870 132 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 3255 86 0 0
sp_cancel_supplier_invoice 6331 6324 7 0.11 2 42366 965 0 7
sp_cancel_supplier_order 387 387 0 0.00 10 1206297 124815 0 0
sp_cancel_write_off 9743 9743 0 0.00 1 22944 125 0 0
sp_client_order 19116 19116 0 0.00 4 30042 414 0 0
sp_customer_reserve 432379 432379 0 0.00 0 85631 179 0 0
sp_fill_shopping_cart 29571 29571 0 0.00 1 27616 57 0 0
sp_fill_shopping_cart_clo_res 432371 432371 0 0.00 0 19830 17 0 0
sp_get_clo_for_invoice 4595 4595 0 0.00 0 27672 418 0 0
sp_kill_cost_storno 16438 16438 0 0.00 0 22885 61 0 0
sp_kill_qstorno_ret_qs2qd 1126514 1126514 0 0.00 0 75915 37 0 0
sp_kill_qty_storno 592801 592801 0 0.00 0 75925 80 0 0
sp_lock_selected_doc 19340 19340 0 0.00 0 6882 6 0 0
sp_make_cost_storno 26057 26057 0 0.00 0 31140 77 0 0
sp_multiply_rows_for_pdistr 26057 26057 0 0.00 0 23830 40 0 0
sp_multiply_rows_for_qdistr 19116 19116 0 0.00 0 27052 183 0 0
sp_pay_from_customer 6583 6583 0 0.00 4 24404 138 0 0
sp_pay_to_supplier 5499 5499 0 0.00 5 31151 179 0 0
sp_payment_common 12082 12082 0 0.00 1 23118 33 0 0
sp_qd_handle_on_cancel_clo 1658 1658 0 0.00 0 18545 325 0 0
sp_qd_handle_on_invoice_upd_sts 10279 10279 0 0.00 0 38136 404 0 0
sp_qd_handle_on_reserve_upd_sts 9380 9380 0 0.00 0 26575 69 0 0
sp_reserve_write_off 9380 9380 0 0.00 7 31132 246 0 0
sp_supplier_invoice 5229 5229 0 0.00 8 93113 4161 0 0
sp_supplier_order 5228 5228 0 0.00 7 98624 1506 0 0
srv_aggregate_perf_data 92 92 0 0.00 3078 175856 17308 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1480 140355 51329 0 0
srv_find_qd_qs_mism 1023941 1023941 0 0.00 0 28849 5 0 0
srv_make_invnt_saldo 462 462 0 0.00 34 103219 3465 0 0
srv_make_money_saldo 725 725 0 0.00 1 12271 484 0 0
srv_recalc_idx_stat 4 4 0 0.00 274188 706874 407168 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4605 4605 0 0.00 0 16681 207 0 0
t$perf-norm:sp_cancel_adding_invoice 362 362 0 0.00 0 4854 123 0 0
t$perf-norm:sp_cancel_client_order 1658 1658 0 0.00 0 5900 17 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 483 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 616 616 0 0.00 0 5364 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 12258 46 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 6105 48 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 34820 1596 0 0
t$perf-norm:sp_cancel_write_off 926 926 0 0.00 0 22426 42 0 0
t$perf-norm:sp_client_order 19116 19116 0 0.00 0 13248 23 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 8636 17 0 0
t$perf-norm:sp_pay_from_customer 6583 6583 0 0.00 0 15482 17 0 0
t$perf-norm:sp_pay_to_supplier 5499 5499 0 0.00 0 5752 13 0 0
t$perf-norm:sp_reserve_write_off 9380 9380 0 0.00 0 6993 14 0 0
t$perf-norm:sp_supplier_invoice 5230 5230 0 0.00 0 12485 24 0 0
t$perf-norm:sp_supplier_order 5228 5228 0 0.00 0 11469 19 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 585 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 44 2 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 2255 18 0 0
t$perf-norm:srv_make_money_saldo 725 725 0 0.00 0 1297 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 26 10 0 0
v_add_invoice_to_stock 9378 9190 188 2.00 0 13738 18 0 188
v_all_customers 34540 34540 0 0.00 0 50 1 0 0
v_all_suppliers 29046 29046 0 0.00 0 94 1 0 0
v_all_wares 38232 38232 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 724 724 0 0.00 0 13768 28 0 0
v_cancel_client_order 3316 3316 0 0.00 0 13763 15 0 0
v_cancel_customer_prepayment 1232 1232 0 0.00 0 4350 13 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 0 1542 6 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 3222 16 0 0
v_cancel_supplier_invoice 754 740 14 1.85 0 13733 35 0 14
v_cancel_supplier_order 774 774 0 0.00 0 13759 35 0 0
v_cancel_write_off 1852 1852 0 0.00 0 18692 25 0 0
v_max_id_clo_ord 5228 5228 0 0.00 0 729 1 0 0
v_max_id_clo_res 958 958 0 0.00 0 3235 30 0 0
v_max_id_ord_sup 5227 5227 0 0.00 0 6012 7 0 0
v_max_non_paid_invoice 1431 1431 0 0.00 0 117 2 0 0
v_max_non_paid_realizn 3508 3508 0 0.00 0 4708 5 0 0
v_min_id_clo_ord 5228 5228 0 0.00 0 27049 17 0 0
v_min_id_clo_res 958 958 0 0.00 0 4746 20 0 0
v_min_id_ord_sup 5227 5227 0 0.00 0 6466 10 0 0
v_min_non_paid_invoice 5499 5499 0 0.00 0 23107 30 0 0
v_min_non_paid_realizn 6583 6583 0 0.00 0 23110 21 0 0
v_our_firm 9842 9842 0 0.00 0 16 1 0 0
v_reserve_write_off 18760 18760 0 0.00 0 19588 10 0 0
x_lock_depdocs_on_canc_invoice 11638 11638 0 0.00 0 21336 135 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 5710 356 0 0
x_make_qstorno_1000_1200 5228 5228 0 0.00 2 98555 1397 0 0
x_make_qstorno_1200_2000 5229 5229 0 0.00 5 89681 3956 0 0
x_make_qstorno_2100_3300 432379 432379 0 0.00 0 85630 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-09-2021 13:38:08 to 27-09-2021 13:38:12.

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:07 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:08 29172 8192000000 9715711376 10014867456 797871376 8.875 100 84 168 0 826196784 7864000 20461408
11:09:46 14175 8192000000 9751119424 10054160384 824235472 9.141 100 85 170 0 856667152 11626192 20805488
11:11:07 18936 8192000000 9792932176 10107191296 856565504 9.466 100 89 178 0 900548384 22446992 21535888
11:12:30 13449 8192000000 9852474224 10187276288 913388896 10.031 100 76 152 0 969846432 39961280 16496256
11:13:46 13057 8192000000 9913354000 10256904192 959222816 10.481 100 73 146 1 1025767248 51017440 15526992
11:15:02 6072 8192000000 9968056096 10319523840 992069664 10.802 100 85 171 0 1080513376 71129248 17314464
11:16:10 2311 8192000000 10005152928 10362298368 1014391392 11.018 100 89 178 0 1118287504 85826992 18069120
11:17:13 1211 8192000000 10002436992 10367524864 1036396416 11.230 100 87 172 0 1116035760 62547808 17091536
11:18:18 1657 8192000000 10043841440 10415083520 1060406784 11.460 100 95 190 1 1152570352 73145632 19017936
11:19:20 17819 8192000000 9981956912 10372853760 1064991312 11.504 100 50 101 0 1116706992 40439632 11276048
11:20:44 7744 8192000000 9997341440 10395553792 1074071024 11.591 100 48 96 0 1108186144 24315328 9799792
11:21:54 7718 8192000000 9990687392 10392027136 1079470768 11.642 100 34 68 1 1106833040 19563328 7798944
11:23:05 14169 8192000000 9995928240 10409226240 1086904384 11.713 100 24 48 0 1115700896 23680016 5116496
11:24:20 1308 8192000000 10014878240 10407895040 1088963728 11.733 100 70 140 0 1121626960 16928272 15734960
11:25:22 5161 8192000000 10037576336 10428981248 1079576144 11.643 99 56 112 0 1131719376 40341072 11802160
11:26:28 6394 8192000000 9997543856 10387755008 1067396880 11.527 99 25 50 0 1098849840 25892672 5560288
11:27:36 56009 8192000000 9951111328 10336595968 996787840 10.847 94 31 62 2 1016444480 12390544 7266096
11:29:41 3744 8192000000 9978060784 10363457536 1042735264 11.291 99 48 96 0 1078870816 25850384 10285168
11:30:46 7187 8192000000 9940959712 10323881984 1006770000 10.944 98 25 50 0 1036559184 24586288 5202896
11:31:53 5881 8192000000 9944654512 10333782016 1027760144 11.147 100 54 109 2 1060514032 20086864 12667024
11:33:00 43474 8192000000 9930038192 10333769728 1010262784 10.978 97 26 52 0 1039844704 24090464 5491456
11:34:45 7039 8192000000 9994986016 10384826368 1041361296 11.278 99 54 109 0 1074047648 21064768 11621584
11:35:56 4195 8192000000 9982979696 10370969600 1061290080 11.469 100 63 129 4 1090280096 14499184 14490832
11:37:02 16223 8192000000 9989832336 10381799424 1074310448 11.593 100 27 54 1 1104667024 24417392 5939184
11:38:22 94085 8192000000 10044621312 10453929984 1100697184 11.844 100 40 79 0 1154800688 44398144 9705360
11:41:10 5542 8192000000 10075982720 10484342784 1114190896 11.972 100 55 109 1 1186798112 61613728 10993488
11:42:20 2105 8192000000 10144475776 10543697920 1130044480 12.122 100 72 141 1 1247527232 102274848 15207904
11:43:25 3747 8192000000 10159346656 10561871872 1141828288 12.233 100 67 134 0 1265169040 107678720 15662032
11:44:32 14799 8192000000 10160918880 10584670208 1130645968 12.127 99 54 105 2 1257533376 114890928 11996480
11:45:52 11235 8192000000 10209984608 10621612032 1153302320 12.340 100 57 113 0 1304600352 139321424 11976608
11:47:07 15976 8192000000 10223288272 10643693568 1161788224 12.420 100 50 100 1 1331082480 157510976 11783280
11:48:39 9522 8192000000 10273593760 10696196096 1174217504 12.536 100 62 120 2 1389743984 201270368 14256112
11:49:53 1721 8192000000 10348503904 10753609728 1179553280 12.586 100 87 174 1 1451314112 251466416 20294416
11:51:02 1875 8192000000 10378850416 10782748672 1172803040 12.523 100 88 175 1 1483854800 290515536 20536224
11:52:07 6562 8192000000 10375607712 10804826112 1150069680 12.310 99 52 103 0 1491806352 328981456 12755216
11:53:23 1915 8192000000 10424556000 10842255360 1167136672 12.470 100 74 148 1 1526006560 341993168 16876720
11:54:29 4059 8192000000 10470461120 10872352768 1164431152 12.445 100 85 168 1 1567591680 383489120 19671408
11:55:38 1479 8192000000 10486528576 10891816960 1169083472 12.488 100 85 164 1 1586709120 398465968 19159680
11:56:43 9596 8192000000 10516196912 10955055104 1159587664 12.399 100 50 99 0 1620512448 448259872 12664912
11:57:55 11236 8192000000 10487535328 10958352384 1144152016 12.255 100 41 82 0 1587673264 432046704 11474544
11:59:15 28335 8192000000 10358756240 10799824896 1117042960 11.999 99 27 54 1 1464285440 339764480 7478000
12:00:55 44457 8192000000 10307022016 10784321536 1115607888 11.985 100 18 36 0 1444828528 324433248 4787392
12:02:44 33998 8192000000 10290821680 10783772672 1101039584 11.848 99 23 46 0 1431781152 325038848 5702720
12:04:24 69178 8192000000 10063343856 10520481792 1061110096 11.467 95 17 32 3 1130008192 65280176 3617920
12:06:57 55549 8192000000 10105272848 10544627712 1104436816 11.880 100 26 52 0 1200164704 90413952 5313936
12:08:54 140355 8192000000 10109398976 10586390528 1115187872 11.982 99 17 34 1 1186333920 67667744 3478304
12:12:15 49049 8192000000 10135669584 10615296000 1105647312 11.891 98 18 36 1 1210726080 100926832 4151936
12:14:14 55550 8192000000 10138361808 10586570752 1133779728 12.157 100 22 44 0 1238976624 100481296 4715600
12:16:10 84405 8192000000 10027696656 10468012032 1088959968 11.733 97 18 36 0 1132725360 39536688 4228704
12:18:44 39008 8192000000 10093749664 10535985152 1133789504 12.157 100 31 62 0 1192711568 52242704 6679360
12:20:32 79730 8192000000 10049098080 10528886784 1080615936 11.653 95 7 14 0 1117064768 35125920 1322912
12:22:52 40938 8192000000 10102339520 10533691392 1127138000 12.094 99 33 64 1 1179271744 45142528 6991216
12:24:35 105019 8192000000 10152208544 10621947904 1133228512 12.152 99 16 32 0 1244132144 107518880 3384752
12:27:20 125266 8192000000 10077666896 10577969152 1121076720 12.037 98 8 16 0 1149655120 27024672 1553728
12:30:39 96201 8192000000 10054128336 10490351616 1067248272 11.526 94 17 34 0 1109448480 38716336 3483872
12:33:16 94816 8192000000 10058288960 10519158784 1121199232 12.038 99 36 72 0 1180147968 51283328 7665408
12:36:12 61393 8192000000 10090523872 10557566976 1126201200 12.086 99 35 68 2 1214019312 80516336 7301776
12:38:27 34361 8192000000 10090792384 10564063232 1127320288 12.096 99 15 28 2 1202416304 72557824 2538192
12:40:04 63366 8192000000 10091562656 10595823616 1112504128 11.956 98 10 20 0 1181942720 67334080 2104512
12:42:41 59506 8192000000 10104766944 10558627840 1095300000 11.793 97 22 44 0 1164484864 64379376 4805488
12:44:41 66416 8192000000 10151004096 10643234816 1105398224 11.889 97 27 54 0 1229298208 117617872 6282112
12:46:53 59530 8192000000 10113845232 10554507264 1121154160 12.038 98 33 66 0 1175646800 47066800 7425840
12:48:59 111301 8192000000 10071169472 10508853248 1081762976 11.664 96 31 59 2 1159647936 70984448 6900512
12:51:51 85889 8192000000 10074075552 10575499264 1047201248 11.334 93 10 20 1 1108157424 58923040 2033136
12:56:39 52684 8192000000 10127978032 10592362496 1124665376 12.071 100 28 56 1 1228877584 97935728 6276480
12:58:33 49296 8192000000 10048287312 10568241152 1098440912 11.823 98 9 18 0 1141359856 40976144 1942800
13:01:20 64771 8192000000 10052805136 10504417280 1091792336 11.760 98 18 36 1 1136116032 40960736 3362960
13:03:26 78256 8192000000 10134409328 10597453824 1125600400 12.080 100 15 30 0 1226673904 97590416 3483088
13:06:00 64123 8192000000 10075440160 10600718336 1123281744 12.058 100 12 24 0 1171777536 45738672 2757120
13:08:11 50488 8192000000 10120838288 10567704576 1121043536 12.037 98 26 50 0 1201232496 74784112 5404848
13:10:09 47514 8192000000 10097306592 10543423488 1120437296 12.031 99 22 44 0 1167315184 42388928 4488960
13:12:01 46473 8192000000 10148622944 10600177664 1140050448 12.216 100 25 49 0 1213777280 68527888 5198944
13:14:35 21376 8192000000 10066847840 10494816256 1116175712 11.991 98 38 72 0 1134527952 10673456 7678784
13:15:57 54951 8192000000 10075535296 10547347456 1116831472 11.997 98 24 48 0 1191758416 70384640 4542304
13:17:58 29776 8192000000 10132416144 10614415360 1129749488 12.119 99 40 80 0 1215319872 77904720 7665664
13:19:30 129849 8192000000 10145805808 10610593792 1148060768 12.291 100 15 30 1 1246254608 94738512 3455328
13:23:07 58222 8192000000 10065862176 10541203456 1122569920 12.051 98 12 24 0 1149230912 23920160 2740832
13:25:09 39889 8192000000 10074189776 10530476032 1134396352 12.163 99 11 22 0 1184288528 47632912 2259264
13:26:51 82043 8192000000 10055427232 10526715904 1121468000 12.041 98 18 36 2 1181658352 56443760 3746592
13:29:17 83938 8192000000 10084408176 10563780608 1118186736 12.010 99 25 50 0 1167322288 43942048 5193504
13:31:56 49968 8192000000 10090536048 10564296704 1129602720 12.118 100 6 12 0 1190432704 59473808 1356176
13:33:51 129361 8192000000 10088315296 10563379200 1088300544 11.726 95 16 32 0 1146488400 54915184 3272672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

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

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          228002
MON$OLDEST_ACTIVE               230373
MON$OLDEST_SNAPSHOT             230373
MON$NEXT_TRANSACTION            230373
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                       2052576
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 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 ---
:156: 
:157: mscs-test-161.budzdorov.ru    Mon Sep 27 13:37:08 2021
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:159: 
:160: 
:161: mscs-test-161.budzdorov.ru    Mon Sep 27 13:37:09 2021
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:163: 
--- end of diff output ---

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

27.09.21 13:38:32. 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-27 13:38:35 No severe PSQL-related problems occured
27.09.21 13:38:36. End of report.