Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.12.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 15345328 kB
MemAvailable 30336824 kB
Buffers 0 kB
Cached 16339364 kB
SwapCached 11360 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-12-30 01:38:35
DTS_END                         2022-12-30 01:38:35.0190
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/58202
TRN_ID                          239429
ATT_ID                          657
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: 8944.78 21954 1073373
customer order: creation 168.50 359 20220
customer order: refuse 14.88 389 1786
order to supplier: creation 45.29 1326 5435
order to supplier: removal 3.30 96354 396
invoice (draft): creation 44.25 3402 5310
invoice (draft): removal 50.75 775 6090
invoice accept: apply 41.50 18107 4980
invoice accept: cancel 46.48 851 5578
customer reserve: creation 3707.28 184 444874
customer reserve: removal 4525.90 55 543108
realization accept: apply 82.77 201 9932
realization accept: cancel 86.71 110 10405
payment from customer: creation 57.91 122 6949
payment from customer: removal 5.73 61 687
payment to supplier: creation 48.30 134 5796
payment to supplier: removal 3.21 107 385
service: total inventory turnovers 4.64 1009 557
service: total monetary turnovers 6.54 25 785
service: aggregate perf. data 0.80 20879 96
service: refresh index statistics 0.03 294639 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-12-2022 01:39:38 to 30-12-2022 01:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2868
TEST_TIME 2 5133
TEST_TIME 3 4954
TEST_TIME 4 4782
TEST_TIME 5 4583
TEST_TIME 6 4534
TEST_TIME 7 4844
TEST_TIME 8 5463
TEST_TIME 9 5551
TEST_TIME 10 5803
TEST_TIME 11 5967
TEST_TIME 12 6637
TEST_TIME 13 6811
TEST_TIME 14 6880
TEST_TIME 15 6884
TEST_TIME 16 7131
TEST_TIME 17 7412
TEST_TIME 18 7693
TEST_TIME 19 7642
TEST_TIME 20 7827
TEST_TIME 21 8494
TEST_TIME 22 8754
TEST_TIME 23 8786
TEST_TIME 24 8703
TEST_TIME 25 8742
TEST_TIME 26 9053
TEST_TIME 27 9236
TEST_TIME 28 9366
TEST_TIME 29 9651
TEST_TIME 30 9786
TEST_TIME 31 9786
TEST_TIME 32 9877
TEST_TIME 33 9804
TEST_TIME 34 9662
TEST_TIME 35 9852
TEST_TIME 36 9860
TEST_TIME 37 9636
TEST_TIME 38 9674
TEST_TIME 39 9550
TEST_TIME 40 9505
TEST_TIME 41 9422
TEST_TIME 42 9482
TEST_TIME 43 9445
TEST_TIME 44 9461
TEST_TIME 45 9437
TEST_TIME 46 9272
TEST_TIME 47 9215
TEST_TIME 48 9280
TEST_TIME 49 9107
TEST_TIME 50 9084
TEST_TIME 51 8976
TEST_TIME 52 9125
TEST_TIME 53 9034
TEST_TIME 54 8967
TEST_TIME 55 8895
TEST_TIME 56 8809
TEST_TIME 57 8733
TEST_TIME 58 8790
TEST_TIME 59 8766
TEST_TIME 60 8711
TEST_TIME 61 8769
TEST_TIME 62 8847
TEST_TIME 63 8876
TEST_TIME 64 8912
TEST_TIME 65 8882
TEST_TIME 66 8875
TEST_TIME 67 8989
TEST_TIME 68 9037
TEST_TIME 69 8976
TEST_TIME 70 9066
TEST_TIME 71 9095
TEST_TIME 72 9207
TEST_TIME 73 9150
TEST_TIME 74 9288
TEST_TIME 75 9180
TEST_TIME 76 9143
TEST_TIME 77 9083
TEST_TIME 78 9028
TEST_TIME 79 9007
TEST_TIME 80 8993
TEST_TIME 81 9030
TEST_TIME 82 8989
TEST_TIME 83 8954
TEST_TIME 84 8908
TEST_TIME 85 8917
TEST_TIME 86 8907
TEST_TIME 87 8875
TEST_TIME 88 8886
TEST_TIME 89 8865
TEST_TIME 90 8870
TEST_TIME 91 8892
TEST_TIME 92 8913
TEST_TIME 93 8921
TEST_TIME 94 8926
TEST_TIME 95 8935
TEST_TIME 96 8980
TEST_TIME 97 9021
TEST_TIME 98 9025
TEST_TIME 99 8983
TEST_TIME 100 9092
TEST_TIME 101 9097
TEST_TIME 102 9074
TEST_TIME 103 9140
TEST_TIME 104 9069
TEST_TIME 105 9008
TEST_TIME 106 9022
TEST_TIME 107 8997
TEST_TIME 108 8984
TEST_TIME 109 8953
TEST_TIME 110 8957
TEST_TIME 111 8941
TEST_TIME 112 8882
TEST_TIME 113 8901
TEST_TIME 114 8924
TEST_TIME 115 8898
TEST_TIME 116 8936
TEST_TIME 117 8915
TEST_TIME 118 8919
TEST_TIME 119 8925
TEST_TIME 120 8949

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-12-2022 01:39:42 to 30-12-2022 01:39:45.

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 1486186 1486186 0 0.00 0 16926 6 0 0
doc_list_biud 1486180 1486180 0 0.00 0 46049 26 0 0
sp_add_invoice_to_stock 5087 4980 107 2.10 23 165233 18107 0 107
sp_cancel_adding_invoice 5578 5578 0 0.00 4 95878 851 0 0
sp_cancel_client_order 1786 1786 0 0.00 4 17035 390 0 0
sp_cancel_customer_reserve 543108 543108 0 0.00 0 46051 55 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 4887 61 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 7461 107 0 0
sp_cancel_supplier_invoice 6109 6090 19 0.31 1 26964 775 0 19
sp_cancel_supplier_order 396 396 0 0.00 9 864438 96355 0 0
sp_cancel_write_off 10405 10405 0 0.00 1 11881 111 0 0
sp_client_order 20220 20220 0 0.00 3 46123 360 0 0
sp_customer_reserve 444874 444874 0 0.00 0 35916 185 0 0
sp_fill_shopping_cart 30958 30958 0 0.00 1 8583 39 0 0
sp_fill_shopping_cart_clo_res 444851 444851 0 0.00 0 12985 15 0 0
sp_get_clo_for_invoice 4957 4957 0 0.00 0 24173 371 0 0
sp_kill_cost_storno 17055 17055 0 0.00 0 11877 57 0 0
sp_kill_qstorno_ret_qs2qd 1103107 1103107 0 0.00 0 46048 30 0 0
sp_kill_qty_storno 582248 582248 0 0.00 0 46049 66 0 0
sp_lock_selected_doc 20710 20710 0 0.00 0 8397 4 0 0
sp_make_cost_storno 27632 27632 0 0.00 0 16923 61 0 0
sp_multiply_rows_for_pdistr 27630 27630 0 0.00 0 11543 30 0 0
sp_multiply_rows_for_qdistr 20220 20220 0 0.00 0 41116 160 0 0
sp_pay_from_customer 6949 6949 0 0.00 4 12634 123 0 0
sp_pay_to_supplier 5796 5796 0 0.00 4 19932 134 0 0
sp_payment_common 12745 12745 0 0.00 1 11133 31 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 16989 366 0 0
sp_qd_handle_on_invoice_upd_sts 10531 10531 0 0.00 0 17773 341 0 0
sp_qd_handle_on_reserve_upd_sts 9932 9932 0 0.00 0 17889 79 0 0
sp_reserve_write_off 9932 9932 0 0.00 6 17977 201 0 0
sp_supplier_invoice 5310 5310 0 0.00 7 86592 3402 0 0
sp_supplier_order 5435 5435 0 0.00 5 59563 1327 0 0
srv_aggregate_perf_data 96 96 0 0.00 2221 208108 20879 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1696 122908 47346 0 0
srv_find_qd_qs_mism 1027315 1027315 0 0.00 0 14825 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 30 33456 1010 0 0
srv_make_money_saldo 785 785 0 0.00 1 525 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 160933 651847 294639 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4980 4980 0 0.00 0 36105 167 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 4040 84 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 7521 23 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 1624 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 7646 28 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 754 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 4373 20 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 22180 1445 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 3655 11 0 0
t$perf-norm:sp_client_order 20220 20220 0 0.00 0 8941 17 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 4234 14 0 0
t$perf-norm:sp_pay_from_customer 6949 6949 0 0.00 0 7507 11 0 0
t$perf-norm:sp_pay_to_supplier 5796 5796 0 0.00 0 4493 9 0 0
t$perf-norm:sp_reserve_write_off 9932 9932 0 0.00 0 7644 11 0 0
t$perf-norm:sp_supplier_invoice 5310 5310 0 0.00 0 9254 17 0 0
t$perf-norm:sp_supplier_order 5435 5435 0 0.00 0 8517 18 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 479 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 53 1 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 1103 23 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 2189 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 23 8 0 0
v_add_invoice_to_stock 10118 9904 214 2.11 0 4221 20 0 214
v_all_customers 36924 36924 0 0.00 0 23 1 0 0
v_all_suppliers 29558 29558 0 0.00 0 22 1 0 0
v_all_wares 40436 40436 0 0.00 0 95 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 658 7 0 0
v_cancel_client_order 3572 3572 0 0.00 0 2439 4 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 967 3 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 1580 5 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 629 4 0 0
v_cancel_supplier_invoice 816 778 38 4.65 0 5776 24 0 38
v_cancel_supplier_order 792 792 0 0.00 0 536 7 0 0
v_cancel_write_off 2078 2078 0 0.00 0 1619 14 0 0
v_max_id_clo_ord 5435 5435 0 0.00 0 2160 2 0 0
v_max_id_clo_res 968 968 0 0.00 0 2196 14 0 0
v_max_id_ord_sup 5305 5305 0 0.00 0 4390 4 0 0
v_max_non_paid_invoice 1757 1757 0 0.00 0 1052 3 0 0
v_max_non_paid_realizn 3714 3714 0 0.00 0 3534 6 0 0
v_min_id_clo_ord 5435 5435 0 0.00 0 6418 9 0 0
v_min_id_clo_res 968 968 0 0.00 0 3480 9 0 0
v_min_id_ord_sup 5305 5305 0 0.00 0 3330 4 0 0
v_min_non_paid_invoice 5796 5796 0 0.00 0 8656 23 0 0
v_min_non_paid_realizn 6949 6949 0 0.00 0 11116 21 0 0
v_our_firm 9982 9982 0 0.00 0 336 1 0 0
v_reserve_write_off 19864 19864 0 0.00 0 4668 6 0 0
x_lock_depdocs_on_canc_invoice 11279 11279 0 0.00 0 16863 121 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 3626 351 0 0
x_make_qstorno_1000_1200 5435 5435 0 0.00 1 59555 1247 0 0
x_make_qstorno_1200_2000 5310 5310 0 0.00 3 85665 3262 0 0
x_make_qstorno_2100_3300 444874 444874 0 0.00 0 35914 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-12-2022 01:39:46 to 30-12-2022 01:39:50.

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:38 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:39 32188 8192000000 9909847712 10220363776 987365888 10.756 100 88 176 0 1016569328 5761168 23442272
23:11:16 23588 8192000000 9954019280 10275487744 1035950512 11.226 100 92 184 0 1068548576 8392304 24205760
23:12:45 16300 8192000000 9980814176 10316234752 1071569952 11.567 100 80 158 0 1101268128 9895664 19802512
23:14:06 8275 8192000000 10038682064 10389712896 1123409296 12.059 100 75 151 0 1151941568 10222240 18310032
23:15:18 5917 8192000000 10081060960 10439294976 1167757328 12.476 100 79 156 1 1197631536 11991344 17882864
23:16:27 3986 8192000000 10132582240 10500579328 1216395648 12.928 100 76 152 1 1246687872 13338624 16953600
23:17:34 6918 8192000000 10153775376 10533842944 1247265264 13.213 100 70 141 0 1274422304 11954864 15202176
23:18:43 1245 8192000000 10187982032 10572050432 1277474528 13.490 100 85 171 1 1309510176 12878032 19157616
23:19:47 6359 8192000000 10201716032 10590715904 1292553920 13.627 100 71 142 0 1320852176 12588960 15709296
23:20:54 18103 8192000000 10189280368 10595991552 1299844080 13.694 100 44 88 0 1319356480 9707840 9804560
23:22:15 7610 8192000000 10207244816 10610388992 1309802928 13.784 100 40 80 1 1328373264 9158768 9411568
23:23:23 8400 8192000000 10199762192 10617925632 1317731312 13.856 100 14 29 0 1326900032 5918000 3250720
23:24:32 60335 8192000000 10209305232 10629378048 1322018992 13.895 100 35 66 0 1334439520 4416128 8004400
23:26:36 5965 8192000000 10225674064 10643906560 1330997744 13.976 100 25 48 1 1342574672 5409296 6167632
23:27:45 6762 8192000000 10217441936 10640408576 1330175888 13.969 100 19 38 0 1340021888 5715360 4130640
23:28:54 17197 8192000000 10178533264 10585243648 1210121600 12.870 92 32 62 0 1223107552 4623520 8362432
23:30:12 16772 8192000000 10155547248 10559356928 1200529296 12.781 94 20 39 0 1210307728 5548368 4230064
23:31:33 5685 8192000000 10139392240 10542297088 1228434720 13.040 98 31 61 0 1240806848 5897088 6475040
23:32:39 14158 8192000000 10138832160 10543087616 1221609840 12.977 97 18 36 0 1231028400 5188768 4229792
23:33:54 70191 8192000000 10153349136 10559614976 1250053248 13.239 98 30 60 0 1262852896 5524656 7274992
23:36:08 5004 8192000000 10186879120 10598408192 1297620960 13.674 100 27 54 0 1310352256 6693248 6038048
23:37:15 6415 8192000000 10204882720 10619060224 1315587408 13.837 100 30 61 0 1332855344 9710080 7557856
23:38:29 18753 8192000000 10245913200 10658050048 1340796336 14.065 100 38 71 1 1358824640 10115680 7912624
23:39:53 54333 8192000000 10251367696 10674016256 1350648256 14.153 100 39 78 1 1367462448 8648880 8165312
23:41:52 29538 8192000000 10270530912 10694930432 1371458320 14.340 100 40 81 0 1391898352 11007664 9432368
23:43:35 14208 8192000000 10299156000 10722553856 1388395280 14.492 100 43 85 1 1410728352 12347968 9985104
23:44:58 6489 8192000000 10310230192 10737770496 1398651120 14.583 100 39 78 1 1422655344 14285328 9718896
23:46:12 10420 8192000000 10304180848 10730319872 1391435440 14.519 100 48 96 0 1417603808 14531760 11636608
23:47:30 53057 8192000000 10317998656 10752372736 1401421888 14.608 100 34 68 0 1427596864 16980944 9194032
23:49:36 1905 8192000000 10323440480 10744721408 1399909264 14.594 100 62 122 3 1430138800 15780032 14449504
23:50:43 6423 8192000000 10312616912 10741878784 1397762448 14.575 100 43 86 0 1425907440 17951904 10193088
23:51:53 6632 8192000000 10316653680 10738499584 1390995296 14.515 100 59 116 1 1424771008 19748208 14027504
23:53:06 4737 8192000000 10294085008 10704789504 1346473104 14.116 98 75 147 2 1386068880 22124656 17471120
23:54:13 2458 8192000000 10320020272 10727915520 1375867344 14.380 100 70 138 0 1420806624 27816736 17122544
23:55:20 4239 8192000000 10309182224 10728034304 1382680400 14.441 100 57 113 1 1416403920 19553504 14170016
23:56:30 4004 8192000000 10319976016 10729754624 1367064032 14.301 99 69 136 1 1413034320 29829440 16140848
23:57:41 1695 8192000000 10303871056 10714038272 1365728880 14.289 100 83 164 1 1417884064 29760384 22394800
23:58:48 3958 8192000000 10327141632 10739953664 1373747456 14.361 100 76 152 1 1431219280 37526688 19945136
23:59:58 11144 8192000000 10305134368 10734989312 1365046464 14.283 99 37 74 1 1404126880 29010608 10069808
00:01:23 22926 8192000000 10289804480 10712940544 1338445680 14.043 98 29 58 0 1374053968 27731216 7877072
00:02:54 6894 8192000000 10323981520 10741293056 1380521216 14.421 100 50 96 0 1428628624 35988464 12118944
00:04:05 11635 8192000000 10323152640 10747375616 1377288352 14.392 100 33 67 0 1427690016 41075840 9325824
00:05:22 19289 8192000000 10306815840 10735595520 1373092384 14.355 100 28 56 1 1414090896 33028112 7970400
00:06:49 24216 8192000000 10298709712 10724950016 1338238560 14.042 98 22 44 0 1372866368 28547888 6079920
00:08:34 78634 8192000000 10274746048 10711801856 1342352176 14.079 98 29 55 0 1368386368 19503568 6530624
00:11:58 15548 8192000000 10292443168 10722930688 1368988752 14.318 100 53 105 0 1398673056 17540144 12144160
00:14:23 94215 8192000000 10284519392 10731626496 1371621920 14.342 100 21 40 1 1395852784 19386224 4844640
00:16:59 34960 8192000000 10300042976 10746269696 1390438416 14.510 100 15 30 0 1402756528 8784944 3533168
00:18:40 44390 8192000000 10307865584 10758709248 1386947008 14.479 99 24 48 0 1402183104 9832064 5404032
00:20:26 71101 8192000000 10308044640 10765844480 1404943232 14.639 100 38 75 0 1428875792 14623584 9308976
00:22:42 71288 8192000000 10298445616 10760867840 1393097168 14.533 100 4 8 0 1413995264 19996192 901904
00:25:05 86135 8192000000 10294398096 10764288000 1387048784 14.480 99 22 44 0 1409507856 17116480 5342592
00:27:40 9594 8192000000 10324538576 10759237632 1392841136 14.531 99 71 137 2 1420919184 11079744 16998304
00:28:50 23739 8192000000 10309316384 10767773696 1407489408 14.662 100 16 29 2 1427951408 17676288 2785712
00:30:37 61323 8192000000 10299030208 10750271488 1373954496 14.362 98 10 20 0 1389673696 12965008 2754192
00:32:42 37297 8192000000 10301616576 10757804032 1397946320 14.577 99 21 40 0 1414740080 12088976 4704784
00:34:20 117613 8192000000 10293062288 10746929152 1351497264 14.161 96 20 40 0 1368349872 12032544 4820064
00:37:18 54810 8192000000 10283243056 10744266752 1367312784 14.303 98 13 26 1 1382666304 12300848 3052672
00:39:18 107605 8192000000 10292362480 10745446400 1356272704 14.204 97 22 42 1 1372566128 11214048 5079376
00:42:07 66593 8192000000 10281428432 10735599616 1338182464 14.041 96 21 42 0 1350949536 8632944 4134128
00:44:22 35253 8192000000 10287558608 10732617728 1376794128 14.388 100 34 68 0 1403263600 19253552 7215920
00:45:58 82284 8192000000 10282266912 10727329792 1318134736 13.860 95 15 30 0 1333144800 11905888 3104176
00:48:29 81558 8192000000 10277523296 10735173632 1357688624 14.217 99 17 30 0 1374210448 13243984 3277840
00:50:50 122908 8192000000 10270804976 10729312256 1342494288 14.080 98 17 32 0 1366667200 20695888 3477024
00:54:00 68743 8192000000 10256813312 10703101952 1312469296 13.808 96 21 42 0 1327985632 11250624 4265712
00:56:10 85758 8192000000 10265850688 10723434496 1357581824 14.216 99 12 24 0 1378395344 17294608 3518912
00:58:41 76270 8192000000 10265427408 10723749888 1323642496 13.910 96 15 30 0 1338703568 11189600 3871472
01:00:58 58519 8192000000 10245737040 10699173888 1330273296 13.970 97 12 22 0 1341704640 8917520 2513824
01:02:56 110885 8192000000 10247831184 10710843392 1301693632 13.711 95 20 40 0 1317209136 11070736 4444768
01:05:55 46299 8192000000 10270296688 10725470208 1371064416 14.337 100 13 26 0 1381850176 8099824 2685936
01:07:43 40667 8192000000 10290659568 10736951296 1364024240 14.273 98 30 60 1 1378649472 7428064 7197168
01:09:26 87209 8192000000 10314142480 10758340608 1377421056 14.393 98 14 28 1 1388070464 7896784 2752624
01:11:54 27322 8192000000 10293491472 10730205184 1349261216 14.141 97 43 85 1 1368450208 10349968 8839024
01:13:25 26697 8192000000 10301560416 10744377344 1380760832 14.423 99 41 82 0 1400101728 10753920 8586976
01:14:58 49541 8192000000 10295432688 10756710400 1386792368 14.477 99 26 51 0 1403085568 10768784 5524416
01:16:50 107114 8192000000 10309066944 10776899584 1389874816 14.505 99 3 6 0 1406510240 16050192 585232
01:19:37 66530 8192000000 10314624304 10758475776 1359129280 14.230 97 14 28 0 1372309616 10427504 2752832
01:23:26 80563 8192000000 10280775360 10747564032 1384125408 14.453 100 13 26 0 1401287856 14306464 2855984
01:26:03 81287 8192000000 10302028784 10768068608 1403894752 14.630 100 13 26 0 1421573200 14492000 3186448
01:28:28 47542 8192000000 10291794416 10746175488 1348369120 14.133 96 16 33 0 1362588128 10401616 3817392
01:30:16 76382 8192000000 10285699712 10751803392 1381600608 14.431 99 29 58 0 1401159920 13012128 6547184
01:32:39 82279 8192000000 10288274160 10756972544 1361030240 14.247 98 11 22 0 1384288368 20718160 2539968
01:35:01 16610 8192000000 10253005360 10698772480 1317591936 13.855 96 26 51 0 1329390432 6297248 5501248
01:36:25 83360 8192000000 10261120400 10714218496 1331105808 13.977 97 22 44 0 1350197056 14116944 4974304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-12-2022 01:39:52 to 30-12-2022 01:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-12-2022 01:40:08 to 30-12-2022 01:40:10.

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          238860
MON$OLDEST_ACTIVE               239605
MON$OLDEST_SNAPSHOT             239605
MON$NEXT_TRANSACTION            239605
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                       2021248
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1403: 
:1404: mscs-test-161.budzdorov.ru    Fri Dec 30 01:38:40 2022
:1405:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1406: 
:1407: 
:1408: mscs-test-161.budzdorov.ru    Fri Dec 30 01:38:45 2022
:1409:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1410: 
--- end of diff output ---

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

30.12.22 01:40:11. 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-12-30 01:40:14 No severe PSQL-related problems occured
30.12.22 01:40:15. End of report.