Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.04.2022 19:39
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 16916832 kB
MemAvailable 31580472 kB
Buffers 0 kB
Cached 14835012 kB
SwapCached 2364 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 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-04-08 19:38:13
DTS_END                         2022-04-08 19:38:13.4970
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(57:17)
IP                              ::1/47678
TRN_ID                          226712
ATT_ID                          612
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: 9320.62 24056 1118474
customer order: creation 162.13 383 19455
customer order: refuse 13.99 323 1679
order to supplier: creation 44.34 1348 5321
order to supplier: removal 3.53 93611 423
invoice (draft): creation 43.17 3535 5180
invoice (draft): removal 55.02 700 6602
invoice accept: apply 39.37 18151 4724
invoice accept: cancel 49.69 683 5963
customer reserve: creation 3696.32 178 443558
customer reserve: removal 4925.71 53 591085
realization accept: apply 78.82 188 9458
realization accept: cancel 86.88 96 10426
payment from customer: creation 54.64 111 6557
payment from customer: removal 5.89 90 707
payment to supplier: creation 46.58 131 5590
payment to supplier: removal 3.48 76 418
service: total inventory turnovers 4.46 1034 535
service: total monetary turnovers 5.79 22 695
service: aggregate perf. data 0.78 24535 94
service: refresh index statistics 0.03 335874 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-04-2022 19:39:41 to 08-04-2022 19:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7240
TEST_TIME 2 5994
TEST_TIME 3 6428
TEST_TIME 4 5892
TEST_TIME 5 6336
TEST_TIME 6 6146
TEST_TIME 7 5998
TEST_TIME 8 6195
TEST_TIME 9 6504
TEST_TIME 10 6521
TEST_TIME 11 6979
TEST_TIME 12 6961
TEST_TIME 13 7255
TEST_TIME 14 7825
TEST_TIME 15 7817
TEST_TIME 16 8174
TEST_TIME 17 8256
TEST_TIME 18 8219
TEST_TIME 19 8631
TEST_TIME 20 9216
TEST_TIME 21 9748
TEST_TIME 22 9964
TEST_TIME 23 10074
TEST_TIME 24 11179
TEST_TIME 25 11178
TEST_TIME 26 10928
TEST_TIME 27 11376
TEST_TIME 28 11277
TEST_TIME 29 11053
TEST_TIME 30 10890
TEST_TIME 31 10795
TEST_TIME 32 10671
TEST_TIME 33 10587
TEST_TIME 34 10386
TEST_TIME 35 10180
TEST_TIME 36 10147
TEST_TIME 37 10171
TEST_TIME 38 10041
TEST_TIME 39 9869
TEST_TIME 40 9734
TEST_TIME 41 9752
TEST_TIME 42 9634
TEST_TIME 43 9653
TEST_TIME 44 9542
TEST_TIME 45 9427
TEST_TIME 46 9291
TEST_TIME 47 9221
TEST_TIME 48 9243
TEST_TIME 49 9182
TEST_TIME 50 9147
TEST_TIME 51 9206
TEST_TIME 52 9182
TEST_TIME 53 9163
TEST_TIME 54 9087
TEST_TIME 55 9206
TEST_TIME 56 9227
TEST_TIME 57 9342
TEST_TIME 58 9410
TEST_TIME 59 9373
TEST_TIME 60 9435
TEST_TIME 61 9549
TEST_TIME 62 9590
TEST_TIME 63 9556
TEST_TIME 64 9726
TEST_TIME 65 9786
TEST_TIME 66 9758
TEST_TIME 67 9626
TEST_TIME 68 9576
TEST_TIME 69 9518
TEST_TIME 70 9525
TEST_TIME 71 9443
TEST_TIME 72 9465
TEST_TIME 73 9421
TEST_TIME 74 9469
TEST_TIME 75 9395
TEST_TIME 76 9440
TEST_TIME 77 9499
TEST_TIME 78 9483
TEST_TIME 79 9444
TEST_TIME 80 9490
TEST_TIME 81 9486
TEST_TIME 82 9551
TEST_TIME 83 9574
TEST_TIME 84 9527
TEST_TIME 85 9598
TEST_TIME 86 9570
TEST_TIME 87 9593
TEST_TIME 88 9642
TEST_TIME 89 9592
TEST_TIME 90 9495
TEST_TIME 91 9476
TEST_TIME 92 9430
TEST_TIME 93 9367
TEST_TIME 94 9332
TEST_TIME 95 9349
TEST_TIME 96 9424
TEST_TIME 97 9430
TEST_TIME 98 9435
TEST_TIME 99 9413
TEST_TIME 100 9438
TEST_TIME 101 9440
TEST_TIME 102 9483
TEST_TIME 103 9535
TEST_TIME 104 9524
TEST_TIME 105 9519
TEST_TIME 106 9588
TEST_TIME 107 9567
TEST_TIME 108 9582
TEST_TIME 109 9571
TEST_TIME 110 9561
TEST_TIME 111 9510
TEST_TIME 112 9479
TEST_TIME 113 9454
TEST_TIME 114 9438
TEST_TIME 115 9445
TEST_TIME 116 9406
TEST_TIME 117 9386
TEST_TIME 118 9373
TEST_TIME 119 9355
TEST_TIME 120 9323

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-04-2022 19:39:45 to 08-04-2022 19:39:48.

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 1528760 1528760 0 0.00 0 56840 6 0 0
doc_list_biud 1528759 1528759 0 0.00 0 44019 26 0 0
sp_add_invoice_to_stock 4814 4724 90 1.86 57 143122 18151 0 90
sp_cancel_adding_invoice 5963 5963 0 0.00 5 65985 683 0 0
sp_cancel_client_order 1679 1679 0 0.00 4 7888 323 0 0
sp_cancel_customer_reserve 591085 591085 0 0.00 1 44020 54 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 6418 90 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 4786 76 0 0
sp_cancel_supplier_invoice 6613 6602 11 0.16 2 16175 701 0 11
sp_cancel_supplier_order 423 423 0 0.00 9 944470 93612 0 0
sp_cancel_write_off 10426 10426 0 0.00 1 11071 97 0 0
sp_client_order 19455 19455 0 0.00 3 54578 383 0 0
sp_customer_reserve 443558 443558 0 0.00 0 38962 178 0 0
sp_fill_shopping_cart 29948 29948 0 0.00 1 28899 45 0 0
sp_fill_shopping_cart_clo_res 443555 443555 0 0.00 0 14543 14 0 0
sp_get_clo_for_invoice 4719 4719 0 0.00 0 33080 310 0 0
sp_kill_cost_storno 17514 17514 0 0.00 0 11456 51 0 0
sp_kill_qstorno_ret_qs2qd 1199621 1199621 0 0.00 0 44017 29 0 0
sp_kill_qty_storno 630355 630355 0 0.00 0 44018 62 0 0
sp_lock_selected_doc 19714 19714 0 0.00 0 6404 5 0 0
sp_make_cost_storno 26324 26324 0 0.00 0 56808 54 0 0
sp_multiply_rows_for_pdistr 26324 26324 0 0.00 0 9072 27 0 0
sp_multiply_rows_for_qdistr 19455 19455 0 0.00 0 54530 172 0 0
sp_pay_from_customer 6557 6557 0 0.00 4 10690 111 0 0
sp_pay_to_supplier 5590 5590 0 0.00 5 12983 132 0 0
sp_payment_common 12147 12147 0 0.00 1 9640 26 0 0
sp_qd_handle_on_cancel_clo 1679 1679 0 0.00 0 7879 294 0 0
sp_qd_handle_on_invoice_upd_sts 10682 10682 0 0.00 0 35741 278 0 0
sp_qd_handle_on_reserve_upd_sts 9458 9458 0 0.00 0 16389 64 0 0
sp_reserve_write_off 9458 9458 0 0.00 6 17335 189 0 0
sp_supplier_invoice 5180 5180 0 0.00 7 86104 3535 0 0
sp_supplier_order 5321 5321 0 0.00 5 36446 1348 0 0
srv_aggregate_perf_data 94 94 0 0.00 2940 445702 24536 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1617 139198 49492 0 0
srv_find_qd_qs_mism 1072925 1072925 0 0.00 0 20214 5 0 0
srv_make_invnt_saldo 535 535 0 0.00 45 80290 1035 0 0
srv_make_money_saldo 695 695 0 0.00 1 3223 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 120712 550998 335875 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4725 4725 0 0.00 0 25888 183 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 3293 83 0 0
t$perf-norm:sp_cancel_client_order 1679 1679 0 0.00 0 1490 11 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 3289 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 3414 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 5238 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 648 10 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 31234 1773 0 0
t$perf-norm:sp_cancel_write_off 842 842 0 0.00 0 5238 14 0 0
t$perf-norm:sp_client_order 19455 19455 0 0.00 0 12091 19 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 5038 20 0 0
t$perf-norm:sp_pay_from_customer 6557 6557 0 0.00 0 4579 11 0 0
t$perf-norm:sp_pay_to_supplier 5590 5590 0 0.00 0 8701 13 0 0
t$perf-norm:sp_reserve_write_off 9458 9458 0 0.00 0 18236 19 0 0
t$perf-norm:sp_supplier_invoice 5180 5180 0 0.00 0 7307 22 0 0
t$perf-norm:sp_supplier_order 5321 5321 0 0.00 0 6310 18 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 369 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 12 1 0 0
t$perf-norm:srv_make_invnt_saldo 535 535 0 0.00 0 5608 27 0 0
t$perf-norm:srv_make_money_saldo 695 695 0 0.00 0 1311 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9618 9438 180 1.87 0 12929 19 0 180
v_all_customers 35052 35052 0 0.00 0 27 0 0 0
v_all_suppliers 29148 29148 0 0.00 0 22 1 0 0
v_all_wares 38910 38910 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 2088 11 0 0
v_cancel_client_order 3358 3358 0 0.00 0 4861 10 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 4962 11 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 4029 7 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 4549 12 0 0
v_cancel_supplier_invoice 814 792 22 2.70 0 2110 17 0 22
v_cancel_supplier_order 846 846 0 0.00 0 2730 6 0 0
v_cancel_write_off 1684 1684 0 0.00 0 4585 25 0 0
v_max_id_clo_ord 5321 5321 0 0.00 0 3930 2 0 0
v_max_id_clo_res 961 961 0 0.00 0 3641 13 0 0
v_max_id_ord_sup 5172 5172 0 0.00 0 5083 6 0 0
v_max_non_paid_invoice 1509 1509 0 0.00 0 6134 8 0 0
v_max_non_paid_realizn 3589 3589 0 0.00 0 5206 5 0 0
v_min_id_clo_ord 5321 5321 0 0.00 0 12547 12 0 0
v_min_id_clo_res 961 961 0 0.00 0 7570 11 0 0
v_min_id_ord_sup 5172 5172 0 0.00 0 5772 6 0 0
v_min_non_paid_invoice 5590 5590 0 0.00 0 9635 18 0 0
v_min_non_paid_realizn 6557 6557 0 0.00 0 5792 18 0 0
v_our_firm 9792 9792 0 0.00 0 19 1 0 0
v_reserve_write_off 18916 18916 0 0.00 0 10560 9 0 0
x_lock_depdocs_on_canc_invoice 12169 12169 0 0.00 0 14922 101 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 23495 487 0 0
x_make_qstorno_1000_1200 5321 5321 0 0.00 1 36407 1261 0 0
x_make_qstorno_1200_2000 5180 5180 0 0.00 3 83183 3371 0 0
x_make_qstorno_2100_3300 443558 443558 0 0.00 0 38961 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-04-2022 19:39:49 to 08-04-2022 19:39:53.

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
17:08:17 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:18 22321 8192000000 9899256112 10206740480 984418176 10.727 100 89 178 0 1013723728 5796336 23509216
17:10:44 11790 8192000000 9939128624 10256158720 1022226224 11.093 100 92 184 0 1053834800 8150032 23458544
17:11:59 14460 8192000000 9971178176 10300698624 1052830000 11.388 100 84 168 0 1083518608 9632976 21055632
17:13:19 10326 8192000000 10014822336 10359296000 1102988544 11.866 100 74 148 0 1131180816 10079776 18112496
17:14:33 9073 8192000000 10060810576 10412912640 1150701520 12.316 100 81 162 0 1180457840 11128144 18628176
17:15:46 7226 8192000000 10109666992 10474549248 1194180224 12.722 100 81 162 3 1226159408 13205792 18773392
17:16:59 5229 8192000000 10146755456 10521329664 1236610000 13.115 100 77 154 2 1267445008 13540832 17294176
17:18:07 2811 8192000000 10180766896 10558291968 1268639904 13.409 100 93 186 0 1302632944 13261952 20731088
17:19:13 2317 8192000000 10193992672 10577104896 1285918560 13.567 100 91 179 2 1319807328 13546560 20342208
17:20:19 36248 8192000000 10198862496 10589532160 1292438640 13.626 100 71 142 1 1321290672 12856016 15996016
17:22:00 6142 8192000000 10196173344 10600398848 1298679840 13.683 100 44 88 0 1318127920 10036496 9411584
17:23:10 5482 8192000000 10191410800 10603462656 1306681568 13.756 100 23 46 3 1318665664 6685488 5298608
17:24:17 16528 8192000000 10218321712 10620149760 1312003840 13.804 100 55 110 1 1331508272 6900288 12604144
17:25:35 5355 8192000000 10209983136 10627825664 1319384608 13.871 100 32 64 0 1333523600 6860336 7278656
17:26:45 8970 8192000000 10218497776 10636546048 1330574544 13.972 100 17 34 2 1339935264 5129696 4231024
17:27:56 5022 8192000000 10235455392 10642120704 1333951968 14.003 100 57 112 0 1352319568 6111664 12255936
17:29:02 5664 8192000000 10198636912 10611560448 1299325664 13.689 99 25 51 2 1311322400 6318672 5678064
17:30:10 7532 8192000000 10178144144 10588565504 1278821888 13.502 99 22 44 1 1290290672 6512144 4956640
17:31:18 17355 8192000000 10162582304 10545532928 1213163984 12.898 96 24 48 3 1224782592 5570704 6047904
17:33:11 6300 8192000000 10144795328 10547191808 1228904352 13.044 97 29 58 1 1241506912 6056048 6546512
17:34:18 11174 8192000000 10150263072 10558967808 1252362048 13.260 99 26 52 0 1264119312 5266736 6490528
17:35:31 12065 8192000000 10178894224 10590412800 1287921504 13.585 100 9 19 0 1295238528 5305760 2011264
17:36:45 51734 8192000000 10180953792 10603470848 1298278288 13.680 100 28 52 1 1309553040 5064576 6210176
17:38:43 35017 8192000000 10241693616 10649255936 1330207040 13.969 100 46 88 2 1349839424 10299984 9332400
17:40:21 29154 8192000000 10272317504 10683346944 1355454112 14.197 100 60 120 1 1382653296 14303200 12895984
17:41:58 15630 8192000000 10272374464 10693947392 1364666544 14.279 100 48 96 4 1390260512 13277184 12316784
17:43:19 32545 8192000000 10278092320 10711420928 1370908000 14.335 100 34 68 2 1392514544 13711232 7895312
17:44:59 1616 8192000000 10311100432 10727845888 1389033840 14.497 100 68 135 0 1421501488 16569200 15898448
17:46:05 9550 8192000000 10316980096 10746982400 1397983040 14.577 100 41 82 2 1433580896 25359680 10238176
17:47:19 5673 8192000000 10340137408 10770227200 1411217664 14.695 100 47 94 0 1452265488 29196192 11851632
17:48:29 5743 8192000000 10357016080 10771537920 1406492528 14.653 99 68 134 0 1456323184 34107536 15723120
17:49:40 4811 8192000000 10367518832 10787012608 1423098960 14.800 100 66 131 0 1473687504 34853136 15735408
17:50:51 2472 8192000000 10374591328 10797416448 1425631520 14.823 100 76 152 2 1482188128 37500704 19055904
17:51:58 7900 8192000000 10358500704 10789728256 1418739856 14.762 100 52 104 1 1471721024 39843264 13137904
17:53:09 5329 8192000000 10367248576 10782838784 1395997360 14.559 98 68 136 1 1450891248 38505344 16388544
17:54:18 11053 8192000000 10352314368 10781315072 1395858720 14.558 99 40 80 2 1446328208 39564832 10904656
17:55:36 8898 8192000000 10350210544 10778943488 1403105104 14.623 100 47 94 0 1458910912 43281904 12523904
17:56:49 3168 8192000000 10360421360 10781495296 1388650608 14.494 99 58 116 2 1455156704 51922720 14583376
17:57:54 8097 8192000000 10343860176 10769731584 1379133440 14.409 99 41 82 1 1443508976 52973936 11401600
17:59:06 8058 8192000000 10349745728 10782167040 1390178576 14.507 100 33 65 2 1453073888 53966800 8928512
18:00:18 22737 8192000000 10313940768 10750840832 1365358416 14.285 99 23 45 1 1407913248 36457936 6096896
18:01:42 24018 8192000000 10306729600 10746949632 1358069040 14.220 99 19 38 0 1413170576 49358128 5743408
18:03:17 42844 8192000000 10309777344 10734764032 1355753808 14.199 98 34 66 1 1389526384 25449696 8322880
18:05:02 9043 8192000000 10297475456 10746601472 1368293648 14.312 99 32 64 3 1397846208 21827616 7724944
18:06:20 61849 8192000000 10271068480 10728964096 1337662544 14.036 98 13 27 0 1361084768 20534480 2887744
18:08:24 86151 8192000000 10262327504 10707587072 1326326544 13.934 97 21 42 0 1341365808 10532880 4506384
18:11:02 84204 8192000000 10257472704 10702835712 1286112256 13.569 95 16 32 0 1301370080 11773040 3484784
18:13:54 95064 8192000000 10250632176 10698805248 1309863968 13.785 96 20 40 0 1332514096 17590800 5059328
18:16:29 31521 8192000000 10255379984 10707505152 1352391952 14.169 99 14 28 0 1368017472 12940016 2685504
18:18:02 62622 8192000000 10276240560 10719031296 1365934544 14.291 100 37 73 2 1384882064 11054064 7893456
18:20:05 74658 8192000000 10255231040 10709700608 1351379808 14.160 99 14 28 0 1366452528 11799520 3273200
18:22:26 69078 8192000000 10271193168 10723033088 1372740848 14.352 100 18 35 2 1387441776 11022640 3678288
18:24:48 45082 8192000000 10275753520 10723061760 1373088384 14.355 100 39 78 0 1390510144 8535744 8886016
18:26:34 69703 8192000000 10280368656 10745487360 1387408912 14.483 100 23 46 0 1402463280 9874336 5180032
18:28:51 34583 8192000000 10278488224 10750316544 1379552912 14.413 99 14 28 2 1396312688 13238464 3521312
18:30:26 66901 8192000000 10290631824 10751721472 1377682144 14.396 99 9 18 0 1388412864 8455440 2275280
18:32:44 59454 8192000000 10303459952 10766938112 1398002416 14.577 100 16 32 1 1419751504 17645328 4103760
18:34:53 32412 8192000000 10284058288 10743283712 1364027328 14.273 98 10 20 0 1380312128 14051184 2233616
18:36:26 100373 8192000000 10302827824 10743836672 1344523952 14.098 96 32 64 1 1365944192 14161440 7258800
18:39:14 84691 8192000000 10302627168 10761109504 1384909120 14.460 99 9 18 1 1405889424 19358864 1621440
18:41:45 88531 8192000000 10315112016 10777116672 1367370448 14.303 98 12 24 0 1388762288 18632208 2759632
18:44:14 133613 8192000000 10282780224 10736205824 1368411520 14.313 98 16 32 1 1389524384 17292496 3820368
18:47:44 9752 8192000000 10299652640 10745991168 1390512608 14.510 100 32 65 1 1410251040 12831120 6907312
18:48:54 60631 8192000000 10282110016 10739200000 1351523088 14.161 97 11 22 0 1367026992 13016096 2487808
18:50:58 79207 8192000000 10266470192 10733920256 1351924896 14.165 98 11 22 1 1372020304 17322224 2773184
18:53:18 64086 8192000000 10285575472 10753007616 1386085824 14.471 100 15 30 0 1400354832 10971616 3297392
18:55:22 58535 8192000000 10270218352 10728488960 1318345680 13.862 96 20 40 0 1339596640 16130320 5120640
18:57:25 49641 8192000000 10272962576 10721144832 1345338464 14.106 98 26 52 0 1361280624 10487248 5454912
18:59:39 51383 8192000000 10269833200 10719268864 1310911616 13.794 95 40 78 2 1336926512 16645376 9369520
19:01:40 94147 8192000000 10261394736 10700804096 1298527248 13.682 95 28 56 1 1318580768 13873104 6180416
19:04:16 23766 8192000000 10273342544 10705166336 1345270160 14.105 99 38 76 0 1363398208 9627424 8500624
19:05:51 97418 8192000000 10253499232 10693091328 1286749424 13.575 94 14 28 0 1304040432 14295904 2995104
19:08:29 102502 8192000000 10265131104 10715648000 1342762880 14.082 98 28 56 0 1359936688 10768416 6405392
19:11:13 97553 8192000000 10261668400 10709925888 1330014432 13.967 97 12 24 0 1352332384 19220320 3097632
19:13:50 52043 8192000000 10272113632 10730995712 1371708608 14.342 100 20 40 0 1393631232 17642240 4280384
19:15:44 62806 8192000000 10286461376 10742259712 1374257248 14.365 99 16 32 0 1386221776 8570912 3393616
19:17:48 39746 8192000000 10300287616 10753347584 1384134832 14.454 99 27 54 0 1400712368 10259696 6317840
19:19:41 49976 8192000000 10294951472 10747318272 1379427920 14.411 99 26 52 0 1396613600 11182704 6002976
19:21:36 79658 8192000000 10302051760 10752081920 1379625824 14.413 99 10 20 0 1392070736 10161920 2282992
19:23:56 42412 8192000000 10320589280 10785415168 1402454928 14.617 100 19 38 0 1420548288 13508496 4584864
19:25:44 99600 8192000000 10314166432 10763542528 1365291952 14.285 97 14 28 1 1377209600 9268304 2649344
19:28:25 11541 8192000000 10324115360 10771456000 1410695104 14.690 100 25 50 0 1424464752 8303072 5466576
19:29:37 139198 8192000000 10301987072 10760785920 1380592368 14.422 98 29 58 1 1398104928 10769328 6743232
19:32:59 45926 8192000000 10298503712 10762977280 1391086784 14.516 99 18 36 0 1406099440 11056368 3956288
19:34:45 53600 8192000000 10289748224 10750705664 1381681824 14.432 99 10 20 0 1398242224 13490016 3070384
19:36:42 78905 8192000000 10289995856 10745802752 1363830224 14.272 98 6 12 0 1378115200 12844176 1440800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-04-2022 19:39:55 to 08-04-2022 19:40:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-04-2022 19:40:11 to 08-04-2022 19:40:13.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          226711
MON$OLDEST_ACTIVE               227173
MON$OLDEST_SNAPSHOT             227173
MON$NEXT_TRANSACTION            227173
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-03 16:41:55.3470
MON$PAGES                       2002560
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Fri Apr  8 19:38:16 2022
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Fri Apr  8 19:38:16 2022
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Fri Apr  8 19:38:18 2022
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:42: 
:43: 
:44: mscs-test-161.budzdorov.ru    Fri Apr  8 19:38:19 2022
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:46: 
:47: 
:48: mscs-test-161.budzdorov.ru    Fri Apr  8 19:38:21 2022
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:50: 
:51: 
:52: mscs-test-161.budzdorov.ru    Fri Apr  8 19:38:21 2022
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:54: 
:55: 
:56: mscs-test-161.budzdorov.ru    Fri Apr  8 19:38:21 2022
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:58: 
:59: 
:60: mscs-test-161.budzdorov.ru    Fri Apr  8 19:38:21 2022
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:62: 
--- end of diff output ---

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

08.04.22 19:40:14. There are 3 logs in /var/tmp/logs-oltp30 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-04-08 19:40:16 No severe PSQL-related problems occured
08.04.22 19:40:18. End of report.