Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.01.2023 19:40
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 15605400 kB
MemAvailable 30442708 kB
Buffers 0 kB
Cached 16143284 kB
SwapCached 4416 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 2023-01-10 19:38:36
DTS_END                         2023-01-10 19:38:36.3830
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/41678
TRN_ID                          235078
ATT_ID                          679
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: 9024.34 17444 1082921
customer order: creation 170.50 352 20460
customer order: refuse 14.26 381 1711
order to supplier: creation 45.03 1216 5404
order to supplier: removal 3.36 95916 403
invoice (draft): creation 44.75 3151 5370
invoice (draft): removal 49.79 763 5975
invoice accept: apply 41.57 18093 4988
invoice accept: cancel 44.84 779 5381
customer reserve: creation 3859.25 180 463110
customer reserve: removal 4457.56 57 534907
realization accept: apply 83.12 190 9974
realization accept: cancel 82.90 102 9948
payment from customer: creation 57.80 113 6936
payment from customer: removal 5.75 80 690
payment to supplier: creation 48.15 127 5778
payment to supplier: removal 3.53 64 424
service: total inventory turnovers 4.68 1182 561
service: total monetary turnovers 6.56 24 787
service: aggregate perf. data 0.92 20060 110
service: refresh index statistics 0.03 206044 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-01-2023 19:41:16 to 10-01-2023 19:41:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3670
TEST_TIME 2 3632
TEST_TIME 3 3941
TEST_TIME 4 4072
TEST_TIME 5 3878
TEST_TIME 6 4107
TEST_TIME 7 4949
TEST_TIME 8 5138
TEST_TIME 9 5341
TEST_TIME 10 5596
TEST_TIME 11 5771
TEST_TIME 12 5662
TEST_TIME 13 5977
TEST_TIME 14 6284
TEST_TIME 15 6687
TEST_TIME 16 6838
TEST_TIME 17 7326
TEST_TIME 18 7635
TEST_TIME 19 8091
TEST_TIME 20 8491
TEST_TIME 21 8468
TEST_TIME 22 8577
TEST_TIME 23 8855
TEST_TIME 24 8817
TEST_TIME 25 9210
TEST_TIME 26 9395
TEST_TIME 27 9646
TEST_TIME 28 10104
TEST_TIME 29 10069
TEST_TIME 30 10421
TEST_TIME 31 10310
TEST_TIME 32 10287
TEST_TIME 33 10013
TEST_TIME 34 9823
TEST_TIME 35 9768
TEST_TIME 36 9678
TEST_TIME 37 9583
TEST_TIME 38 9568
TEST_TIME 39 9441
TEST_TIME 40 9329
TEST_TIME 41 9398
TEST_TIME 42 9314
TEST_TIME 43 9212
TEST_TIME 44 9210
TEST_TIME 45 9177
TEST_TIME 46 9125
TEST_TIME 47 9010
TEST_TIME 48 8914
TEST_TIME 49 8888
TEST_TIME 50 8990
TEST_TIME 51 8966
TEST_TIME 52 8948
TEST_TIME 53 8914
TEST_TIME 54 9050
TEST_TIME 55 9229
TEST_TIME 56 9203
TEST_TIME 57 9223
TEST_TIME 58 9150
TEST_TIME 59 9222
TEST_TIME 60 9224
TEST_TIME 61 9287
TEST_TIME 62 9267
TEST_TIME 63 9296
TEST_TIME 64 9290
TEST_TIME 65 9280
TEST_TIME 66 9315
TEST_TIME 67 9407
TEST_TIME 68 9369
TEST_TIME 69 9318
TEST_TIME 70 9270
TEST_TIME 71 9262
TEST_TIME 72 9203
TEST_TIME 73 9202
TEST_TIME 74 9208
TEST_TIME 75 9184
TEST_TIME 76 9095
TEST_TIME 77 9027
TEST_TIME 78 9009
TEST_TIME 79 9091
TEST_TIME 80 9073
TEST_TIME 81 9100
TEST_TIME 82 9129
TEST_TIME 83 9102
TEST_TIME 84 9117
TEST_TIME 85 9185
TEST_TIME 86 9198
TEST_TIME 87 9177
TEST_TIME 88 9165
TEST_TIME 89 9270
TEST_TIME 90 9265
TEST_TIME 91 9290
TEST_TIME 92 9311
TEST_TIME 93 9223
TEST_TIME 94 9183
TEST_TIME 95 9185
TEST_TIME 96 9163
TEST_TIME 97 9115
TEST_TIME 98 9066
TEST_TIME 99 9038
TEST_TIME 100 9016
TEST_TIME 101 9078
TEST_TIME 102 9088
TEST_TIME 103 9086
TEST_TIME 104 9080
TEST_TIME 105 9082
TEST_TIME 106 9084
TEST_TIME 107 9072
TEST_TIME 108 9138
TEST_TIME 109 9167
TEST_TIME 110 9176
TEST_TIME 111 9159
TEST_TIME 112 9233
TEST_TIME 113 9232
TEST_TIME 114 9253
TEST_TIME 115 9181
TEST_TIME 116 9168
TEST_TIME 117 9134
TEST_TIME 118 9125
TEST_TIME 119 9083
TEST_TIME 120 9028

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-01-2023 19:41:20 to 10-01-2023 19:41:23.

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 1510642 1510642 0 0.00 0 27080 7 0 0
doc_list_biud 1510642 1510642 0 0.00 0 26658 26 0 0
sp_add_invoice_to_stock 5102 4988 114 2.23 49 177937 18093 0 114
sp_cancel_adding_invoice 5381 5381 0 0.00 3 70882 780 0 0
sp_cancel_client_order 1711 1711 0 0.00 4 10037 382 0 0
sp_cancel_customer_reserve 534907 534907 0 0.00 0 26662 57 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 2 4236 80 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 2328 65 0 0
sp_cancel_supplier_invoice 5982 5975 7 0.11 2 19333 763 0 7
sp_cancel_supplier_order 403 403 0 0.00 7 910691 95916 0 0
sp_cancel_write_off 9948 9948 0 0.00 1 11895 103 0 0
sp_client_order 20460 20460 0 0.00 3 29801 353 0 0
sp_customer_reserve 463110 463110 0 0.00 0 43690 180 0 0
sp_fill_shopping_cart 31223 31223 0 0.00 1 10549 37 0 0
sp_fill_shopping_cart_clo_res 463107 463107 0 0.00 0 17546 14 0 0
sp_get_clo_for_invoice 4983 4983 0 0.00 0 31069 315 0 0
sp_kill_cost_storno 16443 16443 0 0.00 0 19980 55 0 0
sp_kill_qstorno_ret_qs2qd 1086140 1086140 0 0.00 0 26649 31 0 0
sp_kill_qty_storno 573282 573282 0 0.00 0 26658 67 0 0
sp_lock_selected_doc 20564 20564 0 0.00 0 7463 6 0 0
sp_make_cost_storno 27671 27671 0 0.00 0 27072 53 0 0
sp_multiply_rows_for_pdistr 27671 27671 0 0.00 0 18497 31 0 0
sp_multiply_rows_for_qdistr 20460 20460 0 0.00 0 17884 148 0 0
sp_pay_from_customer 6936 6936 0 0.00 4 14896 114 0 0
sp_pay_to_supplier 5778 5778 0 0.00 4 19557 127 0 0
sp_payment_common 12714 12714 0 0.00 2 17526 29 0 0
sp_qd_handle_on_cancel_clo 1711 1711 0 0.00 0 10029 349 0 0
sp_qd_handle_on_invoice_upd_sts 10364 10364 0 0.00 0 20914 321 0 0
sp_qd_handle_on_reserve_upd_sts 9974 9974 0 0.00 0 15214 67 0 0
sp_reserve_write_off 9974 9974 0 0.00 5 17992 190 0 0
sp_supplier_invoice 5370 5370 0 0.00 6 69512 3152 0 0
sp_supplier_order 5404 5404 0 0.00 4 29542 1216 0 0
srv_aggregate_perf_data 110 110 0 0.00 2780 346687 20060 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1931 138020 43652 0 0
srv_find_qd_qs_mism 1035054 1035054 0 0.00 0 16179 6 0 0
srv_make_invnt_saldo 561 561 0 0.00 43 75001 1182 0 0
srv_make_money_saldo 787 787 0 0.00 1 1427 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 103518 419870 206045 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 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4989 4989 0 0.00 0 13604 188 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 3308 93 0 0
t$perf-norm:sp_cancel_client_order 1711 1711 0 0.00 0 5712 24 0 0
t$perf-norm:sp_cancel_customer_reserve 633 633 0 0.00 0 13644 59 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 2346 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 662 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 3535 30 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 36220 1822 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 2328 11 0 0
t$perf-norm:sp_client_order 20460 20460 0 0.00 0 8879 18 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 2868 10 0 0
t$perf-norm:sp_pay_from_customer 6936 6936 0 0.00 0 12152 12 0 0
t$perf-norm:sp_pay_to_supplier 5778 5778 0 0.00 0 12076 13 0 0
t$perf-norm:sp_reserve_write_off 9974 9974 0 0.00 0 8122 15 0 0
t$perf-norm:sp_supplier_invoice 5370 5370 0 0.00 0 14102 21 0 0
t$perf-norm:sp_supplier_order 5404 5404 0 0.00 0 7454 16 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 366 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 737 16 0 0
t$perf-norm:srv_make_invnt_saldo 561 561 0 0.00 0 3203 32 0 0
t$perf-norm:srv_make_money_saldo 787 787 0 0.00 0 6000 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 5 0 0
v_add_invoice_to_stock 10194 9966 228 2.23 0 10906 21 0 228
v_all_customers 37188 37188 0 0.00 0 22 1 0 0
v_all_suppliers 30100 30100 0 0.00 0 22 1 0 0
v_all_wares 40920 40920 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 1374 9 0 0
v_cancel_client_order 3422 3422 0 0.00 0 3935 7 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 4225 10 0 0
v_cancel_customer_reserve 1266 1266 0 0.00 0 2138 6 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 2319 12 0 0
v_cancel_supplier_invoice 798 784 14 1.75 0 746 14 0 14
v_cancel_supplier_order 806 806 0 0.00 0 4124 10 0 0
v_cancel_write_off 1942 1942 0 0.00 0 5625 15 0 0
v_max_id_clo_ord 5404 5404 0 0.00 0 1086 2 0 0
v_max_id_clo_res 991 991 0 0.00 0 6671 21 0 0
v_max_id_ord_sup 5359 5359 0 0.00 0 8525 8 0 0
v_max_non_paid_invoice 1491 1491 0 0.00 0 310 3 0 0
v_max_non_paid_realizn 3621 3621 0 0.00 0 2310 5 0 0
v_min_id_clo_ord 5404 5404 0 0.00 0 4273 9 0 0
v_min_id_clo_res 991 991 0 0.00 0 4828 15 0 0
v_min_id_ord_sup 5359 5359 0 0.00 0 4379 5 0 0
v_min_non_paid_invoice 5778 5778 0 0.00 0 17510 21 0 0
v_min_non_paid_realizn 6936 6936 0 0.00 0 14760 21 0 0
v_our_firm 10362 10362 0 0.00 0 35 1 0 0
v_reserve_write_off 19948 19948 0 0.00 0 7088 8 0 0
x_lock_depdocs_on_canc_invoice 10964 10964 0 0.00 0 14426 112 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 4124 289 0 0
x_make_qstorno_1000_1200 5404 5404 0 0.00 1 29514 1138 0 0
x_make_qstorno_1200_2000 5370 5370 0 0.00 3 69500 3020 0 0
x_make_qstorno_2100_3300 463110 463110 0 0.00 0 39902 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-01-2023 19:41:24 to 10-01-2023 19:41:28.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:40 7 8192000000 8810376288 8825511936 14906912 0.181 4 4 5 0 15133648 87456 139280
17:09:41 32940 8192000000 9892237024 10199621632 976793712 10.653 100 92 182 0 1006515360 5602224 24119424
17:11:19 30189 8192000000 9944821744 10263109632 1026357104 11.133 100 88 176 0 1056636112 7417232 22861776
17:12:55 15138 8192000000 9972041408 10303565824 1064091664 11.496 100 83 166 0 1093772848 9168768 20512416
17:14:18 7981 8192000000 10019429776 10365825024 1111711584 11.949 100 74 148 0 1138973520 10094800 17167136
17:15:28 4761 8192000000 10085675200 10440945664 1171766224 12.513 100 72 144 0 1199283072 11690912 15825936
17:16:35 3532 8192000000 10121460800 10488205312 1211271280 12.881 100 83 164 0 1242958784 13376400 18311104
17:17:41 3052 8192000000 10155052928 10529742848 1247999584 13.220 100 83 162 1 1279006288 13526992 17479712
17:18:45 3472 8192000000 10175283568 10560098304 1271700032 13.437 100 76 147 0 1299456848 12294784 15462032
17:19:52 1707 8192000000 10209452000 10592669696 1292170192 13.624 100 88 175 1 1326323248 14856640 19296416
17:20:56 2970 8192000000 10210824448 10601025536 1307378640 13.762 100 77 154 1 1337240656 12068032 17793984
17:22:02 15955 8192000000 10212501520 10619338752 1315708272 13.838 100 52 102 1 1336469968 8814672 11947024
17:23:20 5126 8192000000 10207874400 10618859520 1319124016 13.869 100 35 69 0 1334302896 7561296 7617584
17:24:26 14663 8192000000 10222905952 10639040512 1334030016 14.004 100 24 48 0 1346557168 7317920 5209232
17:25:41 11330 8192000000 10233676864 10648305664 1339853104 14.056 100 40 80 0 1355929936 6860480 9216352
17:26:57 20486 8192000000 10235037872 10659020800 1344574976 14.099 100 24 46 0 1355359856 5372080 5412800
17:28:19 5768 8192000000 10239375520 10656071680 1341500608 14.071 100 31 62 1 1354310208 5803184 7006416
17:29:25 6671 8192000000 10224141680 10643836928 1324922816 13.921 99 22 42 1 1335182384 5534432 4725136
17:30:36 14407 8192000000 10177886464 10575122432 1208866464 12.859 93 25 46 0 1220566736 4489168 7211104
17:31:51 9322 8192000000 10159733632 10570977280 1228496992 13.040 96 14 28 0 1237258592 5582720 3178880
17:33:03 7939 8192000000 10132414448 10532597760 1232519616 13.077 99 25 50 1 1243829072 5683152 5626304
17:34:14 5464 8192000000 10162013696 10559922176 1262527984 13.353 100 40 81 0 1279674544 8102608 9043952
17:35:21 24535 8192000000 10156329680 10570383360 1265062960 13.376 99 27 50 2 1276962624 5403168 6496496
17:37:18 7154 8192000000 10187916320 10598928384 1295240128 13.652 100 32 63 0 1308570800 5792368 7538304
17:38:29 18287 8192000000 10217234208 10630832128 1322731888 13.901 100 17 31 0 1330336400 4352336 3252176
17:39:54 15986 8192000000 10250414256 10656894976 1340266016 14.060 100 50 99 0 1360169904 8378944 11524944
17:41:17 67839 8192000000 10276535168 10697195520 1360566064 14.242 100 47 95 0 1381095840 9077536 11452240
17:43:28 27158 8192000000 10279009728 10700095488 1370020720 14.327 100 35 69 0 1391316480 13534224 7761536
17:44:59 5715 8192000000 10295965184 10718015488 1380558512 14.422 100 45 88 1 1407780688 16848224 10373952
17:46:33 5221 8192000000 10323308304 10737938432 1394540208 14.546 100 67 131 1 1426137776 17143456 14454112
17:47:43 13432 8192000000 10325283120 10750939136 1403588944 14.627 100 45 91 0 1435784560 21254368 10941248
17:49:00 6320 8192000000 10331305152 10758389760 1408589216 14.671 100 52 104 0 1443376128 22174848 12612064
17:50:14 6308 8192000000 10361237744 10784649216 1425106560 14.818 100 65 130 2 1469560992 29076752 15377680
17:51:30 4433 8192000000 10357466080 10784092160 1421900528 14.790 99 57 112 0 1464266144 28028832 14336784
17:52:41 1929 8192000000 10382171408 10807873536 1437390832 14.927 100 70 139 0 1491352496 35598768 18362896
17:53:51 2065 8192000000 10372495888 10787700736 1417812864 14.753 100 83 166 0 1472429504 33792976 20823664
17:54:59 2954 8192000000 10352730352 10765221888 1390197568 14.508 99 76 149 0 1449478304 41115392 18165344
17:56:07 4548 8192000000 10337112016 10758328320 1377332064 14.393 99 45 90 1 1433452448 44371072 11749312
17:57:18 2089 8192000000 10335520704 10751430656 1382758256 14.441 100 78 156 2 1441658960 38428288 20472416
17:58:29 5843 8192000000 10320072256 10736107520 1373744752 14.361 99 55 108 0 1422519872 34833312 13941808
17:59:40 5339 8192000000 10318319968 10740187136 1381411328 14.429 100 49 98 1 1426915024 31651120 13852576
18:00:50 17437 8192000000 10306316928 10735718400 1357342464 14.213 98 30 60 0 1398558976 33000320 8216192
18:02:12 12922 8192000000 10310371456 10732879872 1354565344 14.189 98 39 75 2 1396679312 31948256 10165712
18:03:34 28219 8192000000 10294470400 10720481280 1333510256 13.999 97 27 54 0 1379237216 38153040 7573920
18:05:21 32421 8192000000 10301942928 10734538752 1343746064 14.091 99 21 42 1 1400244192 49980144 6517984
18:07:03 9155 8192000000 10281036960 10705285120 1336341184 14.024 99 38 76 0 1372060992 26404992 9314816
18:08:13 16728 8192000000 10288650736 10720395264 1339704336 14.055 98 46 91 0 1383354704 31882224 11768144
18:09:42 28179 8192000000 10261257296 10689826816 1347970480 14.129 99 43 85 1 1366216960 8719024 9527456
18:11:18 100277 8192000000 10265995808 10705448960 1343600992 14.090 98 29 58 0 1357531744 7248528 6682224
18:14:00 51041 8192000000 10256203472 10704711680 1339699312 14.055 98 17 32 0 1350289712 6998512 3591888
18:15:51 79169 8192000000 10269688288 10718625792 1365616912 14.288 100 22 44 0 1379856672 9159664 5080096
18:18:12 37033 8192000000 10294410816 10731765760 1379828384 14.415 100 48 94 1 1403847200 12590112 11428704
18:19:49 55654 8192000000 10290372560 10740310016 1368505872 14.314 99 11 22 0 1385553648 14847568 2200208
18:21:46 30644 8192000000 10291770080 10741575680 1390900896 14.514 100 21 42 0 1406884608 11020096 4963616
18:23:26 87456 8192000000 10295706656 10758082560 1390486336 14.510 100 20 40 0 1404992176 9487808 5018032
18:25:55 17001 8192000000 10311582272 10763010048 1406459840 14.652 100 24 48 0 1428062768 15861968 5740960
18:27:13 75256 8192000000 10322765584 10790522880 1417539680 14.751 100 22 44 0 1448799456 25728576 5531200
18:29:41 48021 8192000000 10313029568 10778255360 1409634096 14.681 100 17 33 0 1437185680 23630928 3920656
18:31:33 37979 8192000000 10321084128 10777862144 1411315168 14.696 100 31 63 0 1441124480 21006688 8802624
18:33:21 111196 8192000000 10305720048 10769715200 1364189824 14.275 97 7 14 0 1381260480 15530144 1540512
18:36:22 24834 8192000000 10309404592 10761490432 1387372976 14.482 98 26 50 1 1400704464 7890736 5440752
18:37:48 63388 8192000000 10300320192 10744762368 1337982656 14.039 95 23 47 1 1354323968 10732800 5608512
18:39:52 30618 8192000000 10300752016 10744115200 1374985280 14.372 99 26 52 1 1390018208 9914768 5118160
18:41:26 36911 8192000000 10282910928 10730586112 1356425232 14.205 98 27 54 1 1373146032 11048016 5672784
18:43:03 13537 8192000000 10307309872 10738376704 1380254080 14.419 100 47 94 1 1401330240 10510448 10565712
18:44:20 10799 8192000000 10318643968 10755055616 1394907632 14.550 100 44 89 0 1416809136 12450608 9450896
18:45:36 123071 8192000000 10279893984 10737770496 1325014672 13.922 95 10 20 0 1341296544 14202352 2079520
18:48:58 43734 8192000000 10298214400 10740662272 1354439936 14.187 97 26 53 0 1368832704 8654816 5737952
18:50:46 22667 8192000000 10274628720 10718208000 1343019104 14.085 97 40 78 0 1363375952 11127424 9229424
18:52:52 88687 8192000000 10252333168 10710740992 1291660624 13.619 95 11 22 0 1315716976 21495584 2560768
18:55:20 78235 8192000000 10243899504 10700468224 1340514784 14.062 100 15 30 0 1362068416 17546048 4007584
18:57:48 50536 8192000000 10231783744 10684739584 1305300704 13.743 97 19 37 1 1321338944 11335024 4703216
18:59:44 57345 8192000000 10259510144 10711912448 1342452288 14.080 99 15 30 1 1355128304 9401296 3274720
19:01:45 20883 8192000000 10274946400 10717163520 1355304080 14.195 99 26 52 0 1369967504 8952768 5710656
19:03:07 110950 8192000000 10246925472 10698039296 1267215152 13.396 93 12 22 1 1278597632 9179888 2202592
19:06:04 138020 8192000000 10259009504 10715004928 1289683824 13.601 95 18 36 0 1310016320 16023168 4309328
19:09:47 95666 8192000000 10273576096 10715852800 1323039456 13.904 96 24 48 0 1342982048 15033792 4908800
19:14:17 73086 8192000000 10284768064 10747785216 1384661584 14.458 100 9 18 0 1395473456 8648352 2163520
19:16:30 52969 8192000000 10305257440 10764775424 1399524240 14.591 100 17 34 0 1418764432 15225584 4014608
19:18:25 81097 8192000000 10295239584 10768297984 1380866800 14.424 99 5 10 0 1400925984 18841456 1217728
19:20:47 45715 8192000000 10296566608 10755375104 1382073120 14.435 99 34 63 1 1402914048 14123488 6717440
19:22:34 35972 8192000000 10295636640 10745970688 1388166288 14.490 99 23 44 0 1401837808 8699808 4971712
19:24:17 59164 8192000000 10306936160 10770071552 1403644320 14.627 100 16 32 0 1418268992 10900272 3724400
19:26:16 129647 8192000000 10299020272 10767798272 1330561232 13.972 94 16 32 0 1344804112 10336560 3906320
19:29:28 43312 8192000000 10278006944 10736058368 1308974320 13.777 94 15 29 1 1329137744 16598224 3565200
19:31:15 32536 8192000000 10288122400 10719043584 1357650672 14.216 98 42 84 0 1380059904 12344064 10065168
19:32:52 91121 8192000000 10298727264 10742857728 1379444240 14.412 100 20 40 1 1393522256 9813904 4264112
19:35:33 19693 8192000000 10285248864 10736816128 1359279616 14.231 98 19 38 0 1372998144 9322576 4395952
19:36:53 91803 8192000000 10293152432 10752651264 1383131568 14.445 99 10 20 0 1397263904 11966752 2165584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-01-2023 19:41:30 to 10-01-2023 19:41:45.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-01-2023 19:41:46 to 10-01-2023 19:41:48.

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          232609
MON$OLDEST_ACTIVE               235286
MON$OLDEST_SNAPSHOT             235286
MON$NEXT_TRANSACTION            235286
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                       2002496
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.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 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 ---
:287: 
:288: mscs-test-161.budzdorov.ru    Tue Jan 10 19:38:53 2023
:289:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:290: 
:291: 
:292: mscs-test-161.budzdorov.ru    Tue Jan 10 19:38:54 2023
:293:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:294: 
:295: 
:296: mscs-test-161.budzdorov.ru    Tue Jan 10 19:39:05 2023
:297:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:298: 
:299: 
:300: mscs-test-161.budzdorov.ru    Tue Jan 10 19:39:06 2023
:301:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:302: 
--- end of diff output ---

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

10.01.23 19:41:50. 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
2023-01-10 19:41:52 No severe PSQL-related problems occured
10.01.23 19:41:53. End of report.