Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.02.2023 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 15073216 kB
MemAvailable 30371952 kB
Buffers 0 kB
Cached 16600272 kB
SwapCached 3572 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.3
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 2023-02-05 13:37:27
DTS_END                         2023-02-05 13:37:27.5880
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55102
TRN_ID                          218990
ATT_ID                          648
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: 9285.92 19326 1114310
customer order: creation 171.43 451 20572
customer order: refuse 13.78 386 1654
order to supplier: creation 44.38 1391 5326
order to supplier: removal 3.68 134059 442
invoice (draft): creation 44.23 3081 5307
invoice (draft): removal 52.56 919 6307
invoice accept: apply 40.98 24926 4918
invoice accept: cancel 47.20 957 5664
customer reserve: creation 3919.80 234 470376
customer reserve: removal 4655.44 83 558653
realization accept: apply 83.39 249 10007
realization accept: cancel 81.93 157 9832
payment from customer: creation 57.43 147 6891
payment from customer: removal 5.81 97 697
payment to supplier: creation 47.96 180 5755
payment to supplier: removal 3.78 120 454
service: total inventory turnovers 4.04 3530 485
service: total monetary turnovers 7.04 284 845
service: aggregate perf. data 1.00 13254 120
service: refresh index statistics 0.04 202015 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-02-2023 13:38:34 to 05-02-2023 13:38:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6678
TEST_TIME 2 4935
TEST_TIME 3 5196
TEST_TIME 4 5331
TEST_TIME 5 4957
TEST_TIME 6 5953
TEST_TIME 7 6052
TEST_TIME 8 6003
TEST_TIME 9 6017
TEST_TIME 10 6235
TEST_TIME 11 6332
TEST_TIME 12 6320
TEST_TIME 13 6526
TEST_TIME 14 6630
TEST_TIME 15 6710
TEST_TIME 16 6863
TEST_TIME 17 7082
TEST_TIME 18 7341
TEST_TIME 19 7434
TEST_TIME 20 8153
TEST_TIME 21 8150
TEST_TIME 22 8300
TEST_TIME 23 8245
TEST_TIME 24 8747
TEST_TIME 25 9388
TEST_TIME 26 9961
TEST_TIME 27 10804
TEST_TIME 28 10772
TEST_TIME 29 10597
TEST_TIME 30 10594
TEST_TIME 31 10821
TEST_TIME 32 10949
TEST_TIME 33 10724
TEST_TIME 34 10578
TEST_TIME 35 10574
TEST_TIME 36 10442
TEST_TIME 37 10333
TEST_TIME 38 10187
TEST_TIME 39 10251
TEST_TIME 40 10255
TEST_TIME 41 10172
TEST_TIME 42 10171
TEST_TIME 43 10133
TEST_TIME 44 10092
TEST_TIME 45 10137
TEST_TIME 46 10194
TEST_TIME 47 10110
TEST_TIME 48 9942
TEST_TIME 49 9845
TEST_TIME 50 9872
TEST_TIME 51 9837
TEST_TIME 52 9835
TEST_TIME 53 9819
TEST_TIME 54 9771
TEST_TIME 55 9722
TEST_TIME 56 9731
TEST_TIME 57 9699
TEST_TIME 58 9598
TEST_TIME 59 9627
TEST_TIME 60 9537
TEST_TIME 61 9628
TEST_TIME 62 9642
TEST_TIME 63 9659
TEST_TIME 64 9618
TEST_TIME 65 9574
TEST_TIME 66 9685
TEST_TIME 67 9655
TEST_TIME 68 9666
TEST_TIME 69 9787
TEST_TIME 70 9763
TEST_TIME 71 9761
TEST_TIME 72 9829
TEST_TIME 73 9777
TEST_TIME 74 9802
TEST_TIME 75 9794
TEST_TIME 76 9687
TEST_TIME 77 9644
TEST_TIME 78 9601
TEST_TIME 79 9567
TEST_TIME 80 9565
TEST_TIME 81 9537
TEST_TIME 82 9537
TEST_TIME 83 9483
TEST_TIME 84 9449
TEST_TIME 85 9413
TEST_TIME 86 9398
TEST_TIME 87 9381
TEST_TIME 88 9408
TEST_TIME 89 9409
TEST_TIME 90 9480
TEST_TIME 91 9476
TEST_TIME 92 9467
TEST_TIME 93 9481
TEST_TIME 94 9533
TEST_TIME 95 9521
TEST_TIME 96 9547
TEST_TIME 97 9583
TEST_TIME 98 9569
TEST_TIME 99 9496
TEST_TIME 100 9466
TEST_TIME 101 9408
TEST_TIME 102 9400
TEST_TIME 103 9369
TEST_TIME 104 9360
TEST_TIME 105 9300
TEST_TIME 106 9267
TEST_TIME 107 9223
TEST_TIME 108 9183
TEST_TIME 109 9258
TEST_TIME 110 9257
TEST_TIME 111 9225
TEST_TIME 112 9231
TEST_TIME 113 9197
TEST_TIME 114 9243
TEST_TIME 115 9242
TEST_TIME 116 9216
TEST_TIME 117 9235
TEST_TIME 118 9302
TEST_TIME 119 9330
TEST_TIME 120 9314

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-02-2023 13:38:38 to 05-02-2023 13:38:42.

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 1547914 1547914 0 0.00 0 23456 12 0 0
doc_list_biud 1547914 1547914 0 0.00 0 80900 35 0 0
sp_add_invoice_to_stock 5022 4918 104 2.07 47 180237 24926 0 104
sp_cancel_adding_invoice 5664 5664 0 0.00 6 74013 957 0 0
sp_cancel_client_order 1654 1654 0 0.00 6 18628 387 0 0
sp_cancel_customer_reserve 558653 558653 0 0.00 1 48611 83 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 7518 97 0 0
sp_cancel_pay_to_supplier 454 454 0 0.00 4 3762 121 0 0
sp_cancel_supplier_invoice 6315 6307 8 0.12 2 80912 920 0 8
sp_cancel_supplier_order 442 442 0 0.00 10 1159093 134059 0 0
sp_cancel_write_off 9832 9832 0 0.00 2 11958 157 0 0
sp_client_order 20572 20572 0 0.00 6 21347 452 0 0
sp_customer_reserve 470376 470376 0 0.00 0 42060 234 0 0
sp_fill_shopping_cart 31202 31202 0 0.00 2 20018 71 0 0
sp_fill_shopping_cart_clo_res 470357 470357 0 0.00 0 20804 18 0 0
sp_get_clo_for_invoice 4882 4882 0 0.00 0 26424 274 0 0
sp_kill_cost_storno 16647 16647 0 0.00 0 10350 62 0 0
sp_kill_qstorno_ret_qs2qd 1133887 1133887 0 0.00 0 80893 37 0 0
sp_kill_qty_storno 597440 597440 0 0.00 0 80898 85 0 0
sp_lock_selected_doc 20573 20573 0 0.00 0 9665 8 0 0
sp_make_cost_storno 27534 27534 0 0.00 0 14279 73 0 0
sp_multiply_rows_for_pdistr 27534 27534 0 0.00 0 23426 35 0 0
sp_multiply_rows_for_qdistr 20572 20572 0 0.00 0 16330 182 0 0
sp_pay_from_customer 6891 6891 0 0.00 5 14872 148 0 0
sp_pay_to_supplier 5755 5755 0 0.00 6 23470 180 0 0
sp_payment_common 12646 12646 0 0.00 2 10778 39 0 0
sp_qd_handle_on_cancel_clo 1654 1654 0 0.00 1 18611 326 0 0
sp_qd_handle_on_invoice_upd_sts 10545 10545 0 0.00 0 30382 421 0 0
sp_qd_handle_on_reserve_upd_sts 10007 10007 0 0.00 0 15412 60 0 0
sp_reserve_write_off 10007 10007 0 0.00 8 15429 250 0 0
sp_supplier_invoice 5307 5307 0 0.00 9 108337 3082 0 0
sp_supplier_order 5326 5326 0 0.00 7 83721 1391 0 0
srv_aggregate_perf_data 120 120 0 0.00 2114 111936 13254 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 749 170423 37092 0 0
srv_find_qd_qs_mism 1065781 1065781 0 0.00 0 15051 10 0 0
srv_make_invnt_saldo 485 485 0 0.00 36 77772 3531 0 0
srv_make_money_saldo 845 845 0 0.00 1 10383 284 0 0
srv_recalc_idx_stat 5 5 0 0.00 99545 349394 202016 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 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 4923 4923 0 0.00 1 8604 689 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 5851 328 0 0
t$perf-norm:sp_cancel_client_order 1654 1654 0 0.00 0 3550 25 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 3254 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 4244 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 454 454 0 0.00 0 2061 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 5775 38 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 1 73751 4837 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 2817 25 0 0
t$perf-norm:sp_client_order 20573 20573 0 0.00 0 6697 31 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 2351 15 0 0
t$perf-norm:sp_pay_from_customer 6891 6891 0 0.00 0 5090 18 0 0
t$perf-norm:sp_pay_to_supplier 5755 5755 0 0.00 0 6232 18 0 0
t$perf-norm:sp_reserve_write_off 10007 10007 0 0.00 0 8028 23 0 0
t$perf-norm:sp_supplier_invoice 5307 5307 0 0.00 0 6619 27 0 0
t$perf-norm:sp_supplier_order 5326 5326 0 0.00 0 3235 26 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 642 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 572 13 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 1680 12 0 0
t$perf-norm:srv_make_money_saldo 845 845 0 0.00 0 1206 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 180 81 0 0
v_add_invoice_to_stock 9966 9758 208 2.08 0 5248 23 0 208
v_all_customers 37014 37014 0 0.00 0 63 3 0 0
v_all_suppliers 30096 30096 0 0.00 0 57 3 0 0
v_all_wares 41142 41142 0 0.00 0 107 3 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 1924 13 0 0
v_cancel_client_order 3308 3308 0 0.00 0 5113 9 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 5827 14 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 1519 12 0 0
v_cancel_payment_to_supplier 908 908 0 0.00 0 1623 13 0 0
v_cancel_supplier_invoice 818 802 16 1.95 0 2228 24 0 16
v_cancel_supplier_order 884 884 0 0.00 0 1830 16 0 0
v_cancel_write_off 1946 1946 0 0.00 0 5907 29 0 0
v_max_id_clo_ord 5326 5326 0 0.00 0 5044 5 0 0
v_max_id_clo_res 986 986 0 0.00 0 3868 13 0 0
v_max_id_ord_sup 5305 5305 0 0.00 0 3340 7 0 0
v_max_non_paid_invoice 1338 1338 0 0.00 0 1638 8 0 0
v_max_non_paid_realizn 3659 3659 0 0.00 0 374 6 0 0
v_min_id_clo_ord 5326 5326 0 0.00 0 2341 7 0 0
v_min_id_clo_res 986 986 0 0.00 0 3564 22 0 0
v_min_id_ord_sup 5305 5305 0 0.00 0 8870 18 0 0
v_min_non_paid_invoice 5755 5755 0 0.00 0 10773 19 0 0
v_min_non_paid_realizn 6891 6891 0 0.00 0 3262 13 0 0
v_our_firm 10592 10592 0 0.00 0 142 4 0 0
v_reserve_write_off 20014 20014 0 0.00 0 4636 10 0 0
x_lock_depdocs_on_canc_invoice 11570 11570 0 0.00 0 12263 112 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 8725 296 0 0
x_make_qstorno_1000_1200 5326 5326 0 0.00 3 83604 1254 0 0
x_make_qstorno_1200_2000 5307 5307 0 0.00 4 96239 2876 0 0
x_make_qstorno_2100_3300 470376 470376 0 0.00 0 40415 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-02-2023 13:38:43 to 05-02-2023 13:38:47.

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:31 6 8192000000 8800809232 8813072384 5496080 0.067 4 1 2 0 5599024 67168 35776
11:08:31 20079 8192000000 9723258368 10021777408 801367056 8.910 100 91 180 1 831468080 7333376 22767648
11:10:00 20090 8192000000 9761412528 10070487040 838353728 9.283 100 85 170 0 870105120 10938176 20813216
11:11:25 7463 8192000000 9792211616 10110595072 863285056 9.533 100 85 168 0 900505056 16855648 20364352
11:12:40 7846 8192000000 9858218640 10190962688 910340976 10.001 100 84 168 1 970852752 41980208 18531568
11:13:52 2532 8192000000 9901531712 10241593344 942917392 10.322 100 86 166 1 1013463280 52779072 17766816
11:14:56 2630 8192000000 9975799088 10328817664 988080672 10.763 100 94 181 2 1083184832 76094368 19009792
11:16:01 1530 8192000000 9998235872 10361610240 1017650528 11.049 100 92 180 1 1113419664 77979152 17789984
11:17:04 799 8192000000 9994109328 10360352768 1028630176 11.155 100 96 187 0 1116417856 68193472 19594208
11:18:07 1075 8192000000 10023164368 10398691328 1050680848 11.367 100 95 184 0 1144127136 74657584 18788704
11:19:09 1196 8192000000 10020921408 10397192192 1063241184 11.487 100 95 177 0 1141759696 60644064 17874448
11:20:11 955 8192000000 10042140752 10421383168 1069747888 11.550 100 92 181 0 1160892592 73010128 18134576
11:21:13 6266 8192000000 10055841264 10435260416 1074380768 11.594 100 89 163 0 1173674416 83851360 15442288
11:22:20 3808 8192000000 10018799888 10417258496 1078680704 11.635 100 66 127 1 1145754752 54118864 12955184
11:23:25 1181 8192000000 10066536736 10462351360 1083585280 11.682 100 84 161 4 1187137168 87250704 16301184
11:24:27 4323 8192000000 10020869952 10415456256 1085946432 11.704 100 67 118 3 1151305392 54214960 11144000
11:25:32 3101 8192000000 10047289648 10438025216 1087359184 11.718 100 84 159 1 1170491200 66712544 16419472
11:26:37 1039 8192000000 10063505776 10451578880 1096799856 11.807 100 97 185 1 1185283168 69705216 18778096
11:27:38 960 8192000000 10057671728 10451484672 1098080096 11.819 100 85 162 0 1180755504 66315824 16359584
11:28:40 1449 8192000000 10063719328 10460102656 1093511024 11.776 100 84 158 0 1184044928 74908400 15625504
11:29:42 1065 8192000000 10041886256 10427121664 1082380112 11.670 100 94 172 6 1160867520 60475584 18011824
11:30:44 1047 8192000000 10010960192 10395316224 1059364208 11.450 99 90 165 0 1138882896 61931120 17587568
11:31:47 1071 8192000000 10042392000 10425061376 1071144128 11.563 100 93 177 1 1166179392 76964432 18070832
11:32:48 1098 8192000000 10015702016 10398511104 1055101088 11.410 100 83 165 1 1142326544 69902128 17323328
11:33:50 1225 8192000000 9967058288 10333302784 1015209728 11.026 100 97 180 3 1090477888 56105936 19162224
11:34:52 982 8192000000 9965046880 10332729344 1018610608 11.059 99 94 177 1 1087028128 48035280 20382240
11:35:57 885 8192000000 9996353536 10373873664 1041671376 11.281 100 84 158 1 1116228272 56447712 18109184
11:36:58 5250 8192000000 9979077088 10354352128 1042057968 11.284 100 66 121 1 1101862032 47893632 11910432
11:38:09 5456 8192000000 9999707792 10389192704 1064193120 11.497 100 59 114 0 1128221328 52778096 11250112
11:39:21 5881 8192000000 10033602976 10416693248 1082349952 11.670 100 61 121 0 1144224480 49820048 12054480
11:40:40 4112 8192000000 10083014416 10467368960 1091832944 11.760 100 73 139 0 1204516128 97704384 14978800
11:41:47 4514 8192000000 10112711936 10496008192 1100028752 11.838 100 83 160 1 1233454704 114932064 18493888
11:42:56 3323 8192000000 10172743568 10569760768 1115835744 11.988 100 80 157 0 1294616032 160929952 17850336
11:44:04 4477 8192000000 10121643616 10530922496 1124901520 12.073 100 63 120 3 1237527952 99771472 12854960
11:45:20 1539 8192000000 10190201024 10582433792 1141798448 12.232 100 96 187 1 1306196848 145193632 19204768
11:46:27 5928 8192000000 10237484928 10633236480 1159544000 12.399 100 79 150 1 1354761600 178495680 16721920
11:47:35 1106 8192000000 10300268656 10695352320 1176275008 12.555 100 99 194 0 1416359824 217835776 22249040
11:48:40 1275 8192000000 10309636400 10721705984 1182155424 12.610 100 77 153 0 1429009920 228956704 17897792
11:49:45 738 8192000000 10384468720 10785730560 1194240160 12.723 100 98 186 2 1496066272 280075264 21750848
11:50:48 1259 8192000000 10429425968 10838499328 1198367520 12.761 100 93 185 1 1539900400 320796000 20736880
11:51:52 909 8192000000 10455231888 10865872896 1204315088 12.816 100 96 188 0 1562211008 336384784 21511136
11:52:56 1007 8192000000 10497477792 10918006784 1209520208 12.865 100 92 176 2 1604128752 373898784 20709760
11:54:00 1832 8192000000 10491719168 10938146816 1213575584 12.902 100 91 174 1 1601605648 367206240 20823824
11:55:04 1212 8192000000 10553748576 10964733952 1218664368 12.949 100 94 174 3 1658932448 418389312 21878768
11:56:07 931 8192000000 10568990704 10991104000 1199214880 12.769 100 81 156 1 1677660816 459060128 19385808
11:57:15 2204 8192000000 10546402496 11011653632 1201281584 12.788 100 80 154 1 1654881136 435175200 18424352
11:58:21 22092 8192000000 10529600000 10973646848 1193164688 12.713 100 41 82 0 1642087360 437997424 10925248
11:59:57 29825 8192000000 10546198448 11008151552 1185002384 12.637 100 34 68 0 1641336288 447554704 8779200
12:01:32 19961 8192000000 10455553088 10934738944 1154058048 12.348 100 32 64 0 1610780976 447437440 9285488
12:03:08 20907 8192000000 10294379072 10806452224 1142330928 12.237 99 23 44 1 1425197264 276813376 6052960
12:04:32 26129 8192000000 10130408768 10627428352 1132190656 12.142 98 27 52 0 1249211040 110449568 6570816
12:06:03 34822 8192000000 10206681200 10647416832 1161519840 12.417 100 51 97 2 1283263584 110833120 10910624
12:08:09 48566 8192000000 10155390560 10704592896 1148969888 12.300 99 15 30 0 1276269984 123773904 3526192
12:10:02 55531 8192000000 10072963136 10506653696 1121707680 12.043 98 19 31 1 1156347792 31877136 2762976
12:12:01 53312 8192000000 10069950624 10549452800 1105138144 11.886 97 19 38 0 1159812992 50849152 3825696
12:14:03 13806 8192000000 10079612528 10508759040 1134511072 12.164 100 55 92 4 1171228368 26588112 10129184
12:15:34 170409 8192000000 10075253664 10565197824 1097582368 11.815 96 13 26 0 1138337792 38069312 2686112
12:19:59 20573 8192000000 10087118384 10532798464 1136943888 12.187 100 13 26 0 1193494560 53781568 2769104
12:21:21 155481 8192000000 10062150064 10520391680 1073482144 11.585 94 18 33 1 1123226192 45157280 4586768
12:24:58 117883 8192000000 10145916512 10579439616 1136773888 12.185 99 41 81 0 1227431696 81795760 8862048
12:27:57 40128 8192000000 10087844512 10527281152 1107090352 11.905 98 45 86 1 1199648704 82712176 9846176
12:29:41 9739 8192000000 10084078560 10504884224 1132315936 12.143 100 41 74 1 1163993360 24340592 7336832
12:30:54 25217 8192000000 10105501744 10536873984 1125358448 12.078 99 26 50 0 1198697856 67515600 5823808
12:32:28 13606 8192000000 10133651904 10580299776 1144554464 12.258 100 58 112 3 1250638656 94239712 11844480
12:33:58 35217 8192000000 10102072688 10595446784 1112171152 11.953 98 20 38 1 1206346944 89688256 4487536
12:35:33 34017 8192000000 10117164176 10575937536 1110887488 11.941 98 10 20 0 1211180176 97930208 2362480
12:37:24 62718 8192000000 10102277888 10583638016 1130895136 12.130 100 15 30 0 1222852304 88682192 3274976
12:39:27 59602 8192000000 10074728864 10536235008 1135802176 12.176 100 12 24 0 1176133696 37756224 2575296
12:41:29 3866 8192000000 10103322816 10525540352 1148356208 12.294 100 58 92 5 1195612960 36748256 10508496
12:42:35 44415 8192000000 10141660864 10610737152 1156058384 12.366 100 32 63 0 1252028640 89528384 6441872
12:44:46 46782 8192000000 10134317088 10570203136 1144729152 12.260 99 34 59 2 1227303424 76362176 6212096
12:46:37 32677 8192000000 10096218128 10548449280 1117837280 12.007 98 26 53 0 1193662080 70691104 5133696
12:48:16 73455 8192000000 10093297392 10592862208 1136903568 12.186 99 16 32 0 1214223664 74167536 3152560
12:50:34 32205 8192000000 10102628592 10542608384 1109242192 11.925 96 35 68 0 1154627312 38516496 6868624
12:52:06 40854 8192000000 10080126528 10511884288 1093688368 11.778 96 31 58 0 1137610032 38458208 5463456
12:53:51 47449 8192000000 10088802528 10535657472 1121021408 12.037 99 47 86 0 1169005008 37815312 10168288
12:55:42 51369 8192000000 10088837152 10533535744 1133439072 12.154 99 31 62 0 1213193072 73039472 6714528
12:57:34 62349 8192000000 10105254608 10582487040 1126616016 12.089 99 16 32 1 1215582672 84669424 4297232
12:59:36 43468 8192000000 10084591952 10527944704 1137218880 12.189 100 18 37 0 1167843696 26290960 4333856
13:01:20 62341 8192000000 10102254224 10574757888 1123476464 12.060 99 11 22 0 1187920544 62108688 2335392
13:03:22 46001 8192000000 10082429776 10514325504 1088489008 11.728 96 24 48 0 1147120048 53348880 5282160
13:05:15 34267 8192000000 10097097216 10561458176 1100258384 11.840 98 33 62 0 1186102896 79403872 6440640
13:06:50 79837 8192000000 10091184512 10587107328 1116862704 11.997 99 27 52 0 1214593536 92473584 5257248
13:09:14 73995 8192000000 10111904560 10563448832 1096001264 11.800 96 29 58 0 1165155824 63350976 5803584
13:11:29 27790 8192000000 10076015632 10503237632 1114087504 11.971 98 38 73 0 1158775632 37780784 6907344
13:12:59 36208 8192000000 10128924624 10570530816 1113965536 11.970 98 47 94 0 1205016960 81727232 9324192
13:14:36 83732 8192000000 10076826192 10535534592 1119997072 12.027 99 20 40 0 1171353184 47355616 4000496
13:18:06 14815 8192000000 10056558960 10521350144 1124199344 12.067 99 26 52 1 1157572400 28511104 4861952
13:19:22 57155 8192000000 10054325328 10534203392 1127078048 12.094 100 20 40 0 1176833056 45865952 3889056
13:21:25 67932 8192000000 10071872432 10535604224 1123115200 12.056 99 25 48 0 1169873712 41456256 5302256
13:23:43 73354 8192000000 10120222176 10583756800 1105247552 11.887 97 13 26 0 1165257376 56679264 3330560
13:26:23 69385 8192000000 10081595088 10581176320 1132307008 12.143 100 20 41 0 1218425152 81808304 4309840
13:28:33 10458 8192000000 10094655328 10535641088 1145714560 12.269 100 50 93 1 1210410704 54972192 9723952
13:29:48 39543 8192000000 10146721344 10573287424 1138952144 12.206 99 34 68 1 1224426816 78322496 7152176
13:31:28 89062 8192000000 10149110432 10610417664 1121812464 12.044 98 30 58 0 1229376064 101194176 6369424
13:34:02 63307 8192000000 10162349760 10669436928 1126935152 12.092 98 37 74 0 1277274560 142727184 7612224
13:36:07 33807 8192000000 10148530384 10664157184 1131593328 12.136 99 35 70 0 1217770800 79245728 6931744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 05-02-2023 13:38:49 to 05-02-2023 13:39:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 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 05-02-2023 13:39:06 to 05-02-2023 13:39:08.

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          215983
MON$OLDEST_ACTIVE               219458
MON$OLDEST_SNAPSHOT             219458
MON$NEXT_TRANSACTION            219458
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                       2056032
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2895 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2895 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2895 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    Sun Feb  5 13:37:30 2023
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:151: 
:152: 
:153: mscs-test-161.budzdorov.ru    Sun Feb  5 13:37:51 2023
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Sun Feb  5 13:37:51 2023
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:159: 
:160: 
:161: mscs-test-161.budzdorov.ru    Sun Feb  5 13:37:51 2023
: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'

05.02.23 13:39:09. 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
2023-02-05 13:39:12 No severe PSQL-related problems occured
05.02.23 13:39:13. End of report.