Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.11.2021 10:37
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 16673116 kB
MemAvailable 31582432 kB
Buffers 0 kB
Cached 14981088 kB
SwapCached 0 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.1
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 2021-11-17 10:37:07
DTS_END                         2021-11-17 10:37:07.7650
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/60040
TRN_ID                          232241
ATT_ID                          607
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: 8858.21 22686 1062985
customer order: creation 167.61 443 20113
customer order: refuse 14.43 336 1732
order to supplier: creation 44.17 1336 5300
order to supplier: removal 3.45 108697 414
invoice (draft): creation 43.97 3445 5276
invoice (draft): removal 51.18 850 6142
invoice accept: apply 38.87 18655 4664
invoice accept: cancel 46.35 814 5562
customer reserve: creation 3601.40 183 432168
customer reserve: removal 4558.33 65 546999
realization accept: apply 81.59 180 9791
realization accept: cancel 83.47 114 10016
payment from customer: creation 56.78 118 6814
payment from customer: removal 5.67 71 680
payment to supplier: creation 47.84 135 5741
payment to supplier: removal 3.38 59 405
service: total inventory turnovers 3.33 3471 400
service: total monetary turnovers 5.48 586 657
service: aggregate perf. data 0.89 16160 107
service: refresh index statistics 0.03 298007 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-11-2021 10:38:14 to 17-11-2021 10:38:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5696
TEST_TIME 2 3399
TEST_TIME 3 3880
TEST_TIME 4 3961
TEST_TIME 5 4480
TEST_TIME 6 4609
TEST_TIME 7 4828
TEST_TIME 8 4954
TEST_TIME 9 5644
TEST_TIME 10 6203
TEST_TIME 11 6625
TEST_TIME 12 6886
TEST_TIME 13 7316
TEST_TIME 14 7579
TEST_TIME 15 7678
TEST_TIME 16 8024
TEST_TIME 17 8416
TEST_TIME 18 8512
TEST_TIME 19 9092
TEST_TIME 20 9109
TEST_TIME 21 9015
TEST_TIME 22 9264
TEST_TIME 23 9392
TEST_TIME 24 9797
TEST_TIME 25 10040
TEST_TIME 26 10462
TEST_TIME 27 11223
TEST_TIME 28 10969
TEST_TIME 29 10686
TEST_TIME 30 10376
TEST_TIME 31 10533
TEST_TIME 32 10504
TEST_TIME 33 10374
TEST_TIME 34 10177
TEST_TIME 35 10135
TEST_TIME 36 9935
TEST_TIME 37 9879
TEST_TIME 38 9692
TEST_TIME 39 9565
TEST_TIME 40 9843
TEST_TIME 41 9694
TEST_TIME 42 9595
TEST_TIME 43 9501
TEST_TIME 44 9419
TEST_TIME 45 9403
TEST_TIME 46 9567
TEST_TIME 47 9445
TEST_TIME 48 9473
TEST_TIME 49 9499
TEST_TIME 50 9523
TEST_TIME 51 9635
TEST_TIME 52 9619
TEST_TIME 53 9622
TEST_TIME 54 9702
TEST_TIME 55 9602
TEST_TIME 56 9518
TEST_TIME 57 9444
TEST_TIME 58 9401
TEST_TIME 59 9365
TEST_TIME 60 9345
TEST_TIME 61 9286
TEST_TIME 62 9194
TEST_TIME 63 9161
TEST_TIME 64 9137
TEST_TIME 65 9158
TEST_TIME 66 9111
TEST_TIME 67 9198
TEST_TIME 68 9248
TEST_TIME 69 9295
TEST_TIME 70 9281
TEST_TIME 71 9261
TEST_TIME 72 9305
TEST_TIME 73 9353
TEST_TIME 74 9303
TEST_TIME 75 9475
TEST_TIME 76 9394
TEST_TIME 77 9418
TEST_TIME 78 9317
TEST_TIME 79 9271
TEST_TIME 80 9228
TEST_TIME 81 9177
TEST_TIME 82 9125
TEST_TIME 83 9140
TEST_TIME 84 9183
TEST_TIME 85 9147
TEST_TIME 86 9166
TEST_TIME 87 9138
TEST_TIME 88 9169
TEST_TIME 89 9145
TEST_TIME 90 9136
TEST_TIME 91 9265
TEST_TIME 92 9269
TEST_TIME 93 9256
TEST_TIME 94 9277
TEST_TIME 95 9237
TEST_TIME 96 9177
TEST_TIME 97 9140
TEST_TIME 98 9099
TEST_TIME 99 9054
TEST_TIME 100 9038
TEST_TIME 101 8990
TEST_TIME 102 8986
TEST_TIME 103 8985
TEST_TIME 104 9014
TEST_TIME 105 8980
TEST_TIME 106 8960
TEST_TIME 107 8939
TEST_TIME 108 8974
TEST_TIME 109 9074
TEST_TIME 110 9064
TEST_TIME 111 9065
TEST_TIME 112 9012
TEST_TIME 113 8944
TEST_TIME 114 8995
TEST_TIME 115 8941
TEST_TIME 116 8926
TEST_TIME 117 8889
TEST_TIME 118 8885
TEST_TIME 119 8932
TEST_TIME 120 8864

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-11-2021 10:38:17 to 17-11-2021 10:38:21.

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 1464784 1464784 0 0.00 0 22137 7 0 0
doc_list_biud 1464781 1464781 0 0.00 0 67626 31 0 0
sp_add_invoice_to_stock 4793 4664 129 2.69 31 254910 18655 0 129
sp_cancel_adding_invoice 5562 5562 0 0.00 5 80909 815 0 0
sp_cancel_client_order 1732 1732 0 0.00 4 13984 337 0 0
sp_cancel_customer_reserve 546999 546999 0 0.00 1 67627 66 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 2 3158 71 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 1620 60 0 0
sp_cancel_supplier_invoice 6147 6142 5 0.08 1 22913 851 0 5
sp_cancel_supplier_order 414 414 0 0.00 6 1147167 108698 0 0
sp_cancel_write_off 10016 10016 0 0.00 1 19569 115 0 0
sp_client_order 20113 20113 0 0.00 4 62853 444 0 0
sp_customer_reserve 432168 432168 0 0.00 0 48199 183 0 0
sp_fill_shopping_cart 30678 30678 0 0.00 1 14175 46 0 0
sp_fill_shopping_cart_clo_res 432160 432160 0 0.00 0 32007 18 0 0
sp_get_clo_for_invoice 4653 4653 0 0.00 0 30763 436 0 0
sp_kill_cost_storno 16663 16663 0 0.00 0 9365 57 0 0
sp_kill_qstorno_ret_qs2qd 1110570 1110570 0 0.00 0 67603 35 0 0
sp_kill_qty_storno 585308 585308 0 0.00 0 67626 76 0 0
sp_lock_selected_doc 19921 19921 0 0.00 0 8866 5 0 0
sp_make_cost_storno 26999 26999 0 0.00 0 14848 56 0 0
sp_multiply_rows_for_pdistr 26998 26998 0 0.00 0 8822 31 0 0
sp_multiply_rows_for_qdistr 20113 20113 0 0.00 0 62827 242 0 0
sp_pay_from_customer 6814 6814 0 0.00 5 16564 119 0 0
sp_pay_to_supplier 5741 5741 0 0.00 5 17279 135 0 0
sp_payment_common 12555 12555 0 0.00 1 11305 25 0 0
sp_qd_handle_on_cancel_clo 1732 1732 0 0.00 0 13956 308 0 0
sp_qd_handle_on_invoice_upd_sts 10214 10214 0 0.00 0 67087 342 0 0
sp_qd_handle_on_reserve_upd_sts 9791 9791 0 0.00 0 15922 56 0 0
sp_reserve_write_off 9791 9791 0 0.00 7 17264 180 0 0
sp_supplier_invoice 5276 5276 0 0.00 6 105428 3446 0 0
sp_supplier_order 5300 5300 0 0.00 6 43364 1337 0 0
srv_aggregate_perf_data 107 107 0 0.00 2969 261815 16160 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2237 131231 49448 0 0
srv_find_qd_qs_mism 1018229 1018229 0 0.00 0 22136 6 0 0
srv_make_invnt_saldo 400 400 0 0.00 53 94485 3472 0 0
srv_make_money_saldo 657 657 0 0.00 1 13683 586 0 0
srv_recalc_idx_stat 4 4 0 0.00 208271 365151 298007 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 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 4664 4664 0 0.00 0 33587 189 0 0
t$perf-norm:sp_cancel_adding_invoice 371 371 0 0.00 0 4269 84 0 0
t$perf-norm:sp_cancel_client_order 1732 1732 0 0.00 0 6572 16 0 0
t$perf-norm:sp_cancel_customer_reserve 588 588 0 0.00 0 588 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 3704 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 3442 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 346 346 0 0.00 0 1077 17 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 17017 1524 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 7298 14 0 0
t$perf-norm:sp_client_order 20113 20113 0 0.00 0 27964 20 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 4547 11 0 0
t$perf-norm:sp_pay_from_customer 6814 6814 0 0.00 0 6751 6 0 0
t$perf-norm:sp_pay_to_supplier 5741 5741 0 0.00 0 15048 19 0 0
t$perf-norm:sp_reserve_write_off 9791 9791 0 0.00 0 8248 12 0 0
t$perf-norm:sp_supplier_invoice 5276 5276 0 0.00 0 7037 21 0 0
t$perf-norm:sp_supplier_order 5300 5300 0 0.00 0 25671 20 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 227 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 22 1 0 0
t$perf-norm:srv_make_invnt_saldo 400 400 0 0.00 0 1915 10 0 0
t$perf-norm:srv_make_money_saldo 657 657 0 0.00 0 1771 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9562 9304 258 2.69 0 6855 17 0 258
v_all_customers 36756 36756 0 0.00 0 18 1 0 0
v_all_suppliers 29442 29442 0 0.00 0 19 1 0 0
v_all_wares 40226 40226 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 742 742 0 0.00 0 3219 9 0 0
v_cancel_client_order 3464 3464 0 0.00 0 3638 5 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 1064 7 0 0
v_cancel_customer_reserve 1176 1176 0 0.00 0 1206 7 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 818 7 0 0
v_cancel_supplier_invoice 702 692 10 1.42 0 429 13 0 10
v_cancel_supplier_order 828 828 0 0.00 0 957 4 0 0
v_cancel_write_off 1884 1884 0 0.00 0 1711 14 0 0
v_max_id_clo_ord 5300 5300 0 0.00 0 1559 2 0 0
v_max_id_clo_res 933 933 0 0.00 0 1795 10 0 0
v_max_id_ord_sup 5265 5265 0 0.00 0 6723 8 0 0
v_max_non_paid_invoice 1585 1585 0 0.00 0 2048 4 0 0
v_max_non_paid_realizn 3667 3667 0 0.00 0 1961 3 0 0
v_min_id_clo_ord 5300 5300 0 0.00 0 8991 10 0 0
v_min_id_clo_res 933 933 0 0.00 0 6918 30 0 0
v_min_id_ord_sup 5265 5265 0 0.00 0 6559 7 0 0
v_min_non_paid_invoice 5741 5741 0 0.00 0 8822 19 0 0
v_min_non_paid_realizn 6814 6814 0 0.00 0 8064 20 0 0
v_our_firm 9764 9764 0 0.00 0 158 1 0 0
v_reserve_write_off 19582 19582 0 0.00 0 3793 5 0 0
x_lock_depdocs_on_canc_invoice 11358 11358 0 0.00 0 27514 116 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 15991 382 0 0
x_make_qstorno_1000_1200 5300 5300 0 0.00 2 43336 1250 0 0
x_make_qstorno_1200_2000 5276 5276 0 0.00 3 96608 3273 0 0
x_make_qstorno_2100_3300 432168 432168 0 0.00 0 45270 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-11-2021 10:38:22 to 17-11-2021 10:38:26.

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:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:12 23866 8192000000 9723092592 10021568512 800991344 8.906 100 83 166 0 828792912 7721984 20079584
08:09:48 31452 8192000000 9764928848 10074996736 840273136 9.303 100 91 182 0 875591120 13292032 22025952
08:11:24 17567 8192000000 9807921472 10127437824 872593264 9.626 100 85 170 1 917872256 26009088 19269904
08:12:47 9596 8192000000 9862201520 10194796544 909543616 9.993 100 73 146 0 965508496 39782448 16182432
08:14:00 8218 8192000000 9921921760 10265858048 962100416 10.510 100 81 161 2 1031408784 52319184 16989184
08:15:11 5241 8192000000 9964616832 10319499264 1001272048 10.891 100 86 173 0 1082312656 63627040 17413568
08:16:18 5916 8192000000 9991803296 10357948416 1032490192 11.192 100 83 166 0 1114984032 65568864 16924976
08:17:25 2189 8192000000 10007615872 10380869632 1054119888 11.400 100 91 176 6 1123001440 49530144 19351408
08:18:28 1774 8192000000 10038184960 10409684992 1064511568 11.500 100 91 181 2 1144976608 62257952 18207088
08:19:30 7945 8192000000 9975116208 10367750144 1065559168 11.510 100 49 96 4 1094845600 19641152 9645280
08:20:40 9293 8192000000 9995355360 10403905536 1075612112 11.606 100 25 50 2 1111444480 30963344 4869024
08:21:52 6742 8192000000 9983858400 10389770240 1083120672 11.677 100 24 48 0 1114575696 26569312 4885712
08:23:00 50526 8192000000 9992823888 10391769088 1083520704 11.681 100 31 63 1 1105973968 15077808 7375456
08:24:51 5526 8192000000 10020575760 10416553984 1089217168 11.735 100 32 62 4 1118530192 22799680 6513344
08:25:59 9192 8192000000 10009698800 10421800960 1090679088 11.749 100 23 47 1 1118266800 22783424 4804288
08:27:16 7511 8192000000 10007176944 10399756288 1082951312 11.676 100 43 87 3 1119382752 26783456 9647984
08:28:26 40451 8192000000 9991752928 10387496960 1021200496 11.084 95 37 73 1 1057593248 29104112 7288640
08:30:09 6621 8192000000 9958611328 10343976960 1008517056 10.961 98 43 80 5 1045368688 27736400 9115232
08:31:16 7265 8192000000 9949741344 10316623872 1019050768 11.063 99 43 88 0 1053069984 24840512 9178704
08:32:24 6746 8192000000 9924707360 10306433024 1001275872 10.891 98 45 88 3 1023237264 10423760 11537632
08:33:31 35539 8192000000 9946753776 10343628800 1027939008 11.149 100 19 38 0 1058270544 26550800 3780736
08:35:07 4131 8192000000 9971753296 10357313536 1047190272 11.334 100 44 88 2 1076226528 19811216 9225040
08:36:14 2964 8192000000 9977631168 10364882944 1057968928 11.437 100 53 107 1 1088510848 18558656 11983264
08:37:20 23682 8192000000 9992757856 10382237696 1076146432 11.611 100 40 80 0 1092186416 7571744 8468240
08:39:09 101528 8192000000 10052919728 10467094528 1107896832 11.913 100 27 54 1 1161051488 47762192 5392464
08:42:02 40522 8192000000 10103838848 10515648512 1125980256 12.083 100 35 70 1 1217308496 83734720 7593520
08:43:50 5315 8192000000 10141893792 10546331648 1138281664 12.199 100 59 114 1 1251974816 99874960 13818192
08:45:08 15815 8192000000 10192426656 10604478464 1159442560 12.398 100 43 84 0 1303097920 133941648 9713712
08:46:27 3191 8192000000 10237082960 10645536768 1142632736 12.240 99 66 129 1 1335714864 178508128 14574000
08:47:34 5156 8192000000 10242010000 10659983360 1160843920 12.411 100 54 107 2 1352414208 179829088 11741200
08:48:43 2904 8192000000 10243658144 10643345408 1157890864 12.384 100 79 156 0 1338818976 163105296 17822816
08:49:51 2427 8192000000 10278570816 10679484416 1162473488 12.426 100 74 147 0 1377325904 198937520 15914896
08:50:58 3005 8192000000 10257033856 10657001472 1140628464 12.221 99 83 164 0 1358575328 198470800 19476064
08:52:06 4459 8192000000 10264305360 10660413440 1148039888 12.291 99 76 152 0 1367078064 201607632 17430544
08:53:13 2235 8192000000 10267867200 10686382080 1121002464 12.036 98 61 121 1 1355784880 220713520 14068896
08:54:19 3107 8192000000 10321620720 10729369600 1140959664 12.225 100 66 132 0 1428795312 271942272 15893376
08:55:26 8611 8192000000 10323768800 10734002176 1144096272 12.254 99 59 118 0 1412696704 253371216 15229216
08:56:40 13949 8192000000 10289033440 10715709440 1133533632 12.155 97 39 77 1 1361980848 218940976 9506240
08:58:11 8095 8192000000 10300123504 10737766400 1152756480 12.335 100 36 73 1 1396971312 235629936 8584896
08:59:27 17952 8192000000 10321279024 10764066816 1142652160 12.240 100 35 69 0 1414929008 263819328 8457520
09:00:53 11481 8192000000 10321194800 10763341824 1130892752 12.130 100 24 48 0 1429453776 291901344 6659680
09:02:07 33320 8192000000 10281982160 10754068480 1111746496 11.949 99 23 46 1 1402612064 284689360 6176208
09:03:59 41573 8192000000 10062377808 10573254656 1107890160 11.912 100 15 30 0 1209394288 97975376 3528752
09:05:41 101994 8192000000 10057043984 10477260800 1070701504 11.559 96 25 50 0 1140855584 65073664 5080416
09:08:36 35607 8192000000 10076707280 10529193984 1123513936 12.060 100 22 44 0 1200045344 71737888 4793520
09:10:22 83959 8192000000 10083390048 10529050624 1129314464 12.115 99 13 26 0 1165909312 34027088 2567760
09:12:46 36483 8192000000 10097825488 10555437056 1141669952 12.231 100 22 42 2 1208402848 62187824 4545072
09:14:34 48617 8192000000 10139537568 10581934080 1151698240 12.325 100 23 46 0 1224201616 67540368 4963008
09:16:35 78155 8192000000 10086034640 10628419584 1136355936 12.181 99 22 44 0 1256192176 114440288 5395952
09:18:57 116727 8192000000 10101952912 10601533440 1120822048 12.035 98 9 18 0 1170059408 46903504 2333856
09:21:55 100020 8192000000 10097158848 10609643520 1134151584 12.160 99 20 40 2 1226680832 88294192 4235056
09:24:35 42470 8192000000 10120657728 10578063360 1160691872 12.410 100 29 56 2 1235552832 68442048 6418912
09:26:22 34866 8192000000 10141625424 10612846592 1158838208 12.392 100 35 69 0 1226452800 59851648 7762944
09:28:04 60538 8192000000 10131383120 10633129984 1142978656 12.244 99 8 16 0 1255010992 110173232 1859104
09:30:04 131231 8192000000 10102982512 10571472896 1119789376 12.025 96 23 46 0 1177409872 52822976 4797520
09:33:16 89957 8192000000 10112653984 10568073216 1148997072 12.300 99 15 30 0 1188676784 36570736 3108976
09:35:49 39140 8192000000 10103419552 10540691456 1150080224 12.310 99 37 74 0 1196342928 39030544 7232160
09:37:31 48803 8192000000 10128793152 10606993408 1172660944 12.522 100 10 20 0 1232928016 58155616 2111456
09:39:20 93273 8192000000 10117311872 10595061760 1151269424 12.321 98 10 20 0 1226606128 73266368 2070336
09:41:55 32919 8192000000 10086947504 10533699584 1108199392 11.915 96 24 49 1 1163451952 50029328 5223232
09:43:44 74587 8192000000 10071538224 10532904960 1037510080 11.241 92 20 40 0 1126809040 84123840 5175120
09:45:59 25359 8192000000 10065002992 10501419008 1097278256 11.812 99 39 78 1 1150726816 45445056 8003504
09:47:24 46029 8192000000 10095241952 10530586624 1077043872 11.619 96 39 78 0 1146960656 62159744 7757040
09:49:17 42210 8192000000 10116161328 10569039872 1086683952 11.711 98 42 83 1 1183765952 88022256 9059744
09:50:59 52239 8192000000 10120719936 10562711552 1097782624 11.817 98 23 46 0 1200322208 97446736 5092848
09:53:06 43664 8192000000 10071458384 10482638848 1079760000 11.645 97 35 70 1 1113196848 26587904 6848944
09:54:52 114486 8192000000 10039241584 10468319232 1102333040 11.860 99 37 74 0 1158411888 48253776 7825072
09:57:47 45058 8192000000 10053542864 10501050368 1092151024 11.763 98 12 24 0 1144242144 49085296 3005824
10:00:38 67990 8192000000 10037694000 10539839488 1093916032 11.780 99 8 16 0 1144917440 49031824 1969584
10:02:54 83497 8192000000 10073414112 10559668224 1083740432 11.683 97 24 48 0 1148088048 58912448 5435168
10:05:19 82142 8192000000 10107950144 10546716672 1109407312 11.927 99 18 36 0 1174207136 60848768 3951056
10:07:41 86420 8192000000 10080714304 10595184640 1126919632 12.092 99 19 38 0 1234547968 103404928 4223408
10:10:08 34354 8192000000 10096769280 10510647296 1092269120 11.764 97 44 87 1 1155618064 55028896 8320048
10:11:55 31265 8192000000 10067598752 10492551168 1118734000 12.015 99 36 72 0 1157353616 31286176 7333440
10:13:29 108532 8192000000 10039840464 10507247616 1122366080 12.049 99 16 32 1 1174534976 48973600 3195296
10:16:27 89831 8192000000 10076379616 10569420800 1140725968 12.222 100 13 26 0 1190256384 46851744 2678672
10:19:13 68125 8192000000 10090844928 10552422400 1146434816 12.276 100 16 32 1 1185950816 36209888 3306112
10:21:30 68205 8192000000 10089772480 10525536256 1128522464 12.107 98 24 47 2 1160175328 26481808 5171056
10:23:39 42878 8192000000 10158665328 10597126144 1152075648 12.329 100 25 50 1 1234764608 77524528 5164432
10:25:57 62478 8192000000 10085583616 10582695936 1144648624 12.259 100 9 18 0 1229566496 82873408 2044464
10:28:07 50480 8192000000 10085673664 10510970880 1105741360 11.892 96 27 54 1 1131076128 19480352 5854416
10:30:01 70615 8192000000 10127587360 10573266944 1124919600 12.073 99 50 100 0 1215643264 80514512 10209152
10:32:22 44797 8192000000 10062263824 10518274048 1113187520 11.963 98 9 18 0 1154297856 39146048 1964288
10:34:19 85390 8192000000 10120033680 10594652160 1123364576 12.059 98 9 18 0 1191414144 65709904 2339664
10:36:52 21930 8192000000 8872730304 9033695232 46568128 0.565 5 2 4 0 50337344 3540784 228432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-11-2021 10:38:28 to 17-11-2021 10:38:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 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 17-11-2021 10:38:43 to 17-11-2021 10:38:45.

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          232554
MON$OLDEST_ACTIVE               232555
MON$OLDEST_SNAPSHOT             232555
MON$NEXT_TRANSACTION            232555
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                       2047776
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

17.11.21 10:38:46. 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
2021-11-17 10:38:48 No severe PSQL-related problems occured
17.11.21 10:38:50. End of report.