Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.2022 01: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 15976584 kB
MemAvailable 30960160 kB
Buffers 0 kB
Cached 15770584 kB
SwapCached 2508 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.11
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-08-11 01:38:25
DTS_END                         2022-08-11 01:38:25.7340
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/38116
TRN_ID                          245546
ATT_ID                          729
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: 9343.29 21542 1121195
customer order: creation 183.68 371 22041
customer order: refuse 15.32 335 1838
order to supplier: creation 48.26 1153 5791
order to supplier: removal 3.53 87200 423
invoice (draft): creation 47.98 3192 5757
invoice (draft): removal 52.15 700 6258
invoice accept: apply 43.55 17523 5226
invoice accept: cancel 46.80 759 5616
customer reserve: creation 4018.43 174 482212
customer reserve: removal 4576.49 53 549179
realization accept: apply 87.94 188 10553
realization accept: cancel 82.15 106 9858
payment from customer: creation 62.33 114 7480
payment from customer: removal 6.28 59 754
payment to supplier: creation 51.34 140 6161
payment to supplier: removal 3.68 79 442
service: total inventory turnovers 5.18 1091 622
service: total monetary turnovers 7.34 27 881
service: aggregate perf. data 0.83 22658 99
service: refresh index statistics 0.03 294919 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2022 01:39:51 to 11-08-2022 01:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2235
TEST_TIME 2 3119
TEST_TIME 3 3941
TEST_TIME 4 3790
TEST_TIME 5 4314
TEST_TIME 6 4937
TEST_TIME 7 4916
TEST_TIME 8 5343
TEST_TIME 9 5819
TEST_TIME 10 5859
TEST_TIME 11 6293
TEST_TIME 12 6642
TEST_TIME 13 7005
TEST_TIME 14 7612
TEST_TIME 15 7660
TEST_TIME 16 7976
TEST_TIME 17 8103
TEST_TIME 18 8239
TEST_TIME 19 9089
TEST_TIME 20 9234
TEST_TIME 21 9673
TEST_TIME 22 9480
TEST_TIME 23 9747
TEST_TIME 24 10150
TEST_TIME 25 10191
TEST_TIME 26 10996
TEST_TIME 27 10828
TEST_TIME 28 10775
TEST_TIME 29 10660
TEST_TIME 30 10606
TEST_TIME 31 10386
TEST_TIME 32 10275
TEST_TIME 33 10187
TEST_TIME 34 10167
TEST_TIME 35 9965
TEST_TIME 36 9992
TEST_TIME 37 9918
TEST_TIME 38 9862
TEST_TIME 39 9813
TEST_TIME 40 9839
TEST_TIME 41 9683
TEST_TIME 42 9693
TEST_TIME 43 9880
TEST_TIME 44 9707
TEST_TIME 45 9714
TEST_TIME 46 9698
TEST_TIME 47 9576
TEST_TIME 48 9476
TEST_TIME 49 9388
TEST_TIME 50 9508
TEST_TIME 51 9498
TEST_TIME 52 9440
TEST_TIME 53 9571
TEST_TIME 54 9583
TEST_TIME 55 9543
TEST_TIME 56 9522
TEST_TIME 57 9573
TEST_TIME 58 9679
TEST_TIME 59 9604
TEST_TIME 60 9615
TEST_TIME 61 9661
TEST_TIME 62 9778
TEST_TIME 63 9823
TEST_TIME 64 9769
TEST_TIME 65 9794
TEST_TIME 66 9702
TEST_TIME 67 9692
TEST_TIME 68 9619
TEST_TIME 69 9568
TEST_TIME 70 9600
TEST_TIME 71 9530
TEST_TIME 72 9556
TEST_TIME 73 9531
TEST_TIME 74 9430
TEST_TIME 75 9406
TEST_TIME 76 9511
TEST_TIME 77 9463
TEST_TIME 78 9472
TEST_TIME 79 9450
TEST_TIME 80 9511
TEST_TIME 81 9496
TEST_TIME 82 9565
TEST_TIME 83 9560
TEST_TIME 84 9690
TEST_TIME 85 9624
TEST_TIME 86 9669
TEST_TIME 87 9661
TEST_TIME 88 9631
TEST_TIME 89 9551
TEST_TIME 90 9513
TEST_TIME 91 9551
TEST_TIME 92 9500
TEST_TIME 93 9451
TEST_TIME 94 9435
TEST_TIME 95 9400
TEST_TIME 96 9409
TEST_TIME 97 9423
TEST_TIME 98 9440
TEST_TIME 99 9449
TEST_TIME 100 9483
TEST_TIME 101 9459
TEST_TIME 102 9506
TEST_TIME 103 9526
TEST_TIME 104 9520
TEST_TIME 105 9519
TEST_TIME 106 9517
TEST_TIME 107 9538
TEST_TIME 108 9556
TEST_TIME 109 9525
TEST_TIME 110 9487
TEST_TIME 111 9464
TEST_TIME 112 9414
TEST_TIME 113 9385
TEST_TIME 114 9361
TEST_TIME 115 9302
TEST_TIME 116 9335
TEST_TIME 117 9386
TEST_TIME 118 9385
TEST_TIME 119 9403
TEST_TIME 120 9344

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-08-2022 01:39:55 to 11-08-2022 01:39: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 1566914 1566914 0 0.00 0 14324 6 0 0
doc_list_biud 1566914 1566914 0 0.00 0 45573 24 0 0
sp_add_invoice_to_stock 5340 5226 114 2.13 16 180728 17523 0 114
sp_cancel_adding_invoice 5616 5616 0 0.00 4 71752 760 0 0
sp_cancel_client_order 1838 1838 0 0.00 4 8371 335 0 0
sp_cancel_customer_reserve 549179 549179 0 0.00 0 45574 53 0 0
sp_cancel_pay_from_customer 754 754 0 0.00 3 5375 60 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 7110 80 0 0
sp_cancel_supplier_invoice 6268 6258 10 0.15 1 17660 701 0 10
sp_cancel_supplier_order 423 423 0 0.00 6 1092887 87201 0 0
sp_cancel_write_off 9858 9858 0 0.00 1 10260 106 0 0
sp_client_order 22041 22041 0 0.00 3 13346 371 0 0
sp_customer_reserve 482212 482212 0 0.00 0 92642 174 0 0
sp_fill_shopping_cart 33583 33583 0 0.00 1 15170 38 0 0
sp_fill_shopping_cart_clo_res 482211 482211 0 0.00 0 16638 14 0 0
sp_get_clo_for_invoice 5225 5225 0 0.00 0 22954 310 0 0
sp_kill_cost_storno 16670 16670 0 0.00 0 8010 50 0 0
sp_kill_qstorno_ret_qs2qd 1114897 1114897 0 0.00 0 45563 29 0 0
sp_kill_qty_storno 588950 588950 0 0.00 0 45573 63 0 0
sp_lock_selected_doc 21882 21882 0 0.00 0 8816 6 0 0
sp_make_cost_storno 29419 29419 0 0.00 0 12833 52 0 0
sp_multiply_rows_for_pdistr 29419 29419 0 0.00 0 9315 28 0 0
sp_multiply_rows_for_qdistr 22041 22041 0 0.00 0 12361 184 0 0
sp_pay_from_customer 7480 7480 0 0.00 4 11124 115 0 0
sp_pay_to_supplier 6161 6161 0 0.00 4 14309 141 0 0
sp_payment_common 13641 13641 0 0.00 1 10927 34 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 8361 305 0 0
sp_qd_handle_on_invoice_upd_sts 10841 10841 0 0.00 0 18654 302 0 0
sp_qd_handle_on_reserve_upd_sts 10553 10553 0 0.00 0 14709 62 0 0
sp_reserve_write_off 10553 10553 0 0.00 6 18834 189 0 0
sp_supplier_invoice 5757 5757 0 0.00 6 86079 3193 0 0
sp_supplier_order 5791 5791 0 0.00 5 24749 1154 0 0
srv_aggregate_perf_data 99 99 0 0.00 2159 283103 22658 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2278 105484 45973 0 0
srv_find_qd_qs_mism 1070517 1070517 0 0.00 0 12556 5 0 0
srv_make_invnt_saldo 622 622 0 0.00 21 65739 1092 0 0
srv_make_money_saldo 881 881 0 0.00 1 938 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 206602 498468 294919 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5226 5226 0 0.00 0 26493 158 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 3451 108 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 1432 12 0 0
t$perf-norm:sp_cancel_customer_reserve 732 732 0 0.00 0 2583 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 754 754 0 0.00 0 635 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 2733 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 448 448 0 0.00 0 1994 19 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 24034 1501 0 0
t$perf-norm:sp_cancel_write_off 1071 1071 0 0.00 0 6739 25 0 0
t$perf-norm:sp_client_order 22041 22041 0 0.00 0 13166 17 0 0
t$perf-norm:sp_customer_reserve 1053 1053 0 0.00 0 8634 19 0 0
t$perf-norm:sp_pay_from_customer 7480 7480 0 0.00 0 4309 8 0 0
t$perf-norm:sp_pay_to_supplier 6161 6161 0 0.00 0 4284 9 0 0
t$perf-norm:sp_reserve_write_off 10553 10553 0 0.00 0 8068 13 0 0
t$perf-norm:sp_supplier_invoice 5757 5757 0 0.00 0 3474 17 0 0
t$perf-norm:sp_supplier_order 5791 5791 0 0.00 0 13397 19 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 1463 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 417 9 0 0
t$perf-norm:srv_make_invnt_saldo 622 622 0 0.00 0 767 20 0 0
t$perf-norm:srv_make_money_saldo 881 881 0 0.00 0 1004 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10678 10450 228 2.13 0 8852 24 0 228
v_all_customers 39928 39928 0 0.00 0 22 1 0 0
v_all_suppliers 31602 31602 0 0.00 0 22 1 0 0
v_all_wares 44082 44082 0 0.00 0 164 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 254 5 0 0
v_cancel_client_order 3676 3676 0 0.00 0 6859 8 0 0
v_cancel_customer_prepayment 1508 1508 0 0.00 0 5353 7 0 0
v_cancel_customer_reserve 1464 1464 0 0.00 0 2153 6 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 1643 9 0 0
v_cancel_supplier_invoice 916 896 20 2.18 0 4619 24 0 20
v_cancel_supplier_order 846 846 0 0.00 0 407 4 0 0
v_cancel_write_off 2142 2142 0 0.00 0 2796 16 0 0
v_max_id_clo_ord 5789 5789 0 0.00 0 594 1 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 2601 18 0 0
v_max_id_ord_sup 5753 5753 0 0.00 0 5396 3 0 0
v_max_non_paid_invoice 1902 1902 0 0.00 0 1669 3 0 0
v_max_non_paid_realizn 4041 4041 0 0.00 0 6857 7 0 0
v_min_id_clo_ord 5789 5789 0 0.00 0 5461 7 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 5561 28 0 0
v_min_id_ord_sup 5753 5753 0 0.00 0 5539 9 0 0
v_min_non_paid_invoice 6161 6161 0 0.00 0 9166 25 0 0
v_min_non_paid_realizn 7480 7480 0 0.00 0 6893 23 0 0
v_our_firm 11032 11032 0 0.00 0 17 1 0 0
v_reserve_write_off 21106 21106 0 0.00 0 9150 10 0 0
x_lock_depdocs_on_canc_invoice 11426 11426 0 0.00 0 17505 117 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 7040 336 0 0
x_make_qstorno_1000_1200 5791 5791 0 0.00 1 24650 1077 0 0
x_make_qstorno_1200_2000 5757 5757 0 0.00 3 86043 3052 0 0
x_make_qstorno_2100_3300 482212 482212 0 0.00 0 92641 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-08-2022 01:39:59 to 11-08-2022 01:40: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
23:08:29 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:30 5961 8192000000 9878338544 10184134656 967701376 10.564 100 83 166 0 995228464 5687616 21839472
23:10:43 13706 8192000000 9926953392 10244820992 1013160288 11.006 100 81 161 0 1041641264 7794608 20686368
23:12:04 12883 8192000000 9981371680 10309890048 1063461552 11.490 100 87 174 0 1094667472 9635456 21570464
23:13:21 15041 8192000000 10025388320 10366353408 1107978560 11.913 100 87 174 1 1140095888 10887600 21229728
23:14:38 7221 8192000000 10065649808 10416291840 1148825392 12.298 100 89 179 0 1182807600 12632448 21349760
23:15:49 4887 8192000000 10102367152 10466799616 1191060416 12.693 100 76 152 0 1221431744 12009088 18362240
23:16:56 3338 8192000000 10137586704 10506641408 1226808192 13.025 100 89 176 1 1259192416 12875856 19508368
23:18:01 1925 8192000000 10166691216 10542010368 1252950496 13.265 100 83 162 2 1283441520 12593872 17897152
23:19:06 1040 8192000000 10196572608 10579275776 1280407152 13.517 100 90 178 0 1313938288 13372496 20158640
23:20:08 1122 8192000000 10207257968 10593955840 1298564160 13.682 100 82 161 1 1328637536 12222528 17850848
23:21:11 30575 8192000000 10200461152 10607255552 1304586032 13.737 100 38 76 0 1321023840 7771104 8666704
23:22:46 7706 8192000000 10216008368 10624372736 1316045776 13.841 100 28 56 1 1329405104 7480112 5879216
23:24:00 15984 8192000000 10208793456 10630799360 1326353408 13.934 100 13 26 0 1334739424 5454624 2931392
23:25:17 10713 8192000000 10221586368 10639155200 1333987056 14.003 100 19 38 0 1344603344 5806800 4809488
23:26:29 19925 8192000000 10226618352 10651836416 1336510128 14.026 100 30 60 0 1350044640 7126240 6408272
23:27:54 5156 8192000000 10225881632 10648608768 1337622384 14.036 100 20 40 0 1347250144 5584304 4043456
23:29:04 11018 8192000000 10214458720 10636820480 1303466416 13.727 98 20 41 1 1317966400 9654704 4845280
23:30:17 11643 8192000000 10145861568 10552262656 1207637024 12.847 94 32 62 0 1221009232 4942512 8429696
23:31:31 9773 8192000000 10154355712 10561134592 1247963072 13.219 99 21 40 1 1257882976 5131216 4788688
23:32:45 7844 8192000000 10129570656 10533826560 1199242768 12.769 95 23 46 0 1210057360 5494176 5320416
23:33:54 8881 8192000000 10143907504 10543403008 1212295888 12.890 96 24 48 0 1222677952 4987280 5394784
23:35:03 12330 8192000000 10168158816 10575245312 1269192224 13.414 99 27 55 0 1280621184 5354112 6074848
23:36:16 20967 8192000000 10175126416 10586451968 1285845280 13.566 100 30 60 1 1299207728 6891072 6471376
23:37:39 5515 8192000000 10197178320 10612269056 1305579216 13.746 100 27 52 0 1317325104 6049008 5696880
23:38:54 13130 8192000000 10236341984 10648100864 1337973424 14.039 100 33 65 2 1351184144 5224320 7986400
23:40:14 43211 8192000000 10267426928 10683342848 1354480496 14.188 100 38 76 0 1374312960 11236592 8595872
23:42:01 45908 8192000000 10287357984 10704035840 1371608752 14.341 100 54 107 0 1398729568 14466416 12654400
23:44:10 11139 8192000000 10297530304 10717827072 1382392256 14.438 100 50 100 1 1411116224 17826144 10897824
23:45:26 10920 8192000000 10307769936 10727133184 1388238800 14.490 100 56 111 0 1420175408 19200752 12735856
23:46:45 13303 8192000000 10319314192 10742198272 1395225840 14.552 100 62 122 1 1430717232 20171472 15319920
23:48:07 7774 8192000000 10335506416 10760118272 1408539712 14.671 100 55 106 2 1441674832 20758384 12376736
23:49:21 3354 8192000000 10360204144 10775625728 1406506928 14.653 99 81 160 0 1456675024 31677664 18490432
23:50:29 2267 8192000000 10360939536 10775867392 1410062672 14.684 100 87 174 1 1465860608 33754256 22043680
23:51:35 3813 8192000000 10364277680 10779664384 1407756528 14.664 100 74 146 0 1467862000 41896864 18208608
23:52:44 3136 8192000000 10333133808 10754797568 1385595776 14.467 99 75 146 1 1443774704 40572400 17606528
23:53:52 4191 8192000000 10310136176 10723995648 1356782256 14.208 99 71 140 1 1412316336 37844432 17689648
23:55:01 9283 8192000000 10316435504 10731692032 1364100416 14.274 99 65 128 3 1418085120 37888544 16096160
23:56:16 4073 8192000000 10337075792 10752327680 1375682144 14.378 100 68 134 0 1439575616 46117168 17776304
23:57:31 4310 8192000000 10326268864 10738577408 1368087424 14.310 99 64 128 1 1428365152 43297872 16979856
23:58:38 25298 8192000000 10319951696 10754048000 1370957568 14.336 99 34 68 0 1417020576 37072176 8990832
00:00:09 19479 8192000000 10309456016 10745167872 1376098992 14.382 99 22 44 0 1408821920 26654304 6068624
00:01:47 21663 8192000000 10319718176 10758991872 1376504256 14.385 99 23 46 0 1415100640 32339904 6256480
00:03:10 34558 8192000000 10289243024 10736451584 1364858288 14.281 99 12 24 0 1392282096 23945920 3477888
00:05:12 52021 8192000000 10256492656 10699112448 1309010528 13.777 96 26 53 1 1323655968 8542320 6103120
00:07:14 105483 8192000000 10273929008 10716946432 1349198112 14.140 99 13 26 0 1360850464 9005264 2647088
00:10:04 54895 8192000000 10277395808 10716602368 1356983536 14.210 99 45 86 2 1372376192 5415552 9977104
00:12:03 104440 8192000000 10279462224 10718638080 1365921136 14.290 99 31 62 1 1382154432 8610320 7622976
00:14:51 19801 8192000000 10292369504 10740858880 1385443024 14.465 100 31 62 0 1403919072 11380128 7095920
00:16:16 23788 8192000000 10327330720 10759114752 1400578912 14.600 100 52 104 0 1428659744 16383008 11697824
00:17:48 61988 8192000000 10307098048 10762493952 1378206608 14.401 98 17 34 1 1398664240 16086016 4371616
00:20:10 38231 8192000000 10306770416 10763988992 1399140768 14.587 100 17 34 0 1420024656 16537520 4346368
00:21:49 64933 8192000000 10300978896 10758631424 1386253744 14.472 99 32 64 1 1401032880 7345536 7433600
00:24:12 47873 8192000000 10293864944 10763812864 1382498992 14.439 99 28 56 0 1401638240 12356784 6782464
00:26:08 70508 8192000000 10307089536 10779697152 1407923952 14.665 100 16 32 0 1425719040 13966960 3828128
00:28:21 69351 8192000000 10289944144 10755555328 1354129504 14.185 96 3 6 0 1372172400 17434560 608336
00:30:39 20196 8192000000 10323341808 10778009600 1415326128 14.731 100 47 94 0 1437779616 11506176 10947312
00:32:00 82242 8192000000 10297114336 10759708672 1356626128 14.207 96 12 24 0 1368735408 9529488 2579792
00:34:28 29369 8192000000 10302040656 10748489728 1371024560 14.336 99 13 24 1 1386011952 12480400 2506992
00:35:58 59808 8192000000 10271042464 10728517632 1323086240 13.905 95 13 26 1 1339704368 13360016 3258112
00:38:00 37392 8192000000 10281466560 10717474816 1320386224 13.880 96 33 67 0 1337743424 10143840 7213360
00:39:38 56266 8192000000 10262647360 10709913600 1301108352 13.705 95 14 28 0 1320571440 16102944 3360144
00:41:40 70073 8192000000 10252275392 10694569984 1256130496 13.295 92 10 19 0 1266431856 8384672 1916688
00:43:50 99400 8192000000 10271337440 10724249600 1357075328 14.211 100 24 47 1 1373943008 11796240 5071440
00:46:35 17783 8192000000 10283220128 10745520128 1376246208 14.383 100 9 18 0 1396275024 18092656 1936160
00:47:53 67905 8192000000 10269993344 10733330432 1355607824 14.198 99 17 33 0 1382154208 22326256 4220128
00:50:03 34920 8192000000 10258351760 10716758016 1339952480 14.057 98 12 23 0 1352284960 9687824 2644656
00:51:46 71626 8192000000 10261675312 10717032448 1340665232 14.063 98 8 16 0 1352839344 10532224 1641888
00:54:03 69717 8192000000 10256319008 10717351936 1330128768 13.968 97 15 30 0 1348565040 14661184 3775088
00:56:16 32223 8192000000 10248030192 10696015872 1314034960 13.823 97 18 36 0 1327848432 9719216 4094256
00:57:48 79276 8192000000 10279981104 10736160768 1359375568 14.232 99 16 32 0 1376167088 13384880 3406640
01:00:15 36395 8192000000 10293915856 10747224064 1347938864 14.129 97 21 41 0 1372434720 19624848 4871008
01:01:56 100792 8192000000 10280298928 10731638784 1334695472 14.010 96 15 30 0 1361509312 24048960 2764880
01:04:37 48982 8192000000 10302802800 10744602624 1376276704 14.383 99 29 58 0 1393314336 10140560 6897072
01:06:30 86741 8192000000 10299649296 10746568704 1384798048 14.459 100 25 50 1 1405513152 15246064 5469040
01:08:57 51109 8192000000 10282161344 10752610304 1384880432 14.460 100 11 22 0 1398793184 11681856 2230896
01:10:58 74358 8192000000 10279977328 10744934400 1377635216 14.395 99 14 28 0 1389889456 9228224 3026016
01:13:19 76872 8192000000 10306366048 10763513856 1391243536 14.517 99 18 36 0 1406580144 11051168 4285440
01:15:37 76576 8192000000 10321527264 10776334336 1363499280 14.269 97 14 28 1 1382705904 16331728 2874896
01:17:55 74777 8192000000 10285551664 10748674048 1362427664 14.259 98 17 35 1 1383454992 16868064 4159264
01:20:15 29779 8192000000 10318227728 10761207808 1378549696 14.404 98 43 86 3 1399220704 11038512 9632496
01:21:47 40439 8192000000 10317669600 10766970880 1393072240 14.533 99 20 40 0 1406783728 9341168 4370320
01:23:31 21985 8192000000 10299025280 10744713216 1365475072 14.286 98 29 57 0 1382317760 11028352 5814336
01:24:53 60460 8192000000 10272594608 10719653888 1291378176 13.617 93 22 44 0 1304846848 8578096 4890576
01:27:56 84831 8192000000 10234781088 10677997568 1321594736 13.891 98 23 46 0 1336913392 10010560 5308096
01:30:23 66895 8192000000 10275553664 10726973440 1360111024 14.238 99 19 38 0 1380458912 16117584 4230304
01:32:31 59155 8192000000 10287325904 10745155584 1367560592 14.305 99 15 29 0 1392889824 21748992 3580240
01:34:30 65865 8192000000 10272992624 10731184128 1343496160 14.089 98 19 40 1 1370281248 22206624 4578464
01:36:38 63819 8192000000 10289980464 10743652352 1365618992 14.288 99 12 23 0 1385647536 17185664 2842880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-08-2022 01:40:05 to 11-08-2022 01:40:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-08-2022 01:40:21 to 11-08-2022 01:40:23.

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          242540
MON$OLDEST_ACTIVE               246059
MON$OLDEST_SNAPSHOT             246059
MON$NEXT_TRANSACTION            246059
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                       2026144
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Thu Aug 11 01:38:28 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Thu Aug 11 01:38:29 2022
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Thu Aug 11 01:38:33 2022
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:72: 
:73: 
:74: mscs-test-161.budzdorov.ru    Thu Aug 11 01:38:33 2022
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:76: 
:77: 
:78: mscs-test-161.budzdorov.ru    Thu Aug 11 01:38:34 2022
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:80: 
--- end of diff output ---

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

11.08.22 01:40:24. 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-08-11 01:40:26 No severe PSQL-related problems occured
11.08.22 01:40:27. End of report.