Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.02.2023 07:47
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 15427616 kB
MemAvailable 30466260 kB
Buffers 0 kB
Cached 16311480 kB
SwapCached 3568 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-08 07:46:32
DTS_END                         2023-02-08 07:46:32.5200
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(68:17)
IP                              ::1/40894
TRN_ID                          219989
ATT_ID                          595
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: 8614.08 23383 1033689
customer order: creation 172.13 455 20656
customer order: refuse 13.95 410 1674
order to supplier: creation 44.16 1311 5299
order to supplier: removal 3.17 163684 380
invoice (draft): creation 44.79 3120 5375
invoice (draft): removal 45.48 1072 5457
invoice accept: apply 41.16 28348 4939
invoice accept: cancel 41.78 1212 5014
customer reserve: creation 3843.54 271 461225
customer reserve: removal 4077.53 99 489304
realization accept: apply 82.25 292 9870
realization accept: cancel 76.04 181 9125
payment from customer: creation 58.83 191 7060
payment from customer: removal 5.33 136 639
payment to supplier: creation 47.91 202 5749
payment to supplier: removal 3.48 129 417
service: total inventory turnovers 4.20 3428 504
service: total monetary turnovers 7.25 315 870
service: aggregate perf. data 1.06 12021 127
service: refresh index statistics 0.04 250787 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-02-2023 07:48:15 to 08-02-2023 07:48:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7739
TEST_TIME 2 6902
TEST_TIME 3 6340
TEST_TIME 4 6630
TEST_TIME 5 6297
TEST_TIME 6 6129
TEST_TIME 7 6800
TEST_TIME 8 6835
TEST_TIME 9 6678
TEST_TIME 10 6844
TEST_TIME 11 7036
TEST_TIME 12 6986
TEST_TIME 13 6954
TEST_TIME 14 7144
TEST_TIME 15 7177
TEST_TIME 16 7251
TEST_TIME 17 7324
TEST_TIME 18 7524
TEST_TIME 19 7733
TEST_TIME 20 7855
TEST_TIME 21 7812
TEST_TIME 22 7881
TEST_TIME 23 7972
TEST_TIME 24 8193
TEST_TIME 25 8311
TEST_TIME 26 8635
TEST_TIME 27 8553
TEST_TIME 28 9934
TEST_TIME 29 10370
TEST_TIME 30 10605
TEST_TIME 31 11062
TEST_TIME 32 10848
TEST_TIME 33 10640
TEST_TIME 34 10511
TEST_TIME 35 10327
TEST_TIME 36 10362
TEST_TIME 37 10164
TEST_TIME 38 10007
TEST_TIME 39 9973
TEST_TIME 40 10045
TEST_TIME 41 9973
TEST_TIME 42 9820
TEST_TIME 43 9727
TEST_TIME 44 9545
TEST_TIME 45 9454
TEST_TIME 46 9419
TEST_TIME 47 9280
TEST_TIME 48 9262
TEST_TIME 49 9163
TEST_TIME 50 9191
TEST_TIME 51 9101
TEST_TIME 52 9086
TEST_TIME 53 9033
TEST_TIME 54 8990
TEST_TIME 55 9102
TEST_TIME 56 9037
TEST_TIME 57 9092
TEST_TIME 58 9057
TEST_TIME 59 8974
TEST_TIME 60 8977
TEST_TIME 61 9135
TEST_TIME 62 9181
TEST_TIME 63 9147
TEST_TIME 64 9209
TEST_TIME 65 9288
TEST_TIME 66 9302
TEST_TIME 67 9262
TEST_TIME 68 9252
TEST_TIME 69 9203
TEST_TIME 70 9163
TEST_TIME 71 9113
TEST_TIME 72 9082
TEST_TIME 73 9022
TEST_TIME 74 9014
TEST_TIME 75 9033
TEST_TIME 76 8977
TEST_TIME 77 8900
TEST_TIME 78 8859
TEST_TIME 79 8868
TEST_TIME 80 8860
TEST_TIME 81 8907
TEST_TIME 82 8913
TEST_TIME 83 8890
TEST_TIME 84 8883
TEST_TIME 85 8947
TEST_TIME 86 8938
TEST_TIME 87 8911
TEST_TIME 88 8981
TEST_TIME 89 8973
TEST_TIME 90 8982
TEST_TIME 91 8959
TEST_TIME 92 8909
TEST_TIME 93 8915
TEST_TIME 94 8905
TEST_TIME 95 8851
TEST_TIME 96 8816
TEST_TIME 97 8794
TEST_TIME 98 8780
TEST_TIME 99 8734
TEST_TIME 100 8724
TEST_TIME 101 8710
TEST_TIME 102 8692
TEST_TIME 103 8699
TEST_TIME 104 8687
TEST_TIME 105 8665
TEST_TIME 106 8736
TEST_TIME 107 8753
TEST_TIME 108 8825
TEST_TIME 109 8859
TEST_TIME 110 8853
TEST_TIME 111 8855
TEST_TIME 112 8813
TEST_TIME 113 8771
TEST_TIME 114 8791
TEST_TIME 115 8759
TEST_TIME 116 8725
TEST_TIME 117 8674
TEST_TIME 118 8675
TEST_TIME 119 8685
TEST_TIME 120 8639

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-02-2023 07:48:18 to 08-02-2023 07:48:22.

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 1458094 1458094 0 0.00 0 42885 14 0 0
doc_list_biud 1458072 1458072 0 0.00 0 56371 39 0 0
sp_add_invoice_to_stock 5038 4939 99 1.96 37 257672 28348 0 99
sp_cancel_adding_invoice 5014 5014 0 0.00 6 109647 1212 0 0
sp_cancel_client_order 1674 1674 0 0.00 6 12755 410 0 0
sp_cancel_customer_reserve 489304 489304 0 0.00 1 30128 100 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 4 7056 137 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 4 2872 130 0 0
sp_cancel_supplier_invoice 5462 5457 5 0.09 2 56386 1072 0 5
sp_cancel_supplier_order 380 380 0 0.00 11 1383755 163684 0 0
sp_cancel_write_off 9125 9125 0 0.00 2 21919 181 0 0
sp_client_order 20656 20656 0 0.00 5 25051 456 0 0
sp_customer_reserve 461225 461225 0 0.00 1 57667 271 0 0
sp_fill_shopping_cart 31321 31321 0 0.00 1 26082 80 0 0
sp_fill_shopping_cart_clo_res 461193 461193 0 0.00 0 21647 20 0 0
sp_get_clo_for_invoice 4893 4893 0 0.00 0 25712 315 0 0
sp_kill_cost_storno 15195 15195 0 0.00 0 30210 73 0 0
sp_kill_qstorno_ret_qs2qd 993570 993570 0 0.00 0 56368 44 0 0
sp_kill_qty_storno 525716 525716 0 0.00 0 56370 100 0 0
sp_lock_selected_doc 20303 20303 0 0.00 0 5989 6 0 0
sp_make_cost_storno 27572 27572 0 0.00 0 42877 90 0 0
sp_multiply_rows_for_pdistr 27570 27570 0 0.00 0 20261 37 0 0
sp_multiply_rows_for_qdistr 20656 20656 0 0.00 0 11624 172 0 0
sp_pay_from_customer 7060 7060 0 0.00 6 30423 192 0 0
sp_pay_to_supplier 5749 5749 0 0.00 6 23113 203 0 0
sp_payment_common 12808 12808 0 0.00 2 9259 42 0 0
sp_qd_handle_on_cancel_clo 1674 1674 0 0.00 0 12744 344 0 0
sp_qd_handle_on_invoice_upd_sts 9906 9906 0 0.00 0 11661 474 0 0
sp_qd_handle_on_reserve_upd_sts 9870 9870 0 0.00 0 21670 68 0 0
sp_reserve_write_off 9870 9870 0 0.00 8 34683 293 0 0
sp_supplier_invoice 5375 5375 0 0.00 8 104063 3120 0 0
sp_supplier_order 5299 5299 0 0.00 8 33826 1312 0 0
srv_aggregate_perf_data 127 127 0 0.00 2415 152018 12021 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 748 143850 37827 0 0
srv_find_qd_qs_mism 984989 984989 0 0.00 0 9625 12 0 0
srv_make_invnt_saldo 504 504 0 0.00 67 59735 3428 0 0
srv_make_money_saldo 870 870 0 0.00 1 14293 316 0 0
srv_recalc_idx_stat 5 5 0 0.00 57494 482987 250788 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4941 4941 0 0.00 0 35850 880 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 2 9197 444 0 0
t$perf-norm:sp_cancel_client_order 1674 1674 0 0.00 0 3638 32 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 1450 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 1307 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 6023 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 734 29 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 66272 6531 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 404 16 0 0
t$perf-norm:sp_client_order 20656 20656 0 0.00 0 13044 35 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 1592 16 0 0
t$perf-norm:sp_pay_from_customer 7060 7060 0 0.00 0 17668 21 0 0
t$perf-norm:sp_pay_to_supplier 5749 5749 0 0.00 0 4386 20 0 0
t$perf-norm:sp_reserve_write_off 9870 9870 0 0.00 0 7704 24 0 0
t$perf-norm:sp_supplier_invoice 5376 5376 0 0.00 0 9409 31 0 0
t$perf-norm:sp_supplier_order 5299 5299 0 0.00 0 7589 34 0 0
t$perf-norm:srv_aggregate_perf_data 127 127 0 0.00 0 956 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 29 3 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 846 12 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 1105 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 1079 261 0 0
v_add_invoice_to_stock 9982 9784 198 1.98 0 21583 29 0 198
v_all_customers 37718 37718 0 0.00 0 70 4 0 0
v_all_suppliers 30028 30028 0 0.00 0 58 4 0 0
v_all_wares 41312 41312 0 0.00 0 195 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 359 14 0 0
v_cancel_client_order 3348 3348 0 0.00 0 2799 10 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 4103 14 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 1 848 12 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 2760 18 0 0
v_cancel_supplier_invoice 770 760 10 1.29 0 13281 39 0 10
v_cancel_supplier_order 760 760 0 0.00 0 388 10 0 0
v_cancel_write_off 1978 1978 0 0.00 0 3189 28 0 0
v_max_id_clo_ord 5297 5297 0 0.00 0 402 5 0 0
v_max_id_clo_res 972 972 0 0.00 0 4568 14 0 0
v_max_id_ord_sup 5368 5368 0 0.00 0 5252 7 0 0
v_max_non_paid_invoice 1399 1399 0 0.00 0 305 7 0 0
v_max_non_paid_realizn 3682 3682 0 0.00 0 6921 10 0 0
v_min_id_clo_ord 5297 5297 0 0.00 0 3210 8 0 0
v_min_id_clo_res 972 972 0 0.00 0 5311 16 0 0
v_min_id_ord_sup 5368 5368 0 0.00 0 6248 13 0 0
v_min_non_paid_invoice 5748 5748 0 0.00 0 9217 17 0 0
v_min_non_paid_realizn 7060 7060 0 0.00 0 6948 13 0 0
v_our_firm 10350 10350 0 0.00 0 70 4 0 0
v_reserve_write_off 19738 19738 0 0.00 0 31160 17 0 0
x_lock_depdocs_on_canc_invoice 10091 10091 0 0.00 0 16640 126 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 3047 339 0 0
x_make_qstorno_1000_1200 5299 5299 0 0.00 3 33595 1162 0 0
x_make_qstorno_1200_2000 5375 5375 0 0.00 3 102161 2885 0 0
x_make_qstorno_2100_3300 461225 461225 0 0.00 0 57660 239 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-02-2023 07:48:23 to 08-02-2023 07:48:27.

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
05:16:36 3 8192000000 8800027168 8811569152 5305136 0.064 2 1 2 0 5404560 63648 35776
05:17:37 35093 8192000000 9724634128 10023919616 804073936 8.938 100 93 184 0 834637856 7992432 22571488
05:19:21 21769 8192000000 9762563280 10070294528 832763472 9.227 100 88 176 0 868060624 14584256 20712896
05:20:52 13554 8192000000 9803199456 10125516800 868580864 9.586 100 81 162 0 912030736 25282128 18167744
05:22:12 5977 8192000000 9863698208 10196664320 911100032 10.008 100 79 156 0 975995280 47483120 17412128
05:23:26 5010 8192000000 9918609232 10264264704 961730016 10.506 100 82 158 0 1030574752 51482288 17362448
05:24:34 2830 8192000000 9957356528 10314616832 999533312 10.874 100 81 153 2 1075708912 61289664 14885936
05:25:39 1167 8192000000 10005614608 10374709248 1026582912 11.136 100 96 187 2 1133630608 87098976 19948720
05:26:42 1426 8192000000 10024367616 10398363648 1050283888 11.363 100 96 187 4 1145187712 76171760 18732064
05:27:45 883 8192000000 10034255680 10409992192 1060601072 11.462 100 96 184 1 1154498192 76255312 17641808
05:28:48 1091 8192000000 10046488720 10428747776 1066483872 11.518 100 85 169 1 1169235232 85550176 17201184
05:29:51 1164 8192000000 10053030656 10431746048 1070452624 11.556 100 94 183 1 1174162288 84477728 19231936
05:30:54 1149 8192000000 10054106544 10435268608 1078654752 11.635 100 89 164 0 1173486912 78363312 16468848
05:31:57 1124 8192000000 10031815600 10414354432 1085101568 11.696 100 86 164 0 1151665024 50941776 15621680
05:32:59 1139 8192000000 10049620896 10432868352 1092721712 11.769 100 96 178 3 1168374912 57411264 18241936
05:34:01 3282 8192000000 10032146720 10424840192 1092379696 11.765 100 69 129 1 1154669568 48417296 13872576
05:35:05 1076 8192000000 10057476352 10449162240 1098246656 11.821 100 99 186 0 1175361520 58476240 18638624
05:36:07 1193 8192000000 10069425952 10461556736 1103528656 11.871 100 97 191 0 1192876768 70449696 18898416
05:37:09 1160 8192000000 10052711328 10441916416 1102064608 11.857 100 95 178 4 1174616176 53339104 19212464
05:38:11 1369 8192000000 10036486544 10420494336 1080246640 11.650 99 85 158 2 1159568928 63356336 15965952
05:39:13 1006 8192000000 10027230560 10413412352 1080180368 11.649 100 91 176 0 1149715424 52400160 17134896
05:40:15 951 8192000000 10005097504 10383556608 1058248272 11.440 99 95 187 3 1129308336 51270272 19789792
05:41:16 1341 8192000000 10010177440 10389225472 1055617280 11.415 100 86 170 1 1131359088 58281280 17460528
05:42:19 1195 8192000000 10013848112 10396086272 1054645936 11.405 100 84 158 0 1137595152 67122704 15826512
05:43:21 1298 8192000000 9998672192 10379075584 1052638560 11.386 100 77 135 3 1124711888 58619904 13453424
05:44:23 2484 8192000000 9976163008 10349961216 1029477472 11.163 99 78 146 1 1098318800 55284416 13556912
05:45:27 1293 8192000000 9982352464 10356686848 1038488000 11.250 100 89 164 4 1101749040 45301152 17959888
05:46:29 1937 8192000000 10012770928 10392961024 1059397968 11.451 100 74 137 0 1134492224 61397632 13696624
05:47:39 2770 8192000000 10021984512 10404782080 1070815216 11.560 100 74 136 3 1140913136 57057264 13040656
05:48:46 5183 8192000000 10046766576 10433609728 1081037968 11.657 100 75 136 2 1159172240 64557312 13576960
05:49:56 2135 8192000000 10093874032 10487336960 1097111584 11.810 100 72 140 0 1212861248 100775104 14974560
05:51:00 21283 8192000000 10085698800 10483707904 1095151760 11.792 99 55 107 0 1183681776 77537952 10992064
05:52:27 757 8192000000 10136684976 10522357760 1118509920 12.013 100 87 160 1 1252233296 116839888 16883488
05:53:32 1489 8192000000 10184813280 10580697088 1132352144 12.144 100 74 143 3 1299116416 150901680 15862592
05:54:40 1193 8192000000 10196419344 10589528064 1149749520 12.307 100 95 185 0 1308056352 136977872 21328960
05:55:51 1513 8192000000 10240197568 10642722816 1161180320 12.414 100 92 177 1 1356327472 174597216 20549936
05:56:55 1874 8192000000 10250135808 10679390208 1169741936 12.494 100 85 164 1 1364329552 176086944 18500672
05:58:00 1986 8192000000 10256503952 10672238592 1178880928 12.580 100 69 137 0 1367992848 174058128 15053792
05:59:08 5703 8192000000 10327761632 10732572672 1189643824 12.680 100 84 166 0 1435737712 227237120 18856768
06:00:18 1409 8192000000 10370507760 10789552128 1199417456 12.771 100 97 187 4 1480485104 258730208 22337440
06:01:22 1049 8192000000 10371791904 10784399360 1186284640 12.649 99 95 188 1 1481115344 272629776 22200928
06:02:28 1051 8192000000 10437530720 10849439744 1203192800 12.806 100 92 173 1 1546284768 322335600 20756368
06:03:30 834 8192000000 10473985872 10885926912 1209194208 12.862 100 99 196 1 1582015776 349376688 23444880
06:04:34 1395 8192000000 10503437072 10941071360 1223759632 12.996 100 92 178 0 1611990000 366701056 21529312
06:05:39 738 8192000000 10502296704 10935226368 1210692144 12.876 100 98 196 1 1606032640 371939664 23400832
06:06:44 941 8192000000 10553205344 10962444288 1214318192 12.909 100 96 190 1 1656816176 420151408 22346576
06:07:49 1160 8192000000 10595208080 11017953280 1212019504 12.888 100 95 189 0 1698107312 463544240 22543568
06:08:51 3527 8192000000 10596062016 11050807296 1209243200 12.862 100 63 126 1 1701692160 476791264 15657696
06:10:00 10825 8192000000 10606065536 11081449472 1203744592 12.811 100 52 105 1 1706346992 489043616 13558784
06:11:13 10130 8192000000 10632853968 11079929856 1194730320 12.727 100 55 108 0 1742493328 532549472 15213536
06:12:26 10136 8192000000 10577066048 11056578560 1170102560 12.498 100 49 98 1 1685613808 502321824 13189424
06:13:49 13056 8192000000 10484583600 11036332032 1163703776 12.438 100 35 70 0 1605000704 431121936 10174992
06:15:07 43859 8192000000 10252174720 10785091584 1122168912 12.047 97 24 48 0 1408127968 279429104 6529952
06:16:51 46536 8192000000 10106433488 10630643712 1140799296 12.223 99 25 47 1 1234956880 88975360 5182224
06:18:45 115584 8192000000 10130596000 10600988672 1144108128 12.254 99 32 62 0 1221969792 70598496 7263168
06:21:48 38776 8192000000 10126217472 10585878528 1131738880 12.138 99 18 35 0 1243442592 108152320 3551392
06:23:31 36090 8192000000 10128874304 10602237952 1139808720 12.214 100 16 30 1 1226683008 83801024 3073264
06:25:11 97467 8192000000 10113790992 10572111872 1139337328 12.209 99 28 56 0 1206594928 61726064 5531536
06:27:52 101295 8192000000 10056943472 10542751744 1121586576 12.042 98 15 27 0 1177016864 52722480 2707808
06:30:50 18952 8192000000 10079909952 10514890752 1148010080 12.291 100 68 117 2 1183035136 22612560 12412496
06:32:13 94409 8192000000 10065408464 10514776064 1104052448 11.876 96 20 40 0 1148046736 39906128 4088160
06:35:04 41430 8192000000 10105322080 10543726592 1117303216 12.002 98 38 75 0 1185314512 59357264 8654032
06:36:49 33712 8192000000 10069443104 10528813056 1114571216 11.976 98 10 20 0 1175291104 58278928 2440960
06:38:31 95532 8192000000 10131218896 10641997824 1128194688 12.104 99 12 25 0 1218777872 87453920 3129264
06:41:07 50770 8192000000 10036099680 10485489664 1107677488 11.910 98 9 16 0 1140440496 31216992 1546016
06:42:58 54907 8192000000 10062565536 10519339008 1087036624 11.714 96 19 38 0 1149945408 59145312 3763472
06:44:54 19260 8192000000 10098383328 10536153088 1135490304 12.173 100 27 51 1 1199184064 58193408 5500352
06:46:14 65626 8192000000 10186638416 10633211904 1144950032 12.262 100 22 44 0 1270641888 120928672 4763184
06:48:25 70642 8192000000 10097149584 10524172288 1114751952 11.977 97 25 41 0 1197648480 78731712 4164816
06:50:50 53061 8192000000 10112828240 10557227008 1119543216 12.023 98 37 73 0 1197686560 71029472 7113872
06:53:06 5305 8192000000 10082618832 10509172736 1138369792 12.200 100 51 99 0 1173819296 25762336 9687168
06:54:16 23619 8192000000 10133775488 10578198528 1144234848 12.255 100 37 74 0 1233536528 82094256 7207424
06:55:42 97171 8192000000 10096898448 10533265408 1109416576 11.927 97 41 82 0 1183265216 65162672 8685968
06:58:27 39203 8192000000 10087703808 10559975424 1121516992 12.041 99 16 32 0 1200093920 74760384 3816544
07:00:08 50627 8192000000 10080104624 10519023616 1103015056 11.866 98 27 53 0 1165601184 56040128 6546000
07:02:01 79156 8192000000 10110447968 10566365184 1143195296 12.246 100 12 24 1 1203475792 57849648 2430848
07:04:21 62714 8192000000 10129214288 10605010944 1139828512 12.214 99 21 42 0 1236378336 91651264 4898560
07:06:27 94789 8192000000 10064520816 10504978432 1111922864 11.951 97 17 34 0 1138690544 23667264 3100416
07:09:02 15028 8192000000 10078452224 10516221952 1127313456 12.096 99 38 74 1 1176354480 41890752 7150272
07:10:19 18908 8192000000 10121664464 10551783424 1121901664 12.045 99 49 98 1 1218211136 86077424 10232048
07:11:49 23444 8192000000 10080329744 10510176256 1096736448 11.807 97 32 63 0 1163686832 60759408 6190976
07:13:15 75721 8192000000 10110146128 10552197120 1083065568 11.677 96 25 50 0 1172021200 83558096 5397536
07:15:33 20668 8192000000 10082758320 10514100224 1094578224 11.786 97 45 89 0 1154640784 51459184 8603376
07:17:04 36620 8192000000 10115462256 10534449152 1110678240 11.939 98 51 98 1 1191553968 71495056 9380672
07:18:44 59149 8192000000 10082473024 10531991552 1101236112 11.849 98 58 106 2 1200843104 88916768 10690224
07:20:43 63190 8192000000 10041172944 10489438208 1105200592 11.887 98 14 28 0 1137407568 29254528 2952448
07:22:46 143850 8192000000 10055434640 10523430912 1072979920 11.581 95 9 18 0 1100482352 25698096 1804336
07:26:11 9490 8192000000 10134205760 10568118272 1141042784 12.225 100 44 88 0 1230010944 79933648 9034512
07:27:24 41701 8192000000 10137902864 10577788928 1115477024 11.984 97 29 57 0 1203561056 82173680 5910352
07:29:13 42926 8192000000 10137608048 10590511104 1106387664 11.898 97 32 64 0 1217259792 103642960 7229168
07:31:00 85229 8192000000 10152848848 10630479872 1116589056 11.995 98 39 78 0 1251004816 125919200 8496560
07:33:28 36049 8192000000 10133643424 10576449536 1096764752 11.807 97 30 58 0 1226179200 123582576 5831872
07:35:08 47875 8192000000 10084505808 10558681088 1121629664 12.042 99 17 32 0 1175836784 50526112 3681008
07:36:59 23394 8192000000 10120322816 10550251520 1126088416 12.084 99 45 89 0 1213718656 78488704 9141536
07:38:23 90505 8192000000 10088008288 10533195776 1091451904 11.756 97 29 58 0 1184617760 87213488 5952368
07:40:54 28077 8192000000 10074381648 10545426432 1105332192 11.888 99 21 42 0 1151012496 41291552 4388752
07:42:31 48136 8192000000 10053530224 10520387584 1095589920 11.796 99 6 10 0 1149392800 52989376 813504
07:44:19 105082 8192000000 10073683504 10555641856 1097928816 11.818 98 14 28 0 1166280416 65407776 2943824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-02-2023 07:48:29 to 08-02-2023 07:48:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-02-2023 07:48:46 to 08-02-2023 07:48:48.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          218677
MON$OLDEST_ACTIVE               220180
MON$OLDEST_SNAPSHOT             220180
MON$NEXT_TRANSACTION            220180
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2030368
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2898 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2898 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2898 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2898 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Feb  8 07:46:39 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Feb  8 07:46:39 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Feb  8 07:46:39 2023
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

08.02.23 07:48:50. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-08 07:48:52 No severe PSQL-related problems occured
08.02.23 07:48:54. End of report.