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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16022916 kB
MemAvailable 31350184 kB
Buffers 0 kB
Cached 15711592 kB
SwapCached 2448 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.2
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 2022-06-07 10:37:18
DTS_END                         2022-06-07 10:37:18.3930
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/40378
TRN_ID                          220437
ATT_ID                          667
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: 9196.35 20243 1103562
customer order: creation 172.91 483 20749
customer order: refuse 14.29 404 1715
order to supplier: creation 46.40 1341 5568
order to supplier: removal 3.75 152453 450
invoice (draft): creation 45.53 3184 5463
invoice (draft): removal 52.55 993 6306
invoice accept: apply 41.41 26962 4969
invoice accept: cancel 47.04 1283 5645
customer reserve: creation 3861.53 255 463383
customer reserve: removal 4614.97 98 553796
realization accept: apply 83.52 290 10022
realization accept: cancel 83.11 179 9973
payment from customer: creation 58.88 195 7066
payment from customer: removal 5.73 127 687
payment to supplier: creation 48.59 203 5831
payment to supplier: removal 3.57 141 428
service: total inventory turnovers 3.64 4303 437
service: total monetary turnovers 8.10 401 972
service: aggregate perf. data 0.82 18499 98
service: refresh index statistics 0.03 193069 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-06-2022 10:39:24 to 07-06-2022 10:39:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10588
TEST_TIME 2 8136
TEST_TIME 3 6611
TEST_TIME 4 6040
TEST_TIME 5 5704
TEST_TIME 6 5920
TEST_TIME 7 6149
TEST_TIME 8 6305
TEST_TIME 9 6681
TEST_TIME 10 6769
TEST_TIME 11 6828
TEST_TIME 12 7222
TEST_TIME 13 7273
TEST_TIME 14 7225
TEST_TIME 15 7518
TEST_TIME 16 7500
TEST_TIME 17 7560
TEST_TIME 18 7517
TEST_TIME 19 7806
TEST_TIME 20 8066
TEST_TIME 21 8360
TEST_TIME 22 8602
TEST_TIME 23 8743
TEST_TIME 24 8750
TEST_TIME 25 8798
TEST_TIME 26 8859
TEST_TIME 27 8920
TEST_TIME 28 9014
TEST_TIME 29 8997
TEST_TIME 30 9407
TEST_TIME 31 9734
TEST_TIME 32 9953
TEST_TIME 33 10243
TEST_TIME 34 10854
TEST_TIME 35 10933
TEST_TIME 36 10709
TEST_TIME 37 10727
TEST_TIME 38 10741
TEST_TIME 39 10595
TEST_TIME 40 10390
TEST_TIME 41 10235
TEST_TIME 42 10257
TEST_TIME 43 10094
TEST_TIME 44 10060
TEST_TIME 45 10077
TEST_TIME 46 10055
TEST_TIME 47 9989
TEST_TIME 48 9997
TEST_TIME 49 10060
TEST_TIME 50 9996
TEST_TIME 51 9889
TEST_TIME 52 9830
TEST_TIME 53 9679
TEST_TIME 54 9688
TEST_TIME 55 9612
TEST_TIME 56 9509
TEST_TIME 57 9593
TEST_TIME 58 9639
TEST_TIME 59 9634
TEST_TIME 60 9572
TEST_TIME 61 9651
TEST_TIME 62 9665
TEST_TIME 63 9723
TEST_TIME 64 9813
TEST_TIME 65 9765
TEST_TIME 66 9911
TEST_TIME 67 9894
TEST_TIME 68 9923
TEST_TIME 69 9917
TEST_TIME 70 9875
TEST_TIME 71 9784
TEST_TIME 72 9849
TEST_TIME 73 9777
TEST_TIME 74 9770
TEST_TIME 75 9735
TEST_TIME 76 9643
TEST_TIME 77 9594
TEST_TIME 78 9530
TEST_TIME 79 9432
TEST_TIME 80 9370
TEST_TIME 81 9436
TEST_TIME 82 9404
TEST_TIME 83 9549
TEST_TIME 84 9494
TEST_TIME 85 9467
TEST_TIME 86 9445
TEST_TIME 87 9419
TEST_TIME 88 9444
TEST_TIME 89 9515
TEST_TIME 90 9495
TEST_TIME 91 9517
TEST_TIME 92 9525
TEST_TIME 93 9544
TEST_TIME 94 9589
TEST_TIME 95 9537
TEST_TIME 96 9589
TEST_TIME 97 9550
TEST_TIME 98 9480
TEST_TIME 99 9432
TEST_TIME 100 9392
TEST_TIME 101 9408
TEST_TIME 102 9359
TEST_TIME 103 9325
TEST_TIME 104 9263
TEST_TIME 105 9276
TEST_TIME 106 9232
TEST_TIME 107 9244
TEST_TIME 108 9221
TEST_TIME 109 9191
TEST_TIME 110 9228
TEST_TIME 111 9206
TEST_TIME 112 9188
TEST_TIME 113 9156
TEST_TIME 114 9196
TEST_TIME 115 9202
TEST_TIME 116 9387
TEST_TIME 117 9401
TEST_TIME 118 9360
TEST_TIME 119 9291
TEST_TIME 120 9217

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-06-2022 10:39:28 to 07-06-2022 10:39:31.

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 1530788 1530788 0 0.00 0 31743 13 0 0
doc_list_biud 1530773 1530773 0 0.00 0 29832 42 0 0
sp_add_invoice_to_stock 5067 4969 98 1.93 65 251577 26963 0 98
sp_cancel_adding_invoice 5645 5645 0 0.00 5 108107 1284 0 0
sp_cancel_client_order 1715 1715 0 0.00 6 14461 405 0 0
sp_cancel_customer_reserve 553796 553796 0 0.00 1 29834 99 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 4 4284 128 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 4 5050 142 0 0
sp_cancel_supplier_invoice 6314 6306 8 0.12 3 14462 993 0 8
sp_cancel_supplier_order 450 450 0 0.00 12 1544446 152453 0 0
sp_cancel_write_off 9973 9973 0 0.00 2 15019 179 0 0
sp_client_order 20749 20749 0 0.00 6 27564 483 0 0
sp_customer_reserve 463383 463383 0 0.00 1 114954 256 0 0
sp_fill_shopping_cart 31773 31773 0 0.00 1 18381 82 0 0
sp_fill_shopping_cart_clo_res 463350 463350 0 0.00 0 14629 17 0 0
sp_get_clo_for_invoice 4930 4930 0 0.00 0 16131 221 0 0
sp_kill_cost_storno 16733 16733 0 0.00 0 31730 78 0 0
sp_kill_qstorno_ret_qs2qd 1124321 1124321 0 0.00 0 26670 44 0 0
sp_kill_qty_storno 592836 592836 0 0.00 0 29832 100 0 0
sp_lock_selected_doc 20724 20724 0 0.00 0 11297 8 0 0
sp_make_cost_storno 27849 27849 0 0.00 0 16188 80 0 0
sp_multiply_rows_for_pdistr 27848 27848 0 0.00 0 12672 37 0 0
sp_multiply_rows_for_qdistr 20749 20749 0 0.00 0 16468 173 0 0
sp_pay_from_customer 7066 7066 0 0.00 6 23389 195 0 0
sp_pay_to_supplier 5831 5831 0 0.00 7 22973 204 0 0
sp_payment_common 12897 12897 0 0.00 2 9059 43 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 1 14450 321 0 0
sp_qd_handle_on_invoice_upd_sts 10574 10574 0 0.00 0 28335 521 0 0
sp_qd_handle_on_reserve_upd_sts 10022 10022 0 0.00 0 27404 66 0 0
sp_reserve_write_off 10022 10022 0 0.00 8 27545 291 0 0
sp_supplier_invoice 5463 5463 0 0.00 7 85349 3184 0 0
sp_supplier_order 5568 5568 0 0.00 7 29345 1342 0 0
srv_aggregate_perf_data 98 98 0 0.00 2978 194522 18500 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 974 114667 33502 0 0
srv_find_qd_qs_mism 1054815 1054815 0 0.00 0 15424 10 0 0
srv_make_invnt_saldo 437 437 0 0.00 63 87746 4303 0 0
srv_make_money_saldo 972 972 0 0.00 1 26268 401 0 0
srv_recalc_idx_stat 4 4 0 0.00 55059 297783 193069 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4974 4974 0 0.00 1 12150 1003 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 1 15420 578 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 3391 30 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 3469 42 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 1602 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 1632 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 1438 32 0 0
t$perf-norm:sp_cancel_supplier_order 450 450 0 0.00 0 86733 6403 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 4102 24 0 0
t$perf-norm:sp_client_order 20749 20749 0 0.00 0 9704 39 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 10972 32 0 0
t$perf-norm:sp_pay_from_customer 7066 7066 0 0.00 0 9846 27 0 0
t$perf-norm:sp_pay_to_supplier 5832 5832 0 0.00 0 6319 21 0 0
t$perf-norm:sp_reserve_write_off 10022 10022 0 0.00 0 15571 27 0 0
t$perf-norm:sp_supplier_invoice 5463 5463 0 0.00 0 8810 38 0 0
t$perf-norm:sp_supplier_order 5568 5568 0 0.00 0 9812 35 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 330 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 710 25 0 0
t$perf-norm:srv_make_invnt_saldo 437 437 0 0.00 0 3349 21 0 0
t$perf-norm:srv_make_money_saldo 972 972 0 0.00 0 3726 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 223 62 0 0
v_add_invoice_to_stock 10050 9854 196 1.95 0 11861 34 0 196
v_all_customers 37882 37882 0 0.00 0 66 4 0 0
v_all_suppliers 30540 30540 0 0.00 0 66 4 0 0
v_all_wares 41496 41496 0 0.00 0 59 4 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 1075 14 0 0
v_cancel_client_order 3430 3430 0 0.00 0 4818 15 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 4255 15 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 2367 14 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 2386 21 0 0
v_cancel_supplier_invoice 890 874 16 1.79 0 313 20 0 16
v_cancel_supplier_order 900 900 0 0.00 0 1068 11 0 0
v_cancel_write_off 1922 1922 0 0.00 0 9781 30 0 0
v_max_id_clo_ord 5567 5567 0 0.00 0 1899 5 0 0
v_max_id_clo_res 970 970 0 0.00 0 1192 12 0 0
v_max_id_ord_sup 5458 5458 0 0.00 0 6295 9 0 0
v_max_non_paid_invoice 1586 1586 0 0.00 0 773 8 0 0
v_max_non_paid_realizn 3729 3729 0 0.00 0 2695 8 0 0
v_min_id_clo_ord 5567 5567 0 0.00 0 2222 9 0 0
v_min_id_clo_res 970 970 0 0.00 0 612 8 0 0
v_min_id_ord_sup 5458 5458 0 0.00 0 7440 14 0 0
v_min_non_paid_invoice 5831 5831 0 0.00 0 3206 12 0 0
v_min_non_paid_realizn 7064 7064 0 0.00 0 6361 17 0 0
v_our_firm 10282 10282 0 0.00 0 58 5 0 0
v_reserve_write_off 20042 20042 0 0.00 0 10791 15 0 0
x_lock_depdocs_on_canc_invoice 11514 11514 0 0.00 0 19289 128 0 0
x_lock_depdocs_on_canc_sup_ord 450 450 0 0.00 0 4029 301 0 0
x_make_qstorno_1000_1200 5568 5568 0 0.00 3 29318 1174 0 0
x_make_qstorno_1200_2000 5463 5463 0 0.00 3 77772 2972 0 0
x_make_qstorno_2100_3300 463382 463382 0 0.00 0 114950 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-06-2022 10:39:32 to 07-06-2022 10:39:36.

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
08:07:21 6 8192000000 8804491920 8818057216 8023952 0.097 3 1 2 0 8181632 96720 60960
08:08:23 29315 8192000000 9728445216 10028167168 806775552 8.965 100 84 168 1 835482224 7825600 20881072
08:09:56 15072 8192000000 9763745952 10075770880 841958144 9.319 100 89 178 0 874687760 11943840 20785776
08:11:16 7786 8192000000 9802610208 10120306688 871615024 9.616 100 91 180 1 913467952 20966288 20886640
08:12:28 10137 8192000000 9855320272 10185510912 910418144 10.001 100 82 164 0 964080320 35399344 18262832
08:13:41 4726 8192000000 9912735488 10257334272 950478736 10.396 100 85 169 0 1026461856 57980464 18002656
08:14:50 2100 8192000000 9948713472 10300444672 986281312 10.745 100 89 172 0 1068532624 64320448 17930864
08:15:55 1815 8192000000 10002709088 10360553472 1011761456 10.992 100 87 168 1 1116555088 88031808 16761824
08:16:58 2126 8192000000 10009105216 10383462400 1033430416 11.201 100 89 174 1 1134831872 83758896 17642560
08:18:02 1035 8192000000 10030277072 10400690176 1051393808 11.374 100 98 190 1 1149706704 78882160 19430736
08:19:04 12162 8192000000 10022472256 10400784384 1064983888 11.504 100 81 154 0 1140271056 59798160 15489008
08:20:17 1234 8192000000 10050595344 10432229376 1071876128 11.570 100 93 180 1 1173442176 83200592 18365456
08:21:19 1209 8192000000 10046561440 10433183744 1083305168 11.679 100 90 174 1 1173866208 71977520 18583520
08:22:22 1186 8192000000 10062823504 10449227776 1086973440 11.714 100 95 188 0 1188642128 83694624 17974064
08:23:24 1192 8192000000 10073688448 10457141248 1093911088 11.780 100 100 195 0 1195878608 82095408 19872112
08:24:26 5017 8192000000 10062659568 10448220160 1098036192 11.819 100 74 144 5 1184994144 72631264 14326688
08:25:32 3978 8192000000 10079053024 10467205120 1102186192 11.858 100 82 161 0 1201470336 83891024 15393120
08:26:37 1121 8192000000 10098705296 10493370368 1104025472 11.876 100 92 176 2 1219424192 96846720 18552000
08:27:39 1181 8192000000 10071934080 10464661504 1103685760 11.873 100 95 183 0 1191738368 70425440 17627168
08:28:41 1566 8192000000 10069615200 10455453696 1098055920 11.819 100 85 156 4 1183554336 69821616 15676800
08:29:45 1063 8192000000 10039807712 10426384384 1093520208 11.776 100 92 179 1 1160614480 48485168 18609104
08:30:47 1006 8192000000 10013421120 10396819456 1070687664 11.559 99 81 143 4 1134906032 48580720 15637648
08:31:49 3014 8192000000 9998515008 10377437184 1056538208 11.423 99 80 158 0 1113790368 41760736 15491424
08:32:56 1270 8192000000 9991539696 10372018176 1039137200 11.256 100 86 161 2 1118822112 63278208 16406704
08:33:58 1067 8192000000 9991994640 10369134592 1035305888 11.220 99 87 170 0 1114750528 62161904 17282736
08:35:00 1413 8192000000 10024792736 10411962368 1049049904 11.352 100 86 168 2 1145793936 80209056 16534976
08:36:02 948 8192000000 10001408224 10378153984 1049250240 11.353 99 92 174 1 1120233216 52716272 18266704
08:37:04 1185 8192000000 10007630576 10386972672 1054390624 11.403 100 81 150 2 1124624592 54956688 15277280
08:38:09 2385 8192000000 10020326080 10398609408 1071283376 11.564 100 84 162 0 1147879248 60447216 16148656
08:39:18 1068 8192000000 10042158592 10424020992 1079402320 11.642 100 86 156 3 1164836944 70228528 15206096
08:40:27 7666 8192000000 10046576208 10430865408 1091732672 11.759 100 79 144 0 1166268000 61042704 13492624
08:41:44 1897 8192000000 10083920336 10475171840 1098700496 11.825 100 76 145 0 1201663520 87408400 15554624
08:42:50 7825 8192000000 10092638240 10488328192 1116440544 11.993 100 76 139 1 1212697072 81441216 14815312
08:44:05 6298 8192000000 10157317920 10555547648 1137329344 12.190 100 75 144 0 1272784224 120208448 15246432
08:45:15 1733 8192000000 10200646016 10593067008 1139930416 12.215 100 86 168 0 1311404944 153030672 18443856
08:46:24 1287 8192000000 10205226608 10624180224 1153301040 12.340 100 96 184 1 1320639840 145946720 21392080
08:47:28 2053 8192000000 10220169808 10622865408 1158834416 12.392 100 83 160 0 1324007632 148313904 16859312
08:48:33 1392 8192000000 10249052752 10656780288 1165833840 12.458 100 96 182 1 1360571056 175463264 19273952
08:49:39 2362 8192000000 10236125952 10642558976 1172959296 12.524 100 87 163 1 1348032176 157678480 17394400
08:50:44 2214 8192000000 10259254416 10665644032 1166811664 12.467 100 94 183 1 1375977168 188341360 20824144
08:51:50 1275 8192000000 10293656352 10704424960 1164190912 12.443 100 96 189 0 1404194560 219917968 20085680
08:52:57 1242 8192000000 10311892144 10721419264 1184311856 12.630 100 92 176 0 1423181408 218853072 20016480
08:54:03 1877 8192000000 10350531312 10761220096 1184019696 12.628 100 90 168 0 1459876528 256696864 19159968
08:55:10 1985 8192000000 10373162992 10782023680 1177045936 12.563 100 95 172 3 1481705472 285269392 19390144
08:56:17 1599 8192000000 10355297120 10820325376 1171231360 12.508 100 94 181 0 1465060480 272304576 21524544
08:57:21 1241 8192000000 10385945648 10826375168 1180169968 12.592 100 95 188 3 1493568928 292326272 21072688
08:58:26 996 8192000000 10348971040 10789347328 1179229184 12.583 100 94 185 1 1455024352 253761120 22034048
08:59:30 959 8192000000 10331343696 10767347712 1182178624 12.611 100 96 189 2 1441381168 236083632 23118912
09:00:35 1136 8192000000 10336172496 10751004672 1163629056 12.437 100 78 151 0 1440455136 258392672 18433408
09:01:38 2497 8192000000 10381790176 10811105280 1177035088 12.563 100 93 180 1 1480977616 281170240 22772288
09:02:44 4592 8192000000 10416340560 10844504064 1178010352 12.572 100 63 126 0 1517169408 323425312 15733744
09:03:51 4812 8192000000 10438219008 10870439936 1166744880 12.466 100 66 126 1 1538154608 355323712 16086016
09:05:08 5137 8192000000 10466280416 10898149376 1183474976 12.623 100 57 114 0 1584246400 386743872 14027552
09:06:15 5517 8192000000 10485145856 10923884544 1176102352 12.554 100 48 96 0 1587755888 398860416 12793120
09:07:22 9996 8192000000 10438494896 10915184640 1173824144 12.533 100 44 86 0 1544921968 360444624 10653200
09:08:34 22895 8192000000 10376455184 10874617856 1159849952 12.402 100 30 60 2 1511957472 344186496 7921024
09:10:17 12046 8192000000 10293908656 10765029376 1150711744 12.316 99 35 70 0 1396098720 237023056 8363920
09:11:30 28711 8192000000 10118823904 10616643584 1150035664 12.310 100 11 20 2 1240510464 87556208 2918592
09:12:59 23933 8192000000 10105775264 10527756288 1155303168 12.359 100 62 116 1 1198510288 30382048 12825072
09:14:28 48726 8192000000 10093430720 10565935104 1131865072 12.139 98 11 22 1 1216885008 82626224 2393712
09:16:17 20991 8192000000 10078276496 10515714048 1109679936 11.929 97 31 58 1 1135228112 19720864 5827312
09:18:43 81436 8192000000 10104599808 10588377088 1142587312 12.240 100 25 50 1 1221559856 73454144 5518400
09:21:10 2956 8192000000 10118419376 10535862272 1132197360 12.142 99 86 163 0 1209400288 60011856 17191072
09:22:14 24544 8192000000 10081162784 10527387648 1133981872 12.159 99 25 49 1 1180008112 40694480 5331760
09:23:39 18793 8192000000 10109215472 10550538240 1139209632 12.208 100 41 82 0 1204596400 56952272 8434496
09:25:01 108418 8192000000 10067662352 10562899968 1098376256 11.822 96 19 38 2 1170718496 68085024 4257216
09:28:21 114667 8192000000 10053834496 10520981504 1086004960 11.705 95 17 34 1 1129153504 39952096 3196448
09:32:07 88960 8192000000 10132362368 10599239680 1139998976 12.216 100 9 18 0 1226197216 84145088 2053152
09:34:41 36577 8192000000 10075551584 10549727232 1112769328 11.959 98 24 47 1 1156275488 38671744 4834416
09:36:21 8594 8192000000 10128562096 10563743744 1129650608 12.118 100 53 104 1 1226570832 86500752 10419472
09:37:31 25241 8192000000 10176253232 10606174208 1144633872 12.259 100 55 110 0 1274265952 118013488 11618592
09:39:09 54002 8192000000 10176512448 10630701056 1145596000 12.268 99 23 46 3 1272739728 121584384 5559344
09:41:43 33868 8192000000 10142924048 10573627392 1145848384 12.271 100 30 59 3 1219484464 67837056 5799024
09:43:18 33744 8192000000 10103332464 10592038912 1135150528 12.170 99 16 32 0 1216739536 77809280 3779728
09:44:54 79432 8192000000 10110404368 10583560192 1143788512 12.251 100 19 38 2 1258133264 109685104 4659648
09:47:18 25580 8192000000 10194793904 10628911104 1151169344 12.320 100 55 109 0 1270323296 107848368 11305584
09:48:47 103313 8192000000 10102319072 10541625344 1124630976 12.071 98 36 71 1 1201539840 69965040 6943824
09:51:36 79981 8192000000 10057962144 10564247552 1127885232 12.101 99 5 10 0 1161576160 32737040 953888
09:53:56 55758 8192000000 10128493456 10578890752 1115973648 11.989 97 18 37 0 1159968720 40106416 3888656
09:57:19 52427 8192000000 10170394304 10633306112 1121214752 12.038 98 39 75 3 1273674672 143107440 9352480
09:59:12 47735 8192000000 10149981744 10613555200 1127044064 12.093 100 25 50 0 1251343808 118674896 5624848
10:01:40 80663 8192000000 10047750048 10489655296 1060111264 11.458 94 21 41 0 1090383680 26010768 4261648
10:04:03 31191 8192000000 10112028608 10546757632 1122028608 12.046 100 34 67 0 1198228128 69695168 6504352
10:05:37 41601 8192000000 10108202720 10567794688 1128289600 12.105 99 34 68 0 1204731664 69328080 7113984
10:07:21 112336 8192000000 10094936960 10546548736 1082996272 11.676 95 20 40 0 1148767776 61593920 4177584
10:11:22 78396 8192000000 10095142080 10522996736 1108579504 11.919 98 36 72 1 1142560944 26322352 7659088
10:13:41 111927 8192000000 10064493008 10546307072 1116806992 11.997 98 17 34 1 1210687488 90442576 3437920
10:16:45 21137 8192000000 10071597248 10524860416 1140393920 12.219 100 34 69 3 1173145952 25630384 7121648
10:18:08 46708 8192000000 10068623168 10538065920 1124954672 12.074 99 14 28 0 1193371120 65745664 2670784
10:20:45 78195 8192000000 10103822256 10569953280 1134246032 12.161 100 13 26 1 1210102736 72952400 2904304
10:23:07 91652 8192000000 10087208640 10538541056 1106873024 11.903 97 21 41 2 1194635728 82647344 5115360
10:25:40 39684 8192000000 10150052400 10600812544 1135018016 12.169 98 34 68 0 1231784048 88805920 7960112
10:27:20 37570 8192000000 10149016624 10597355520 1124018032 12.065 98 27 50 2 1210886000 81066816 5801152
10:29:05 32227 8192000000 10209716912 10664710144 1157189712 12.377 100 32 64 0 1298901584 134249008 7462864
10:30:41 17338 8192000000 10237434112 10672877568 1147041024 12.282 99 46 88 2 1319864992 163040256 9783712
10:32:01 44233 8192000000 10209716752 10674270208 1140732816 12.222 99 22 44 1 1303287968 157299104 5256048
10:33:45 62253 8192000000 10087231664 10625388544 1113541152 11.966 99 22 44 1 1196325424 77912672 4871600
10:35:56 101336 8192000000 9032291904 9267564544 189468464 2.260 17 11 22 0 216926256 25217312 2240480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-06-2022 10:39:38 to 07-06-2022 10:39:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-06-2022 10:39:55 to 07-06-2022 10:39:57.

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          222142
MON$OLDEST_ACTIVE               222143
MON$OLDEST_SNAPSHOT             222143
MON$NEXT_TRANSACTION            222143
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                       2076640
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
--- end of diff output ---

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

07.06.22 10:39:58. 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
2022-06-07 10:40:01 No severe PSQL-related problems occured
07.06.22 10:40:02. End of report.