Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.02.2023 13: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 14678460 kB
MemAvailable 30452960 kB
Buffers 0 kB
Cached 17026316 kB
SwapCached 3616 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-09 13:37:24
DTS_END                         2023-02-09 13:37:24.3510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(113:22) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60366
TRN_ID                          211311
ATT_ID                          664
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: 8922.70 28817 1070724
customer order: creation 160.88 506 19306
customer order: refuse 13.78 514 1653
order to supplier: creation 42.40 1496 5088
order to supplier: removal 3.38 207579 405
invoice (draft): creation 42.86 3918 5143
invoice (draft): removal 52.60 1202 6312
invoice accept: apply 38.77 28350 4652
invoice accept: cancel 47.69 1382 5723
customer reserve: creation 3631.74 263 435809
customer reserve: removal 4601.00 117 552120
realization accept: apply 76.77 335 9212
realization accept: cancel 89.89 185 10787
payment from customer: creation 54.80 209 6576
payment from customer: removal 5.74 153 689
payment to supplier: creation 45.45 217 5454
payment to supplier: removal 3.10 195 372
service: total inventory turnovers 3.47 5414 416
service: total monetary turnovers 7.58 450 909
service: aggregate perf. data 0.78 19325 93
service: refresh index statistics 0.04 304536 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-02-2023 13:38:48 to 09-02-2023 13:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6721
TEST_TIME 2 7023
TEST_TIME 3 7147
TEST_TIME 4 7138
TEST_TIME 5 7122
TEST_TIME 6 6698
TEST_TIME 7 6465
TEST_TIME 8 6092
TEST_TIME 9 6163
TEST_TIME 10 6416
TEST_TIME 11 6338
TEST_TIME 12 6693
TEST_TIME 13 6686
TEST_TIME 14 6850
TEST_TIME 15 6814
TEST_TIME 16 6922
TEST_TIME 17 6944
TEST_TIME 18 7007
TEST_TIME 19 7237
TEST_TIME 20 7370
TEST_TIME 21 7647
TEST_TIME 22 7664
TEST_TIME 23 7975
TEST_TIME 24 8065
TEST_TIME 25 8362
TEST_TIME 26 8270
TEST_TIME 27 8331
TEST_TIME 28 8205
TEST_TIME 29 8237
TEST_TIME 30 8268
TEST_TIME 31 8127
TEST_TIME 32 8168
TEST_TIME 33 8218
TEST_TIME 34 8494
TEST_TIME 35 8666
TEST_TIME 36 8894
TEST_TIME 37 8919
TEST_TIME 38 9720
TEST_TIME 39 9957
TEST_TIME 40 10502
TEST_TIME 41 10577
TEST_TIME 42 10679
TEST_TIME 43 10543
TEST_TIME 44 10320
TEST_TIME 45 10291
TEST_TIME 46 10303
TEST_TIME 47 10257
TEST_TIME 48 10204
TEST_TIME 49 10091
TEST_TIME 50 9979
TEST_TIME 51 9850
TEST_TIME 52 9823
TEST_TIME 53 9786
TEST_TIME 54 9712
TEST_TIME 55 9644
TEST_TIME 56 9597
TEST_TIME 57 9600
TEST_TIME 58 9556
TEST_TIME 59 9445
TEST_TIME 60 9378
TEST_TIME 61 9320
TEST_TIME 62 9284
TEST_TIME 63 9187
TEST_TIME 64 9214
TEST_TIME 65 9210
TEST_TIME 66 9200
TEST_TIME 67 9125
TEST_TIME 68 9159
TEST_TIME 69 9097
TEST_TIME 70 9155
TEST_TIME 71 9154
TEST_TIME 72 9304
TEST_TIME 73 9321
TEST_TIME 74 9331
TEST_TIME 75 9378
TEST_TIME 76 9457
TEST_TIME 77 9452
TEST_TIME 78 9483
TEST_TIME 79 9465
TEST_TIME 80 9391
TEST_TIME 81 9304
TEST_TIME 82 9292
TEST_TIME 83 9337
TEST_TIME 84 9289
TEST_TIME 85 9246
TEST_TIME 86 9250
TEST_TIME 87 9200
TEST_TIME 88 9192
TEST_TIME 89 9118
TEST_TIME 90 9074
TEST_TIME 91 9075
TEST_TIME 92 9024
TEST_TIME 93 9014
TEST_TIME 94 9044
TEST_TIME 95 9045
TEST_TIME 96 9010
TEST_TIME 97 9037
TEST_TIME 98 9038
TEST_TIME 99 8994
TEST_TIME 100 8998
TEST_TIME 101 9038
TEST_TIME 102 9152
TEST_TIME 103 9192
TEST_TIME 104 9239
TEST_TIME 105 9232
TEST_TIME 106 9218
TEST_TIME 107 9217
TEST_TIME 108 9150
TEST_TIME 109 9210
TEST_TIME 110 9152
TEST_TIME 111 9107
TEST_TIME 112 9072
TEST_TIME 113 9062
TEST_TIME 114 9054
TEST_TIME 115 9062
TEST_TIME 116 9047
TEST_TIME 117 9022
TEST_TIME 118 9011
TEST_TIME 119 8981
TEST_TIME 120 8947

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-02-2023 13:38:52 to 09-02-2023 13:38:55.

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 1471877 1471877 0 0.00 0 24084 15 0 0
doc_list_biud 1471865 1471865 0 0.00 0 54676 51 0 0
sp_add_invoice_to_stock 4772 4652 120 2.51 84 196212 28351 0 120
sp_cancel_adding_invoice 5723 5723 0 0.00 6 83268 1383 0 0
sp_cancel_client_order 1653 1653 0 0.00 6 12722 515 0 0
sp_cancel_customer_reserve 552120 552120 0 0.00 1 35139 118 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 8728 154 0 0
sp_cancel_pay_to_supplier 372 372 0 0.00 4 7068 195 0 0
sp_cancel_supplier_invoice 6322 6312 10 0.15 3 36761 1202 0 10
sp_cancel_supplier_order 405 405 0 0.00 10 1540541 207580 0 0
sp_cancel_write_off 10787 10787 0 0.00 2 14385 186 0 0
sp_client_order 19306 19306 0 0.00 6 35543 507 0 0
sp_customer_reserve 435809 435809 0 0.00 1 118095 263 0 0
sp_fill_shopping_cart 29520 29520 0 0.00 1 14640 98 0 0
sp_fill_shopping_cart_clo_res 435792 435792 0 0.00 0 20924 20 0 0
sp_get_clo_for_invoice 4617 4617 0 0.00 0 24391 304 0 0
sp_kill_cost_storno 17571 17571 0 0.00 0 12573 93 0 0
sp_kill_qstorno_ret_qs2qd 1121744 1121744 0 0.00 0 36650 51 0 0
sp_kill_qty_storno 590828 590828 0 0.00 0 54671 117 0 0
sp_lock_selected_doc 19301 19301 0 0.00 0 8483 10 0 0
sp_make_cost_storno 25859 25859 0 0.00 0 22249 96 0 0
sp_multiply_rows_for_pdistr 25856 25856 0 0.00 0 8800 44 0 0
sp_multiply_rows_for_qdistr 19304 19304 0 0.00 0 10348 190 0 0
sp_pay_from_customer 6576 6576 0 0.00 5 24264 209 0 0
sp_pay_to_supplier 5454 5454 0 0.00 7 15519 218 0 0
sp_payment_common 12028 12028 0 0.00 2 8822 58 0 0
sp_qd_handle_on_cancel_clo 1653 1653 0 0.00 0 12706 428 0 0
sp_qd_handle_on_invoice_upd_sts 10340 10340 0 0.00 0 54658 637 0 0
sp_qd_handle_on_reserve_upd_sts 9211 9211 0 0.00 0 12413 73 0 0
sp_reserve_write_off 9212 9212 0 0.00 8 22268 335 0 0
sp_supplier_invoice 5143 5143 0 0.00 10 121339 3919 0 0
sp_supplier_order 5088 5088 0 0.00 7 36975 1497 0 0
srv_aggregate_perf_data 93 93 0 0.00 3084 187190 19325 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 782 149747 31725 0 0
srv_find_qd_qs_mism 1024816 1024816 0 0.00 0 24047 10 0 0
srv_make_invnt_saldo 416 416 0 0.00 48 96003 5414 0 0
srv_make_money_saldo 909 909 0 0.00 1 11702 451 0 0
srv_recalc_idx_stat 5 5 0 0.00 50540 511215 304536 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4658 4658 0 0.00 1 13849 1178 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 1 7170 519 0 0
t$perf-norm:sp_cancel_client_order 1653 1653 0 0.00 0 1382 31 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 1221 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 1619 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 372 372 0 0.00 0 457 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 5409 55 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 101515 8980 0 0
t$perf-norm:sp_cancel_write_off 931 931 0 0.00 0 1259 20 0 0
t$perf-norm:sp_client_order 19306 19306 0 0.00 0 21909 48 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 504 16 0 0
t$perf-norm:sp_pay_from_customer 6576 6576 0 0.00 0 15145 24 0 0
t$perf-norm:sp_pay_to_supplier 5454 5454 0 0.00 0 22261 28 0 0
t$perf-norm:sp_reserve_write_off 9212 9212 0 0.00 0 20413 30 0 0
t$perf-norm:sp_supplier_invoice 5144 5144 0 0.00 0 9034 42 0 0
t$perf-norm:sp_supplier_order 5088 5088 0 0.00 0 3716 37 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 617 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 591 13 0 0
t$perf-norm:srv_make_invnt_saldo 416 416 0 0.00 0 1749 26 0 0
t$perf-norm:srv_make_money_saldo 909 909 0 0.00 0 885 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 168 47 0 0
v_add_invoice_to_stock 9468 9228 240 2.53 0 5057 29 0 240
v_all_customers 35349 35349 0 0.00 0 89 4 0 0
v_all_suppliers 28536 28536 0 0.00 0 74 5 0 0
v_all_wares 38602 38602 0 0.00 0 108 5 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 2848 21 0 0
v_cancel_client_order 3306 3306 0 0.00 0 3215 13 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 1271 12 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 1634 12 0 0
v_cancel_payment_to_supplier 744 744 0 0.00 0 2349 20 0 0
v_cancel_supplier_invoice 790 770 20 2.53 0 598 26 0 20
v_cancel_supplier_order 810 810 0 0.00 0 400 13 0 0
v_cancel_write_off 1862 1862 0 0.00 0 2002 26 0 0
v_max_id_clo_ord 5084 5084 0 0.00 0 1137 6 0 0
v_max_id_clo_res 954 954 0 0.00 0 2433 26 0 0
v_max_id_ord_sup 5135 5135 0 0.00 0 8396 10 0 0
v_max_non_paid_invoice 1404 1404 0 0.00 0 72 9 0 0
v_max_non_paid_realizn 3290 3290 0 0.00 0 6743 14 0 0
v_min_id_clo_ord 5084 5084 0 0.00 0 3356 12 0 0
v_min_id_clo_res 954 954 0 0.00 0 1178 10 0 0
v_min_id_ord_sup 5135 5135 0 0.00 0 8324 17 0 0
v_min_non_paid_invoice 5453 5453 0 0.00 0 8813 25 0 0
v_min_non_paid_realizn 6574 6574 0 0.00 0 5322 21 0 0
v_our_firm 9820 9820 0 0.00 0 69 5 0 0
v_reserve_write_off 18422 18422 0 0.00 0 4708 12 0 0
x_lock_depdocs_on_canc_invoice 11650 11650 0 0.00 0 11799 137 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 18355 488 0 0
x_make_qstorno_1000_1200 5088 5088 0 0.00 3 36941 1294 0 0
x_make_qstorno_1200_2000 5143 5143 0 0.00 3 112908 3647 0 0
x_make_qstorno_2100_3300 435808 435808 0 0.00 0 118094 228 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 09-02-2023 13:38:56 to 09-02-2023 13:39:01.

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
11:07:28 4 8192000000 8801492752 8813731840 6607248 0.080 4 1 2 0 6710192 67168 35776
11:08:29 29495 8192000000 9732515600 10034978816 801795488 8.914 100 90 180 0 832153792 7904720 22453584
11:10:05 19843 8192000000 9775764448 10085322752 847458960 9.375 100 93 186 0 883522704 14155424 21908320
11:11:29 15035 8192000000 9800296528 10114928640 865735312 9.557 100 92 184 1 908007968 20909168 21363488
11:12:59 10548 8192000000 9849318336 10184339456 904716480 9.945 100 83 161 1 966498720 43609232 18173008
11:14:13 2688 8192000000 9908462816 10247663616 944261920 10.335 100 86 165 0 1024815184 62955808 17597456
11:15:19 2117 8192000000 9951150528 10299068416 977817472 10.663 100 90 174 1 1066509264 70713600 17978192
11:16:23 964 8192000000 9996348048 10358738944 1013108544 11.005 100 91 175 0 1114053904 83629376 17315984
11:17:26 1505 8192000000 9985785168 10352979968 1039114704 11.256 100 94 177 2 1109384624 52129664 18140256
11:18:29 989 8192000000 10040561840 10415214592 1054946848 11.408 100 96 182 1 1164228400 91276736 18004816
11:19:31 1070 8192000000 10033543264 10408845312 1066738864 11.521 100 92 178 0 1151896880 67450096 17707920
11:20:33 5098 8192000000 10034342640 10411028480 1070400656 11.556 100 91 176 1 1154862784 67382144 17079984
11:21:39 1188 8192000000 10057814832 10440790016 1075797616 11.607 100 94 180 3 1177458464 83456064 18204784
11:22:42 1349 8192000000 10061627120 10447347712 1079413424 11.642 100 93 179 3 1181331680 84060368 17857888
11:23:45 1177 8192000000 10038339824 10427056128 1085749168 11.702 100 94 187 2 1158225376 52989520 19486688
11:24:47 1134 8192000000 10070796320 10459312128 1088500336 11.728 100 89 172 1 1189235680 82238832 18496512
11:25:49 1190 8192000000 10058638000 10448240640 1094793824 11.788 100 93 176 2 1180683824 67904352 17985648
11:26:51 871 8192000000 10055251008 10444238848 1097573280 11.815 100 90 177 1 1177835024 62813504 17448240
11:27:52 1526 8192000000 10038377280 10437611520 1099300960 11.831 100 82 151 4 1167150064 51683920 16165184
11:28:54 6147 8192000000 10025176416 10424606720 1089372784 11.737 100 57 112 2 1145368512 44816304 11179424
11:30:08 3887 8192000000 10046363136 10437378048 1085888816 11.704 100 79 149 0 1176902304 77269248 13744240
11:31:13 1401 8192000000 10016890000 10403704832 1076413552 11.613 100 86 161 2 1137420832 44656320 16350960
11:32:15 847 8192000000 10015436864 10398818304 1057187696 11.430 100 85 161 2 1137332496 63719776 16425024
11:33:17 1225 8192000000 10018776112 10401169408 1065832832 11.512 100 96 179 2 1144552352 60184848 18534672
11:34:19 1211 8192000000 10005456944 10378141696 1039123376 11.256 100 98 181 3 1126785312 69658208 18003728
11:35:21 1009 8192000000 9986556544 10359410688 1033574448 11.203 100 92 177 1 1109849904 58371600 17903856
11:36:25 1236 8192000000 9991367456 10363228160 1028312912 11.152 100 90 173 1 1110428592 64295392 17820288
11:37:27 1726 8192000000 9990560576 10367168512 1040898224 11.273 100 78 141 2 1109551152 54965344 13687584
11:38:36 6817 8192000000 10013116000 10388004864 1051508704 11.375 99 88 165 4 1131485328 63477376 16499248
11:39:48 7441 8192000000 10056743264 10443759616 1072220768 11.573 100 67 134 1 1171325760 85778576 13326416
11:41:01 1792 8192000000 10054560960 10444263424 1082947984 11.676 100 68 135 0 1173754640 76416272 14390384
11:42:08 3197 8192000000 10094993440 10484514816 1103751776 11.873 100 82 151 2 1208295776 88609840 15934160
11:43:19 2517 8192000000 10108259376 10505531392 1119274688 12.020 100 77 140 0 1219062624 85042832 14745104
11:44:29 7891 8192000000 10155761520 10554368000 1128395440 12.106 100 73 140 0 1269050176 125017424 15637312
11:45:41 9031 8192000000 10193495808 10591768576 1136404016 12.182 100 81 160 1 1305666864 152014112 17248736
11:46:59 16354 8192000000 10190213232 10623361024 1138790368 12.204 100 67 134 1 1305235296 151616960 14827968
11:48:26 1408 8192000000 10227829392 10626924544 1151401488 12.323 100 92 179 1 1341502688 170439680 19661520
11:49:31 5929 8192000000 10231343648 10639421440 1157003184 12.375 100 71 134 1 1341702672 169891616 14807872
11:50:42 1894 8192000000 10258789856 10656792576 1162708000 12.429 100 91 171 0 1371009920 190371424 17930496
11:51:47 820 8192000000 10273285728 10675101696 1163233280 12.434 100 94 186 3 1388028832 202957760 21837792
11:52:51 881 8192000000 10344800688 10743820288 1176661376 12.559 100 98 187 2 1452159744 254656512 20841856
11:53:57 868 8192000000 10376466672 10779045888 1177415744 12.566 100 97 194 0 1486175536 286770864 21988928
11:55:02 2136 8192000000 10389370080 10812239872 1177317360 12.565 100 90 178 1 1493312832 293955328 22040144
11:56:10 2174 8192000000 10378335328 10789838848 1177344688 12.565 100 93 185 0 1487032320 288210928 21476704
11:57:19 1455 8192000000 10423171856 10864386048 1187653344 12.662 100 84 165 2 1529694752 320893888 21147520
11:58:23 961 8192000000 10427974864 10869501952 1182883216 12.617 100 100 195 1 1530132464 324610304 22638944
11:59:29 1265 8192000000 10386387792 10827632640 1167369312 12.472 99 96 187 0 1491262496 302636512 21256672
12:00:34 932 8192000000 10394815088 10817921024 1173849888 12.533 100 91 173 1 1502010624 307704912 20455824
12:01:38 885 8192000000 10407752144 10844950528 1184582864 12.633 100 89 178 0 1511962832 306927680 20452288
12:02:45 776 8192000000 10406157008 10815889408 1190249344 12.686 100 85 162 0 1513378720 303230272 19899104
12:03:49 893 8192000000 10454202240 10874359808 1173542192 12.530 99 88 169 1 1555548592 361703168 20303232
12:04:59 929 8192000000 10507268064 10913513472 1197651648 12.755 100 93 177 0 1605530624 386874496 21004480
12:06:02 1160 8192000000 10540868368 10956140544 1196984176 12.748 100 94 182 0 1651538976 431221648 23333152
12:07:06 4099 8192000000 10596958272 11011411968 1210550096 12.874 100 74 148 0 1701959440 473189792 18219552
12:08:19 5961 8192000000 10666224864 11096064000 1216158608 12.926 100 64 124 1 1766358608 533794656 16405344
12:09:29 1078 8192000000 10704769504 11144040448 1208173696 12.852 100 75 146 0 1804900064 578338608 18387760
12:10:33 2628 8192000000 10706608544 11158716416 1189181712 12.676 100 77 150 0 1808819600 599814384 19823504
12:11:39 10172 8192000000 10682545040 11188359168 1192798896 12.709 100 57 112 0 1794816176 586750160 15267120
12:12:55 17920 8192000000 10616546800 11114205184 1184471808 12.632 100 42 84 0 1714954048 519204592 11277648
12:14:36 15904 8192000000 10572955552 11097370624 1172769200 12.523 100 33 66 0 1689593040 507692160 9131680
12:15:55 20991 8192000000 10358608048 10847358976 1152876400 12.336 100 32 62 1 1468652384 307539952 8236032
12:17:27 31110 8192000000 10209516320 10647179264 1151228448 12.321 100 26 52 0 1302082928 144830864 6023616
12:19:02 97259 8192000000 10153385488 10602971136 1148869152 12.299 99 27 53 2 1247765632 93277264 5619216
12:21:43 31224 8192000000 10133585248 10562961408 1133747504 12.157 99 79 142 3 1231900224 81824688 16328032
12:23:16 93615 8192000000 10096517488 10569728000 1136324896 12.181 99 18 36 0 1227367584 87271248 3771440
12:25:55 149747 8192000000 10135138848 10582667264 1127899360 12.102 98 17 32 0 1180601968 49168320 3534288
12:29:27 79363 8192000000 10115804096 10601881600 1082231456 11.669 94 13 26 0 1162646400 77396592 3018352
12:31:47 76208 8192000000 10136116160 10586050560 1120017280 12.027 99 19 36 0 1239219472 114537664 4664528
12:34:27 72837 8192000000 10045580976 10519810048 1104256592 11.878 98 14 26 0 1136961952 29848496 2856864
12:36:42 1181 8192000000 10051868816 10480021504 1125204592 12.076 99 88 160 4 1164499024 18985088 20309344
12:37:44 41942 8192000000 10102199808 10544623616 1111700576 11.949 98 39 68 0 1182743504 64382304 6660624
12:39:34 43836 8192000000 10094024976 10584694784 1115789856 11.987 99 11 22 0 1198352928 80392992 2170080
12:41:39 67023 8192000000 10078369824 10620297216 1131164992 12.132 100 10 20 0 1203194992 70208112 1821888
12:43:52 27910 8192000000 10118100368 10561638400 1142245312 12.237 100 21 42 0 1213763408 66577584 4940512
12:45:35 36060 8192000000 10187302224 10659340288 1129597968 12.118 98 19 38 0 1244150816 110459120 4093728
12:48:11 37421 8192000000 10176642848 10634100736 1123864240 12.063 99 36 63 0 1257329456 125970640 7494576
12:49:51 24066 8192000000 10084414880 10526904320 1126264656 12.086 100 27 53 0 1226513888 94879824 5369408
12:51:16 14853 8192000000 10111963584 10549026816 1121717488 12.043 99 43 84 1 1191964784 62083632 8163664
12:53:03 49680 8192000000 10110550480 10608812032 1123280048 12.058 99 12 23 1 1197382752 71650208 2452496
12:55:04 57587 8192000000 10120035728 10604265472 1107299168 11.907 98 17 33 0 1176467408 65829680 3338560
12:57:09 120274 8192000000 10129227504 10575015936 1126057184 12.084 99 35 70 0 1210916640 76494496 8364960
13:00:18 17386 8192000000 10104565536 10585759744 1124606608 12.070 100 28 56 0 1216554192 85606832 6340752
13:01:43 72218 8192000000 10062000864 10515177472 1113182352 11.963 99 23 42 0 1154553344 36940544 4430448
13:04:01 50300 8192000000 10064529520 10517327872 1095198896 11.792 97 13 23 0 1142381936 44595552 2587488
13:05:53 71655 8192000000 10068975680 10543042560 1104447408 11.880 98 12 23 0 1138696992 31917120 2332464
13:09:29 73492 8192000000 10064901776 10547511296 1111799600 11.949 99 11 22 0 1175677280 61554624 2323056
13:11:44 58512 8192000000 10049972288 10490179584 1090068112 11.743 96 23 40 3 1130714528 37044240 3602176
13:13:44 43233 8192000000 10129606176 10581417984 1123042240 12.056 98 45 90 0 1210000096 77337520 9620336
13:15:28 17584 8192000000 10144679424 10600865792 1139555520 12.211 100 43 84 0 1253459664 104282656 9621488
13:16:47 5909 8192000000 10189139664 10618093568 1133599952 12.155 100 58 116 1 1278349456 132824528 11924976
13:17:58 16474 8192000000 10216008736 10649001984 1134011472 12.159 99 46 92 0 1309963648 166242864 9709312
13:19:16 33271 8192000000 10154432672 10602205184 1128638240 12.109 99 47 92 0 1273963856 134709056 10616560
13:20:58 81987 8192000000 10099687728 10557546496 1105414464 11.889 97 14 26 0 1154013776 45782112 2817200
13:23:21 38949 8192000000 10105563040 10547097600 1109298080 11.926 97 47 91 1 1182068032 63743360 9026592
13:25:03 109208 8192000000 10069220112 10570100736 1132006800 12.140 100 24 49 0 1224622656 86404688 6211168
13:27:59 55763 8192000000 10115115312 10615570432 1145447712 12.267 100 9 18 0 1206970192 59729360 1793120
13:30:18 79072 8192000000 10089988688 10549272576 1104747312 11.883 98 17 34 0 1169873664 61359088 3767264
13:32:38 99808 8192000000 10087141888 10569281536 1131606064 12.136 100 14 29 0 1204282672 69365408 3311200
13:35:47 44957 8192000000 10098177360 10534137856 1089561008 11.738 97 23 46 0 1154642784 59662432 5419344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-02-2023 13:39:03 to 09-02-2023 13:39:19.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-02-2023 13:39:20 to 09-02-2023 13:39:22.

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          204895
MON$OLDEST_ACTIVE               211652
MON$OLDEST_SNAPSHOT             211652
MON$NEXT_TRANSACTION            211652
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                       2111616
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Thu Feb  9 13:37:25 2023
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Thu Feb  9 13:37:28 2023
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Thu Feb  9 13:37:32 2023
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

09.02.23 13:39:23. 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-09 13:39:26 No severe PSQL-related problems occured
09.02.23 13:39:27. End of report.