Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.07.2022 13:38
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 15753532 kB
MemAvailable 31137076 kB
Buffers 0 kB
Cached 15978940 kB
SwapCached 2520 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.2
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 2022-07-13 13:37:16
DTS_END                         2022-07-13 13:37:16.4060
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/53604
TRN_ID                          226584
ATT_ID                          654
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: 9057.88 27320 1086946
customer order: creation 173.74 464 20849
customer order: refuse 15.14 403 1817
order to supplier: creation 47.19 1314 5663
order to supplier: removal 3.27 165044 392
invoice (draft): creation 47.02 2978 5642
invoice (draft): removal 48.80 934 5856
invoice accept: apply 42.87 26680 5144
invoice accept: cancel 45.48 1287 5458
customer reserve: creation 3950.55 256 474066
customer reserve: removal 4379.56 99 525547
realization accept: apply 85.56 271 10267
realization accept: cancel 83.21 163 9985
payment from customer: creation 61.95 190 7434
payment from customer: removal 5.83 143 700
payment to supplier: creation 51.29 186 6155
payment to supplier: removal 3.43 110 411
service: total inventory turnovers 4.38 3918 526
service: total monetary turnovers 7.66 354 919
service: aggregate perf. data 0.93 17438 111
service: refresh index statistics 0.03 324162 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-07-2022 13:38:51 to 13-07-2022 13:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8079
TEST_TIME 2 6247
TEST_TIME 3 6048
TEST_TIME 4 5592
TEST_TIME 5 6416
TEST_TIME 6 6485
TEST_TIME 7 6153
TEST_TIME 8 6557
TEST_TIME 9 6732
TEST_TIME 10 6793
TEST_TIME 11 6945
TEST_TIME 12 7176
TEST_TIME 13 7390
TEST_TIME 14 7379
TEST_TIME 15 7556
TEST_TIME 16 7658
TEST_TIME 17 7913
TEST_TIME 18 7885
TEST_TIME 19 7807
TEST_TIME 20 7769
TEST_TIME 21 8010
TEST_TIME 22 8118
TEST_TIME 23 8180
TEST_TIME 24 8702
TEST_TIME 25 8806
TEST_TIME 26 8734
TEST_TIME 27 8746
TEST_TIME 28 9114
TEST_TIME 29 9223
TEST_TIME 30 9456
TEST_TIME 31 10263
TEST_TIME 32 10471
TEST_TIME 33 11174
TEST_TIME 34 11147
TEST_TIME 35 11095
TEST_TIME 36 11237
TEST_TIME 37 11095
TEST_TIME 38 10821
TEST_TIME 39 10699
TEST_TIME 40 10537
TEST_TIME 41 10353
TEST_TIME 42 10308
TEST_TIME 43 10365
TEST_TIME 44 10224
TEST_TIME 45 10203
TEST_TIME 46 10061
TEST_TIME 47 9989
TEST_TIME 48 9989
TEST_TIME 49 9947
TEST_TIME 50 9932
TEST_TIME 51 9924
TEST_TIME 52 9925
TEST_TIME 53 9851
TEST_TIME 54 9825
TEST_TIME 55 9822
TEST_TIME 56 9831
TEST_TIME 57 9939
TEST_TIME 58 9984
TEST_TIME 59 10007
TEST_TIME 60 9923
TEST_TIME 61 9905
TEST_TIME 62 10061
TEST_TIME 63 10071
TEST_TIME 64 9976
TEST_TIME 65 9897
TEST_TIME 66 9837
TEST_TIME 67 9781
TEST_TIME 68 9692
TEST_TIME 69 9759
TEST_TIME 70 9706
TEST_TIME 71 9730
TEST_TIME 72 9637
TEST_TIME 73 9588
TEST_TIME 74 9529
TEST_TIME 75 9565
TEST_TIME 76 9531
TEST_TIME 77 9494
TEST_TIME 78 9512
TEST_TIME 79 9531
TEST_TIME 80 9529
TEST_TIME 81 9546
TEST_TIME 82 9521
TEST_TIME 83 9541
TEST_TIME 84 9585
TEST_TIME 85 9695
TEST_TIME 86 9694
TEST_TIME 87 9677
TEST_TIME 88 9626
TEST_TIME 89 9540
TEST_TIME 90 9488
TEST_TIME 91 9475
TEST_TIME 92 9464
TEST_TIME 93 9414
TEST_TIME 94 9385
TEST_TIME 95 9373
TEST_TIME 96 9359
TEST_TIME 97 9348
TEST_TIME 98 9338
TEST_TIME 99 9324
TEST_TIME 100 9318
TEST_TIME 101 9294
TEST_TIME 102 9312
TEST_TIME 103 9296
TEST_TIME 104 9315
TEST_TIME 105 9321
TEST_TIME 106 9404
TEST_TIME 107 9420
TEST_TIME 108 9387
TEST_TIME 109 9381
TEST_TIME 110 9340
TEST_TIME 111 9368
TEST_TIME 112 9320
TEST_TIME 113 9273
TEST_TIME 114 9236
TEST_TIME 115 9192
TEST_TIME 116 9203
TEST_TIME 117 9182
TEST_TIME 118 9171
TEST_TIME 119 9144
TEST_TIME 120 9086

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-07-2022 13:38:55 to 13-07-2022 13:38:58.

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 1521616 1521616 0 0.00 0 33113 14 0 0
doc_list_biud 1521615 1521615 0 0.00 0 30533 39 0 0
sp_add_invoice_to_stock 5283 5144 139 2.63 45 210361 26681 0 139
sp_cancel_adding_invoice 5458 5458 0 0.00 4 84215 1288 0 0
sp_cancel_client_order 1817 1817 0 0.00 5 14655 403 0 0
sp_cancel_customer_reserve 525547 525547 0 0.00 1 20328 99 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 9873 144 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 4 1416 110 0 0
sp_cancel_supplier_invoice 5864 5856 8 0.13 2 28892 934 0 8
sp_cancel_supplier_order 392 392 0 0.00 14 1445805 165045 0 0
sp_cancel_write_off 9985 9985 0 0.00 2 10134 164 0 0
sp_client_order 20849 20849 0 0.00 6 33133 464 0 0
sp_customer_reserve 474066 474066 0 0.00 1 139256 257 0 0
sp_fill_shopping_cart 32139 32139 0 0.00 1 20874 84 0 0
sp_fill_shopping_cart_clo_res 474024 474024 0 0.00 0 20354 20 0 0
sp_get_clo_for_invoice 5102 5102 0 0.00 0 52454 284 0 0
sp_kill_cost_storno 16554 16554 0 0.00 0 19286 68 0 0
sp_kill_qstorno_ret_qs2qd 1067327 1067327 0 0.00 0 30531 43 0 0
sp_kill_qty_storno 564422 564422 0 0.00 0 30532 98 0 0
sp_lock_selected_doc 21394 21394 0 0.00 0 9849 8 0 0
sp_make_cost_storno 28957 28957 0 0.00 0 15678 79 0 0
sp_multiply_rows_for_pdistr 28956 28956 0 0.00 0 7175 38 0 0
sp_multiply_rows_for_qdistr 20849 20849 0 0.00 0 11882 180 0 0
sp_pay_from_customer 7434 7434 0 0.00 5 16233 190 0 0
sp_pay_to_supplier 6155 6155 0 0.00 7 15888 187 0 0
sp_payment_common 13589 13589 0 0.00 2 16223 47 0 0
sp_qd_handle_on_cancel_clo 1817 1817 0 0.00 1 10886 326 0 0
sp_qd_handle_on_invoice_upd_sts 10558 10558 0 0.00 0 24227 479 0 0
sp_qd_handle_on_reserve_upd_sts 10267 10267 0 0.00 0 17653 62 0 0
sp_reserve_write_off 10267 10267 0 0.00 8 17727 271 0 0
sp_supplier_invoice 5642 5642 0 0.00 9 80907 2978 0 0
sp_supplier_order 5663 5663 0 0.00 8 38820 1315 0 0
srv_aggregate_perf_data 111 111 0 0.00 3136 369445 17438 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 698 196867 33597 0 0
srv_find_qd_qs_mism 1035686 1035686 0 0.00 0 33109 12 0 0
srv_make_invnt_saldo 526 526 0 0.00 55 97107 3918 0 0
srv_make_money_saldo 919 919 0 0.00 1 11124 355 0 0
srv_recalc_idx_stat 4 4 0 0.00 229857 437829 324162 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5146 5146 0 0.00 1 18098 926 0 0
t$perf-norm:sp_cancel_adding_invoice 507 507 0 0.00 1 7394 477 0 0
t$perf-norm:sp_cancel_client_order 1817 1817 0 0.00 0 7436 33 0 0
t$perf-norm:sp_cancel_customer_reserve 740 740 0 0.00 0 1573 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 5000 28 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 289 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 1755 35 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 122307 6717 0 0
t$perf-norm:sp_cancel_write_off 1051 1051 0 0.00 0 3685 24 0 0
t$perf-norm:sp_client_order 20849 20849 0 0.00 0 10396 34 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 1499 15 0 0
t$perf-norm:sp_pay_from_customer 7434 7434 0 0.00 0 14269 20 0 0
t$perf-norm:sp_pay_to_supplier 6155 6155 0 0.00 0 5139 21 0 0
t$perf-norm:sp_reserve_write_off 10267 10267 0 0.00 0 12219 23 0 0
t$perf-norm:sp_supplier_invoice 5642 5642 0 0.00 0 2600 28 0 0
t$perf-norm:sp_supplier_order 5663 5663 0 0.00 0 2902 28 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 390 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 58 3 0 0
t$perf-norm:srv_make_invnt_saldo 526 526 0 0.00 0 762 19 0 0
t$perf-norm:srv_make_money_saldo 919 919 0 0.00 0 514 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 184 51 0 0
v_add_invoice_to_stock 10470 10192 278 2.65 0 6080 25 0 278
v_all_customers 38608 38608 0 0.00 0 66 4 0 0
v_all_suppliers 31760 31760 0 0.00 0 90 4 0 0
v_all_wares 41694 41694 0 0.00 0 69 4 0 0
v_cancel_adding_invoice 1014 1014 0 0.00 0 571 13 0 0
v_cancel_client_order 3634 3634 0 0.00 0 1635 10 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 2026 12 0 0
v_cancel_customer_reserve 1480 1480 0 0.00 0 1634 11 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 354 11 0 0
v_cancel_supplier_invoice 842 826 16 1.90 0 1601 26 0 16
v_cancel_supplier_order 784 784 0 0.00 0 580 12 0 0
v_cancel_write_off 2102 2102 0 0.00 0 2007 25 0 0
v_max_id_clo_ord 5657 5657 0 0.00 0 513 5 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 1001 10 0 0
v_max_id_ord_sup 5635 5635 0 0.00 0 5548 8 0 0
v_max_non_paid_invoice 1567 1567 0 0.00 0 96 8 0 0
v_max_non_paid_realizn 3746 3746 0 0.00 0 639 7 0 0
v_min_id_clo_ord 5657 5657 0 0.00 0 1409 8 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 1587 9 0 0
v_min_id_ord_sup 5635 5635 0 0.00 0 4581 9 0 0
v_min_non_paid_invoice 6155 6155 0 0.00 0 4521 15 0 0
v_min_non_paid_realizn 7434 7434 0 0.00 0 16217 20 0 0
v_our_firm 10462 10462 0 0.00 0 59 5 0 0
v_reserve_write_off 20534 20534 0 0.00 0 4978 11 0 0
x_lock_depdocs_on_canc_invoice 10901 10901 0 0.00 0 12604 134 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 3363 357 0 0
x_make_qstorno_1000_1200 5663 5663 0 0.00 3 38801 1142 0 0
x_make_qstorno_1200_2000 5642 5642 0 0.00 4 76602 2749 0 0
x_make_qstorno_2100_3300 474066 474066 0 0.00 0 139249 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-07-2022 13:38:59 to 13-07-2022 13:39:03.

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:20 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:21 38674 8192000000 9732224912 10033213440 796772288 8.864 100 84 168 0 824888640 7442016 20674336
11:10:07 10339 8192000000 9762865056 10071609344 836646528 9.266 100 88 176 0 871648544 13872512 21129504
11:11:30 9698 8192000000 9798678432 10118811648 866305328 9.563 100 85 170 1 913026368 26785888 19935152
11:12:45 8502 8192000000 9852744896 10185834496 908354992 9.981 100 83 158 0 966942912 41104048 17483872
11:13:56 6845 8192000000 9918532624 10261467136 960190608 10.491 100 87 173 0 1030278592 51870800 18217184
11:15:10 1721 8192000000 9979845232 10333159424 992394368 10.805 100 94 187 0 1097288576 85859008 19035200
11:16:14 1287 8192000000 10012215184 10373718016 1014104448 11.015 100 97 186 0 1137457680 104800352 18552880
11:17:17 1126 8192000000 9996996944 10362499072 1040815664 11.273 100 92 179 0 1117769216 59041824 17911728
11:18:19 860 8192000000 10048755136 10422706176 1057031120 11.428 100 98 195 0 1169214752 93563552 18620080
11:19:20 959 8192000000 10036337840 10410541056 1065484816 11.509 100 96 185 0 1154787600 70419776 18883008
11:20:22 1147 8192000000 10062573472 10448744448 1067355024 11.527 100 97 190 1 1184374640 97510224 19509392
11:21:24 1117 8192000000 10059105728 10446450688 1075591056 11.605 100 95 178 1 1177271392 83535088 18145248
11:22:26 894 8192000000 10052675712 10443538432 1079232672 11.640 100 91 180 0 1172985872 75577760 18175440
11:23:29 1091 8192000000 10064267424 10453725184 1083258688 11.679 100 89 172 2 1183594720 82886224 17449808
11:24:30 1242 8192000000 10066510752 10457313280 1093752256 11.778 100 96 185 1 1183531744 72056032 17723456
11:25:33 1124 8192000000 10058343712 10448977920 1096623984 11.806 100 97 193 0 1178939248 62147584 20167680
11:26:35 3518 8192000000 10059313424 10456088576 1094305264 11.784 100 94 183 2 1185354896 71949168 19100464
11:27:39 1204 8192000000 10069035072 10458337280 1089737920 11.740 100 93 184 2 1190700880 83021216 17941744
11:28:41 982 8192000000 10055793488 10447638528 1090704928 11.749 100 89 172 3 1178590464 70206720 17678816
11:29:43 1030 8192000000 10036627888 10427449344 1085504896 11.700 100 91 172 2 1153798304 50592736 17700672
11:30:46 1063 8192000000 10063290176 10450444288 1083105312 11.677 100 90 176 0 1183789488 81762720 18921456
11:31:48 1250 8192000000 9990595632 10364882944 1039612832 11.261 100 93 180 0 1114280480 57204448 17463200
11:32:50 1362 8192000000 10007695008 10381164544 1041803280 11.282 100 91 169 1 1124741376 64753632 18184464
11:33:52 894 8192000000 10010542176 10388664320 1044255728 11.306 100 86 161 1 1127169600 66789520 16124352
11:34:54 954 8192000000 9974143888 10349932544 1030936944 11.177 99 86 165 2 1098092480 49039920 18115616
11:35:58 1345 8192000000 9992090192 10372706304 1032806032 11.195 99 67 112 2 1111337648 65895232 12636384
11:37:00 8186 8192000000 10009880832 10394898432 1053559344 11.395 100 72 138 1 1126143584 58814048 13770192
11:38:16 13281 8192000000 10015740976 10402004992 1065863024 11.513 100 67 129 0 1126537520 48260976 12413520
11:39:36 1227 8192000000 10055566032 10438615040 1073907600 11.589 100 92 172 4 1168616096 75912720 18795776
11:40:52 2737 8192000000 10046313616 10424619008 1073225344 11.583 100 83 143 2 1155342896 67477136 14640416
11:42:00 1382 8192000000 10099368992 10487803904 1095995120 11.800 100 87 164 2 1216286384 101174288 19116976
11:43:04 3708 8192000000 10134372960 10533687296 1112068432 11.952 100 63 120 1 1243399216 118827088 12503696
11:44:14 5144 8192000000 10152657328 10539700224 1119180544 12.019 100 91 175 4 1272413552 135075712 18157296
11:45:23 1026 8192000000 10204825232 10607616000 1120680592 12.033 100 94 181 1 1319280016 178483376 20116048
11:46:26 2584 8192000000 10214474592 10614521856 1133017840 12.150 100 79 156 0 1331767680 180889824 17860016
11:47:31 982 8192000000 10245367600 10644459520 1137621472 12.193 100 93 181 1 1357835648 200939424 19274752
11:48:35 962 8192000000 10275443728 10674663424 1147246816 12.284 100 87 161 0 1392240336 226262192 18731328
11:49:38 1753 8192000000 10289735712 10689282048 1161237376 12.415 100 89 173 0 1402106512 220800720 20068416
11:50:43 1061 8192000000 10329214112 10740682752 1174084592 12.535 100 94 181 1 1436522224 242307472 20130160
11:51:46 899 8192000000 10371362608 10771333120 1171677456 12.513 100 95 185 0 1470012256 278525872 19808928
11:52:52 847 8192000000 10362578608 10763055104 1167280176 12.471 100 94 179 4 1467483280 279750800 20452304
11:53:55 937 8192000000 10390636368 10808995840 1174665728 12.540 100 94 179 2 1497321600 302486368 20169504
11:54:58 957 8192000000 10439026480 10842599424 1191817616 12.700 100 97 189 1 1544652368 332353872 20480880
11:56:02 1040 8192000000 10467455296 10869268480 1185266720 12.639 100 92 183 1 1570483120 363603648 21612752
11:57:06 2566 8192000000 10514231600 10937753600 1186413808 12.650 100 97 187 0 1611276992 402903472 21959712
11:58:10 806 8192000000 10504207744 10966245376 1178451024 12.576 100 96 192 0 1612688928 410225872 24012032
11:59:14 803 8192000000 10536681808 10948411392 1186368768 12.650 100 95 185 0 1640627424 432424048 21834608
12:00:19 713 8192000000 10554259712 11021873152 1186417696 12.650 100 88 174 0 1658883840 451376432 21089712
12:01:26 2149 8192000000 10541017456 11045400576 1188493936 12.669 100 85 163 1 1645190032 436080608 20615488
12:02:31 8166 8192000000 10565495888 11021180928 1207545520 12.846 100 58 114 1 1674492496 451477296 15469680
12:03:48 4852 8192000000 10573376752 11021598720 1196131792 12.740 100 46 92 0 1687565264 479161824 12271648
12:05:21 4586 8192000000 10598625088 11106078720 1189030352 12.674 100 41 82 0 1718236896 518091824 11114720
12:06:31 30884 8192000000 10522542944 11035303936 1161770672 12.420 99 33 66 0 1662477504 491678128 9028704
12:08:07 26887 8192000000 10312905232 10940727296 1136300880 12.181 99 26 51 0 1494969872 351884848 6784144
12:09:36 20265 8192000000 10209477360 10666315776 1095129120 11.791 96 25 46 0 1301502064 201157040 5215904
12:10:58 43000 8192000000 10065112160 10496851968 1118017216 12.008 98 19 37 0 1152505088 30578016 3909856
12:12:46 51544 8192000000 10066603344 10605662208 1122177376 12.048 98 9 16 0 1201625488 77680000 1768112
12:14:37 83366 8192000000 10061079696 10506858496 1137211008 12.189 100 43 69 1 1163052336 18320496 7520832
12:17:08 78510 8192000000 10079573328 10518818816 1106430336 11.899 98 28 56 0 1151285024 39537536 5317152
12:19:30 690 8192000000 10075933200 10500730880 1133137600 12.151 100 74 133 2 1167946816 20776480 14032736
12:20:31 27330 8192000000 10106099424 10589429760 1124889504 12.073 98 34 63 1 1186062560 54855824 6317232
12:22:00 9713 8192000000 10152649712 10573197312 1146629712 12.278 100 85 156 4 1251649888 88438336 16581840
12:23:10 162058 8192000000 10119066336 10604417024 1122244576 12.048 97 16 33 0 1214794576 88843120 3706880
12:26:55 196858 8192000000 10084574736 10545512448 1124575232 12.070 97 16 32 0 1181080752 53338544 3166976
12:31:14 54030 8192000000 10130989616 10607108096 1150186592 12.311 99 19 38 0 1219676800 65427824 4062384
12:33:14 55601 8192000000 10137110832 10655223808 1133337072 12.153 98 19 38 0 1239688688 102160752 4190864
12:35:15 64851 8192000000 10102352672 10549723136 1100399712 11.841 96 18 35 0 1150777184 46669328 3708144
12:37:21 23998 8192000000 10108718144 10535075840 1120829776 12.035 99 48 92 0 1194754336 64106320 9818240
12:38:46 66203 8192000000 10125827088 10652782592 1124167344 12.066 99 19 38 0 1229078752 101207056 3704352
12:40:52 38632 8192000000 10114194864 10555609088 1122373344 12.049 98 34 63 0 1175782064 47398784 6009936
12:42:31 58558 8192000000 10081124720 10521526272 1129317904 12.115 99 38 75 0 1177985328 41027872 7639552
12:44:30 51394 8192000000 10162853424 10623950848 1138567088 12.202 99 37 74 0 1251554224 105169248 7817888
12:46:22 38173 8192000000 10091245280 10566557696 1142585184 12.240 100 19 38 0 1227940864 81405296 3950384
12:48:31 57117 8192000000 10063453920 10529050624 1114646816 11.976 98 8 16 0 1154647504 38858272 1142416
12:50:29 19505 8192000000 10074922464 10518446080 1115352304 11.983 99 27 50 0 1175261040 54007488 5901248
12:51:55 36889 8192000000 10067972256 10548043776 1105146256 11.886 98 6 12 0 1164994288 58712448 1135584
12:53:32 20270 8192000000 10099823600 10540814336 1124283008 12.067 98 24 48 1 1176357232 46778640 5295584
12:54:55 19791 8192000000 10147597312 10587303936 1139554480 12.211 100 45 88 1 1243694160 94497904 9641776
12:56:14 33148 8192000000 10129240944 10582560768 1146389392 12.276 100 27 52 0 1233225088 81150016 5685680
12:57:48 46867 8192000000 10159225008 10609504256 1109625504 11.929 97 36 72 0 1210164928 92727120 7812304
12:59:38 16607 8192000000 10150080784 10584391680 1104091760 11.876 97 40 78 0 1228648976 116668080 7889136
13:00:57 32472 8192000000 10117536224 10578595840 1110336016 11.936 98 35 71 0 1223154384 105229280 7589088
13:02:32 46319 8192000000 10088400704 10536353792 1123182256 12.057 100 26 52 0 1192120768 62750656 6187856
13:05:05 97093 8192000000 10117515024 10574692352 1131441024 12.135 100 42 84 0 1200001312 60415680 8144608
13:08:05 2646 8192000000 10078355040 10503868416 1117722960 12.005 100 85 153 4 1180293232 47057312 15512960
13:09:09 80600 8192000000 10086152144 10559741952 1117884320 12.007 99 17 34 1 1199136624 77867632 3384672
13:12:29 64284 8192000000 10060574912 10566991872 1120661296 12.033 100 12 24 0 1185120000 61927264 2531440
13:14:38 67324 8192000000 10062353680 10516045824 1116699552 11.996 99 32 64 0 1166136320 42564960 6871808
13:16:50 39957 8192000000 10073238672 10537897984 1126464336 12.088 100 30 60 0 1194851616 61761760 6625520
13:18:31 48147 8192000000 10142704048 10594672640 1120285824 12.030 98 41 82 0 1207588768 79285024 8017920
13:20:23 47829 8192000000 10163890912 10628706304 1127232432 12.095 98 38 76 1 1243786320 108628304 7925584
13:22:13 36670 8192000000 10151772672 10581241856 1108096544 11.914 98 38 76 0 1239937776 123916016 7925216
13:23:52 13280 8192000000 10142546128 10580221952 1135406912 12.172 100 47 92 0 1228754656 84418112 8929632
13:25:06 9674 8192000000 10112746560 10552332288 1123797168 12.063 100 53 105 0 1203568560 69449008 10322384
13:26:16 107169 8192000000 10103739328 10583220224 1127407712 12.097 100 30 60 0 1218830016 85878144 5544160
13:29:04 68707 8192000000 10044237728 10503565312 1109375664 11.927 98 4 8 0 1140839696 30680880 783152
13:31:18 78857 8192000000 10084498576 10547138560 1125222000 12.076 99 14 28 0 1182604784 54246368 3136416
13:33:51 53157 8192000000 10088126976 10542948352 1113942048 11.970 97 27 55 0 1174374704 53962832 6469824
13:35:53 26667 8192000000 10114251264 10550591488 1101334720 11.850 97 38 76 0 1196798768 87174800 8289248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-07-2022 13:39:05 to 13-07-2022 13:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 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 13-07-2022 13:39:22 to 13-07-2022 13:39: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          222569
MON$OLDEST_ACTIVE               228195
MON$OLDEST_SNAPSHOT             228195
MON$NEXT_TRANSACTION            228195
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                       2078048
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.2.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 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 ---
:148: 
:149: mscs-test-161.budzdorov.ru    Wed Jul 13 13:37:20 2022
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:151: 
:152: 
:153: mscs-test-161.budzdorov.ru    Wed Jul 13 13:37:22 2022
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Wed Jul 13 13:37:24 2022
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:159: 
:160: 
:161: mscs-test-161.budzdorov.ru    Wed Jul 13 13:37:25 2022
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:163: 
:164: 
:165: mscs-test-161.budzdorov.ru    Wed Jul 13 13:37:26 2022
:166:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:167: 
:168: 
:169: mscs-test-161.budzdorov.ru    Wed Jul 13 13:37:26 2022
:170:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:171: 
--- end of diff output ---

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

13.07.22 13:39: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
2022-07-13 13:39:28 No severe PSQL-related problems occured
13.07.22 13:39:29. End of report.