Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.06.2022 19:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16354748 kB
MemAvailable 31148896 kB
Buffers 0 kB
Cached 15332036 kB
SwapCached 2492 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-06-30 19:38:24
DTS_END                         2022-06-30 19:38:24.3530
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/46024
TRN_ID                          239928
ATT_ID                          705
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: 9111.35 22244 1093362
customer order: creation 169.77 345 20372
customer order: refuse 14.81 317 1777
order to supplier: creation 45.61 1295 5473
order to supplier: removal 3.16 102704 379
invoice (draft): creation 45.78 3304 5494
invoice (draft): removal 50.28 781 6033
invoice accept: apply 41.53 15919 4983
invoice accept: cancel 45.67 707 5480
customer reserve: creation 3853.05 156 462366
customer reserve: removal 4547.45 55 545694
realization accept: apply 82.36 181 9883
realization accept: cancel 83.56 119 10027
payment from customer: creation 58.62 129 7034
payment from customer: removal 5.73 79 688
payment to supplier: creation 49.11 117 5893
payment to supplier: removal 3.30 47 396
service: total inventory turnovers 4.71 1118 565
service: total monetary turnovers 5.99 31 719
service: aggregate perf. data 0.85 18837 102
service: refresh index statistics 0.03 298636 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-06-2022 19:39:01 to 30-06-2022 19:39:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6780
TEST_TIME 2 5813
TEST_TIME 3 5000
TEST_TIME 4 4533
TEST_TIME 5 4607
TEST_TIME 6 4878
TEST_TIME 7 5088
TEST_TIME 8 5533
TEST_TIME 9 5853
TEST_TIME 10 6305
TEST_TIME 11 6365
TEST_TIME 12 6644
TEST_TIME 13 6741
TEST_TIME 14 6929
TEST_TIME 15 6863
TEST_TIME 16 6884
TEST_TIME 17 7121
TEST_TIME 18 7471
TEST_TIME 19 7618
TEST_TIME 20 8150
TEST_TIME 21 8130
TEST_TIME 22 8628
TEST_TIME 23 9555
TEST_TIME 24 9524
TEST_TIME 25 10273
TEST_TIME 26 10105
TEST_TIME 27 10750
TEST_TIME 28 10551
TEST_TIME 29 10593
TEST_TIME 30 10322
TEST_TIME 31 10290
TEST_TIME 32 10165
TEST_TIME 33 10089
TEST_TIME 34 10059
TEST_TIME 35 9965
TEST_TIME 36 9932
TEST_TIME 37 9754
TEST_TIME 38 9669
TEST_TIME 39 9591
TEST_TIME 40 9562
TEST_TIME 41 9553
TEST_TIME 42 9448
TEST_TIME 43 9523
TEST_TIME 44 9464
TEST_TIME 45 9575
TEST_TIME 46 9444
TEST_TIME 47 9450
TEST_TIME 48 9481
TEST_TIME 49 9384
TEST_TIME 50 9295
TEST_TIME 51 9191
TEST_TIME 52 9073
TEST_TIME 53 9113
TEST_TIME 54 9032
TEST_TIME 55 9024
TEST_TIME 56 8933
TEST_TIME 57 8995
TEST_TIME 58 8981
TEST_TIME 59 8989
TEST_TIME 60 8989
TEST_TIME 61 8976
TEST_TIME 62 9040
TEST_TIME 63 8994
TEST_TIME 64 9036
TEST_TIME 65 9082
TEST_TIME 66 9096
TEST_TIME 67 9158
TEST_TIME 68 9128
TEST_TIME 69 9206
TEST_TIME 70 9231
TEST_TIME 71 9185
TEST_TIME 72 9270
TEST_TIME 73 9218
TEST_TIME 74 9101
TEST_TIME 75 9125
TEST_TIME 76 9100
TEST_TIME 77 9066
TEST_TIME 78 9051
TEST_TIME 79 9013
TEST_TIME 80 8986
TEST_TIME 81 8927
TEST_TIME 82 8838
TEST_TIME 83 8909
TEST_TIME 84 8895
TEST_TIME 85 8933
TEST_TIME 86 8914
TEST_TIME 87 8942
TEST_TIME 88 9020
TEST_TIME 89 9055
TEST_TIME 90 9076
TEST_TIME 91 9084
TEST_TIME 92 9122
TEST_TIME 93 9112
TEST_TIME 94 9177
TEST_TIME 95 9260
TEST_TIME 96 9304
TEST_TIME 97 9380
TEST_TIME 98 9296
TEST_TIME 99 9250
TEST_TIME 100 9267
TEST_TIME 101 9222
TEST_TIME 102 9178
TEST_TIME 103 9120
TEST_TIME 104 9118
TEST_TIME 105 9088
TEST_TIME 106 9057
TEST_TIME 107 9005
TEST_TIME 108 8987
TEST_TIME 109 9006
TEST_TIME 110 8998
TEST_TIME 111 9025
TEST_TIME 112 9018
TEST_TIME 113 9035
TEST_TIME 114 9068
TEST_TIME 115 9069
TEST_TIME 116 9076
TEST_TIME 117 9142
TEST_TIME 118 9117
TEST_TIME 119 9163
TEST_TIME 120 9117

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-06-2022 19:39:05 to 30-06-2022 19:39:08.

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 1521083 1521083 0 0.00 0 19988 6 0 0
doc_list_biud 1521083 1521083 0 0.00 0 25498 25 0 0
sp_add_invoice_to_stock 5136 4983 153 2.97 35 155871 15919 0 153
sp_cancel_adding_invoice 5480 5480 0 0.00 4 63506 708 0 0
sp_cancel_client_order 1777 1777 0 0.00 4 9571 317 0 0
sp_cancel_customer_reserve 545694 545694 0 0.00 0 25501 56 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 2 8643 80 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 1784 47 0 0
sp_cancel_supplier_invoice 6048 6033 15 0.24 2 18180 781 0 15
sp_cancel_supplier_order 379 379 0 0.00 7 1085013 102704 0 0
sp_cancel_write_off 10027 10027 0 0.00 1 12139 119 0 0
sp_client_order 20372 20372 0 0.00 3 28552 345 0 0
sp_customer_reserve 462366 462366 0 0.00 0 27901 156 0 0
sp_fill_shopping_cart 31336 31336 0 0.00 1 24517 42 0 0
sp_fill_shopping_cart_clo_res 462355 462355 0 0.00 0 13030 14 0 0
sp_get_clo_for_invoice 4969 4969 0 0.00 0 25406 348 0 0
sp_kill_cost_storno 16591 16591 0 0.00 0 11170 55 0 0
sp_kill_qstorno_ret_qs2qd 1107827 1107827 0 0.00 0 19537 30 0 0
sp_kill_qty_storno 584242 584242 0 0.00 0 25497 66 0 0
sp_lock_selected_doc 20588 20588 0 0.00 0 5911 4 0 0
sp_make_cost_storno 27779 27779 0 0.00 0 17583 52 0 0
sp_multiply_rows_for_pdistr 27779 27779 0 0.00 0 7414 27 0 0
sp_multiply_rows_for_qdistr 20372 20372 0 0.00 0 28517 153 0 0
sp_pay_from_customer 7034 7034 0 0.00 4 17051 130 0 0
sp_pay_to_supplier 5893 5893 0 0.00 4 11708 117 0 0
sp_payment_common 12927 12927 0 0.00 1 16963 32 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 9561 288 0 0
sp_qd_handle_on_invoice_upd_sts 10449 10449 0 0.00 0 18128 306 0 0
sp_qd_handle_on_reserve_upd_sts 9883 9883 0 0.00 0 18504 67 0 0
sp_reserve_write_off 9883 9883 0 0.00 6 18528 181 0 0
sp_supplier_invoice 5494 5494 0 0.00 6 70465 3304 0 0
sp_supplier_order 5473 5473 0 0.00 5 42523 1296 0 0
srv_aggregate_perf_data 102 102 0 0.00 2334 244781 18838 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2557 174893 49930 0 0
srv_find_qd_qs_mism 1045660 1045660 0 0.00 0 19986 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 34 88284 1118 0 0
srv_make_money_saldo 719 719 0 0.00 0 3992 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 134891 443680 298637 0 0
t$perf-abend:sp_add_invoice_to_stock 153 153 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4983 4983 0 0.00 0 11714 169 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 7527 176 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 3131 15 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 1139 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 1371 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 735 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 3809 26 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 17918 1667 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 740 5 0 0
t$perf-norm:sp_client_order 20372 20372 0 0.00 0 8405 17 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 3293 6 0 0
t$perf-norm:sp_pay_from_customer 7034 7034 0 0.00 0 6162 11 0 0
t$perf-norm:sp_pay_to_supplier 5893 5893 0 0.00 0 4465 11 0 0
t$perf-norm:sp_reserve_write_off 9883 9883 0 0.00 0 6304 14 0 0
t$perf-norm:sp_supplier_invoice 5494 5494 0 0.00 0 5830 17 0 0
t$perf-norm:sp_supplier_order 5473 5473 0 0.00 0 5816 13 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 116 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 933 30 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 1966 26 0 0
t$perf-norm:srv_make_money_saldo 719 719 0 0.00 0 814 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 10234 9928 306 2.99 0 10280 19 0 306
v_all_customers 37312 37312 0 0.00 0 125 0 0 0
v_all_suppliers 30366 30366 0 0.00 0 37 1 0 0
v_all_wares 40744 40744 0 0.00 0 220 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 697 5 0 0
v_cancel_client_order 3554 3554 0 0.00 0 5726 9 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 8471 14 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 2599 7 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 491 4 0 0
v_cancel_supplier_invoice 874 844 30 3.43 0 10339 28 0 30
v_cancel_supplier_order 758 758 0 0.00 0 1672 7 0 0
v_cancel_write_off 2012 2012 0 0.00 0 3328 20 0 0
v_max_id_clo_ord 5473 5473 0 0.00 0 377 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 1584 10 0 0
v_max_id_ord_sup 5491 5491 0 0.00 0 5319 5 0 0
v_max_non_paid_invoice 1674 1674 0 0.00 0 1305 3 0 0
v_max_non_paid_realizn 3644 3644 0 0.00 0 3461 5 0 0
v_min_id_clo_ord 5473 5473 0 0.00 0 3073 5 0 0
v_min_id_clo_res 992 992 0 0.00 0 3018 10 0 0
v_min_id_ord_sup 5491 5491 0 0.00 0 4011 6 0 0
v_min_non_paid_invoice 5893 5893 0 0.00 0 6138 18 0 0
v_min_non_paid_realizn 7034 7034 0 0.00 0 16959 29 0 0
v_our_firm 10212 10212 0 0.00 0 19 1 0 0
v_reserve_write_off 19766 19766 0 0.00 0 9947 9 0 0
x_lock_depdocs_on_canc_invoice 11091 11091 0 0.00 0 18012 116 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 10718 461 0 0
x_make_qstorno_1000_1200 5473 5473 0 0.00 1 41947 1228 0 0
x_make_qstorno_1200_2000 5494 5494 0 0.00 2 70457 3138 0 0
x_make_qstorno_2100_3300 462366 462366 0 0.00 0 27899 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-06-2022 19:39:09 to 30-06-2022 19:39:13.

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:28 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:28 28375 8192000000 9913110512 10221334528 986355872 10.746 100 94 188 0 1016954864 6058448 24540544
17:11:07 19775 8192000000 9939326144 10258616320 1024830848 11.119 100 93 186 0 1057078944 8482464 23765632
17:12:31 14846 8192000000 9978853072 10309173248 1059890384 11.455 100 87 175 0 1091876256 10020448 21965424
17:13:50 7053 8192000000 10026399936 10373980160 1117085904 11.999 100 83 166 1 1148168752 11536032 19546816
17:15:02 5512 8192000000 10077634432 10435616768 1160119984 12.404 100 79 158 0 1191155568 12461936 18573648
17:16:13 3661 8192000000 10117318160 10483675136 1203161664 12.806 100 81 160 1 1234566928 13608016 17797248
17:17:19 4360 8192000000 10135711264 10515673088 1233034720 13.082 100 60 120 1 1258648672 11784352 13829600
17:18:26 1098 8192000000 10183258272 10564313088 1273588208 13.454 100 87 170 0 1306261808 13736576 18937024
17:19:29 13774 8192000000 10193111664 10577559552 1282057472 13.532 100 75 150 2 1310302928 11642240 16603216
17:20:44 6024 8192000000 10191511696 10592120832 1293845792 13.639 100 43 86 0 1313178192 9682272 9650128
17:21:54 8008 8192000000 10192653344 10603274240 1306764288 13.757 100 24 48 1 1318077328 5858224 5454816
17:23:04 8274 8192000000 10205840800 10615349248 1315020480 13.832 100 39 76 1 1329513424 5930816 8562128
17:24:13 9039 8192000000 10203964848 10625150976 1318098848 13.859 100 23 46 0 1330501456 6886640 5515968
17:25:24 6226 8192000000 10211545632 10631188480 1325202736 13.924 100 19 37 0 1335156016 5789456 4163824
17:26:31 6606 8192000000 10225501664 10643415040 1334049792 14.004 100 23 46 0 1345747856 6413568 5284496
17:27:41 68356 8192000000 10200670160 10624860160 1291120640 13.614 97 27 53 0 1302513072 5572352 5820080
17:29:52 5841 8192000000 10161208000 10566782976 1257544672 13.307 98 18 36 0 1267373040 5825888 4002480
17:30:59 8499 8192000000 10130140144 10520064000 1190304016 12.686 95 27 52 1 1201411968 4652848 6455104
17:32:08 62416 8192000000 10121380448 10522886144 1197446544 12.753 95 26 52 0 1208746192 5424816 5874832
17:34:12 5046 8192000000 10165227248 10571628544 1264400848 13.370 99 27 56 0 1277348240 6365408 6581984
17:35:18 8000 8192000000 10179638016 10587799552 1285140448 13.560 100 14 28 0 1297883472 9443616 3299408
17:36:30 15226 8192000000 10183811232 10602201088 1299640560 13.692 100 14 28 0 1308364640 5650944 3073136
17:37:46 1072 8192000000 10212517184 10612596736 1303957488 13.731 100 72 144 1 1327033168 6706592 16369088
17:38:50 10003 8192000000 10238571200 10650357760 1335615680 14.018 100 42 84 1 1351724480 6885120 9223680
17:40:05 15630 8192000000 10258316752 10672193536 1349534160 14.143 100 44 88 0 1368238704 8278896 10425648
17:41:25 26147 8192000000 10271069360 10693746688 1373457344 14.358 100 33 64 1 1391912864 10487360 7968160
17:43:03 26315 8192000000 10294771904 10717609984 1387570864 14.484 100 30 59 0 1406298736 11770016 6957856
17:44:43 8459 8192000000 10304828080 10728386560 1395467008 14.555 100 39 78 1 1421869568 17199568 9202992
17:46:01 11356 8192000000 10314099744 10742792192 1402548960 14.618 100 40 77 1 1433365968 20619008 10198000
17:47:18 2637 8192000000 10328527632 10751107072 1397376048 14.572 99 63 125 1 1432758784 20315088 15067648
17:48:27 14608 8192000000 10333812912 10757201920 1388463056 14.492 98 53 106 0 1428522384 26444432 13614896
17:49:47 8963 8192000000 10349715568 10775846912 1422425040 14.794 100 55 110 1 1464470560 28614672 13430848
17:51:03 2555 8192000000 10351883072 10771849216 1410792976 14.691 99 69 137 0 1456102752 28941904 16367872
17:52:11 5562 8192000000 10370116944 10795474944 1424732368 14.815 99 64 128 0 1475615088 35144816 15737904
17:53:20 13400 8192000000 10357725632 10779930624 1413028480 14.711 100 51 102 0 1464045584 37589120 13427984
17:54:41 3141 8192000000 10368255872 10789724160 1409269952 14.677 99 65 127 1 1467794016 42028480 16495584
17:55:51 4510 8192000000 10380371152 10799747072 1423151184 14.801 100 73 144 1 1488367184 47197056 18018944
17:57:03 19631 8192000000 10342297648 10769248256 1396761904 14.566 100 35 68 1 1451431200 44902592 9766704
17:58:30 3345 8192000000 10342169936 10759774208 1380013680 14.417 98 55 109 2 1436326384 41705360 14607344
17:59:35 12896 8192000000 10344902592 10772094976 1382407968 14.438 100 41 82 1 1449692384 56234432 11049984
18:00:51 17655 8192000000 10333766304 10768502784 1366455536 14.295 99 30 60 0 1432196176 56722464 9018176
18:02:14 13819 8192000000 10316512944 10750676992 1374973296 14.372 100 28 56 1 1415381904 32953664 7454944
18:03:30 26920 8192000000 10299586272 10744524800 1368865760 14.317 99 21 36 1 1405548896 31662912 5020224
18:05:27 30861 8192000000 10266787104 10709131264 1335568336 14.017 98 20 36 0 1367748016 27665296 4514384
18:07:00 54068 8192000000 10247754864 10691010560 1329026192 13.958 99 29 58 1 1352032416 15738672 7267552
18:09:00 66524 8192000000 10255906384 10687455232 1320511376 13.881 98 24 48 0 1342548272 16694816 5342080
18:11:09 32777 8192000000 10258972512 10705031168 1355339696 14.195 100 17 34 0 1372608688 13573184 3695808
18:12:45 53050 8192000000 10235735920 10668908544 1298274832 13.680 96 10 18 0 1308219408 8092400 1852176
18:14:45 41960 8192000000 10260795104 10700042240 1356378112 14.205 100 14 28 0 1370538592 10791296 3369184
18:16:27 63353 8192000000 10278584912 10723799040 1373477968 14.358 100 15 31 0 1390480160 13288192 3714000
18:18:39 56651 8192000000 10294903296 10744496128 1385523280 14.466 100 19 38 0 1409412016 19298672 4590064
18:21:28 78661 8192000000 10293859024 10743484416 1390178288 14.507 100 27 54 0 1419705312 23591840 5935184
18:23:48 32682 8192000000 10291877472 10739605504 1378495232 14.403 99 20 41 0 1393133072 10396640 4241200
18:25:21 17622 8192000000 10305978080 10746363904 1392021296 14.524 100 56 111 1 1417684032 13357888 12304848
18:26:41 59330 8192000000 10285021552 10750447616 1390735888 14.512 100 8 15 0 1407670448 14375136 2559424
18:28:41 59929 8192000000 10311418272 10762530816 1404870048 14.638 100 17 34 0 1416540880 7709616 3961216
18:30:46 89675 8192000000 10300943744 10768998400 1407595184 14.663 100 19 39 0 1422315920 10211344 4509392
18:33:26 91592 8192000000 10293031392 10762002432 1391945872 14.523 99 10 19 0 1406616032 12696656 1973504
18:35:58 64757 8192000000 10291721232 10755067904 1370778080 14.334 98 7 14 0 1383535632 11150912 1606640
18:38:03 36567 8192000000 10293121712 10741936128 1380538272 14.421 98 35 68 0 1395512288 8061808 6912208
18:39:46 75335 8192000000 10283605136 10743410688 1350275632 14.150 96 23 44 0 1366830960 11546432 5008896
18:42:01 58825 8192000000 10273179200 10725003264 1304003616 13.732 94 27 54 0 1323875440 14072464 5799360
18:44:01 98988 8192000000 10256459104 10712043520 1257122176 13.304 92 16 32 0 1273036736 12660128 3254432
18:46:47 94390 8192000000 10254372624 10705362944 1259566320 13.326 92 9 17 0 1273759440 12649728 1543392
18:49:25 131980 8192000000 10244839696 10700566528 1270434864 13.426 93 7 14 0 1285211616 13252704 1524048
18:52:37 84626 8192000000 10259739264 10713518080 1358338944 14.222 100 33 65 0 1373732400 7890192 7503264
18:55:03 67251 8192000000 10234430000 10688561152 1345934848 14.111 100 22 44 1 1360517984 9100560 5482576
18:57:15 55930 8192000000 10238130352 10685894656 1327629616 13.946 98 8 17 0 1336184992 6949584 1605792
18:59:11 98394 8192000000 10230428896 10675744768 1303451344 13.727 96 14 28 0 1317059440 10315984 3292112
19:02:01 40559 8192000000 10257152304 10705559552 1352986976 14.174 100 17 34 0 1367209328 10005168 4217184
19:04:00 57586 8192000000 10274595696 10714963968 1332864864 13.993 98 20 40 0 1351852240 14465920 4521456
19:06:36 79006 8192000000 10268173424 10711023616 1321930672 13.894 97 18 34 0 1342151616 16183600 4037344
19:09:00 115149 8192000000 10287466928 10741792768 1340088320 14.058 97 18 36 0 1355717712 11746416 3882976
19:11:55 75860 8192000000 10297756192 10755805184 1375896704 14.380 99 14 28 0 1393179472 14364304 2918464
19:14:11 47093 8192000000 10308925632 10764963840 1381802272 14.433 99 16 32 0 1401016240 15800976 3412992
19:16:00 87641 8192000000 10308058544 10752077824 1370238048 14.329 98 22 44 0 1383952176 8474480 5239648
19:18:32 76312 8192000000 10283171568 10751361024 1391463248 14.519 100 16 30 2 1406262832 11201312 3598272
19:20:49 69597 8192000000 10292298720 10753990656 1392616960 14.529 100 13 26 1 1404110720 8768400 2725360
19:23:24 21362 8192000000 10294456512 10755538944 1391384064 14.518 99 18 35 0 1400826096 5693104 3748928
19:24:54 105275 8192000000 10286865904 10758541312 1383094080 14.444 99 16 32 0 1395197392 8284640 3818672
19:27:41 29359 8192000000 10297989360 10757840896 1388265408 14.490 99 10 20 0 1397012624 6692256 2054960
19:29:20 47523 8192000000 10290387088 10748043264 1353612400 14.180 97 19 38 0 1365921408 7952080 4356928
19:31:08 82807 8192000000 10259580176 10713010176 1291357200 13.617 93 18 36 0 1305909296 10239248 4312848
19:33:32 174891 8192000000 10278816704 10728001536 1257389488 13.306 91 12 24 0 1274058672 13590816 3078368
19:37:47 42199 8192000000 9220397888 9486553088 365970128 4.276 27 4 8 0 371632896 5006464 656304

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-06-2022 19:39:15 to 30-06-2022 19:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 153 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-06-2022 19:39:31 to 30-06-2022 19:39:33.

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          240320
MON$OLDEST_ACTIVE               240321
MON$OLDEST_SNAPSHOT             240321
MON$NEXT_TRANSACTION            240321
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                       2010368
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.33607 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33607 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33607 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33607 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Thu Jun 30 19:38:33 2022
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:14: 
--- end of diff output ---

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

30.06.22 19:39:34. 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-06-30 19:39:36 No severe PSQL-related problems occured
30.06.22 19:39:37. End of report.