Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.12.2021 16:44
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 16691812 kB
MemAvailable 31400756 kB
Buffers 0 kB
Cached 14949664 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 3.0.9
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-16 16:44:19
DTS_END                         2021-12-16 16:44:19.3200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51204
TRN_ID                          237442
ATT_ID                          638
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: 9078.37 23297 1089404
customer order: creation 168.20 415 20184
customer order: refuse 14.05 417 1686
order to supplier: creation 44.43 1411 5331
order to supplier: removal 3.23 113941 388
invoice (draft): creation 44.45 3739 5334
invoice (draft): removal 52.81 856 6337
invoice accept: apply 41.26 18670 4951
invoice accept: cancel 47.24 839 5669
customer reserve: creation 3796.90 185 455628
customer reserve: removal 4568.27 63 548193
realization accept: apply 83.17 200 9980
realization accept: cancel 86.77 107 10412
payment from customer: creation 58.69 149 7043
payment from customer: removal 5.38 71 646
payment to supplier: creation 47.88 172 5745
payment to supplier: removal 3.57 86 428
service: total inventory turnovers 4.17 1256 500
service: total monetary turnovers 7.03 24 843
service: aggregate perf. data 0.85 20226 102
service: refresh index statistics 0.03 303103 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-12-2021 16:45:12 to 16-12-2021 16:45:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3766
TEST_TIME 2 5262
TEST_TIME 3 4409
TEST_TIME 4 4446
TEST_TIME 5 4543
TEST_TIME 6 4647
TEST_TIME 7 5076
TEST_TIME 8 5360
TEST_TIME 9 5260
TEST_TIME 10 5625
TEST_TIME 11 5903
TEST_TIME 12 5952
TEST_TIME 13 6177
TEST_TIME 14 6533
TEST_TIME 15 6662
TEST_TIME 16 7077
TEST_TIME 17 7820
TEST_TIME 18 7980
TEST_TIME 19 8417
TEST_TIME 20 8459
TEST_TIME 21 8789
TEST_TIME 22 8986
TEST_TIME 23 8834
TEST_TIME 24 8928
TEST_TIME 25 9088
TEST_TIME 26 8973
TEST_TIME 27 9153
TEST_TIME 28 9627
TEST_TIME 29 10555
TEST_TIME 30 10821
TEST_TIME 31 10643
TEST_TIME 32 10593
TEST_TIME 33 10528
TEST_TIME 34 10243
TEST_TIME 35 10032
TEST_TIME 36 10008
TEST_TIME 37 9834
TEST_TIME 38 9783
TEST_TIME 39 9694
TEST_TIME 40 9735
TEST_TIME 41 9646
TEST_TIME 42 9507
TEST_TIME 43 9491
TEST_TIME 44 9376
TEST_TIME 45 9373
TEST_TIME 46 9233
TEST_TIME 47 9221
TEST_TIME 48 9188
TEST_TIME 49 9189
TEST_TIME 50 9081
TEST_TIME 51 9012
TEST_TIME 52 9020
TEST_TIME 53 8949
TEST_TIME 54 8906
TEST_TIME 55 8822
TEST_TIME 56 8722
TEST_TIME 57 8971
TEST_TIME 58 8910
TEST_TIME 59 8901
TEST_TIME 60 8888
TEST_TIME 61 9000
TEST_TIME 62 8972
TEST_TIME 63 9047
TEST_TIME 64 9019
TEST_TIME 65 8975
TEST_TIME 66 8968
TEST_TIME 67 9272
TEST_TIME 68 9269
TEST_TIME 69 9233
TEST_TIME 70 9247
TEST_TIME 71 9308
TEST_TIME 72 9234
TEST_TIME 73 9122
TEST_TIME 74 9126
TEST_TIME 75 9160
TEST_TIME 76 9139
TEST_TIME 77 9143
TEST_TIME 78 9125
TEST_TIME 79 9143
TEST_TIME 80 9113
TEST_TIME 81 9100
TEST_TIME 82 9073
TEST_TIME 83 9006
TEST_TIME 84 8917
TEST_TIME 85 8922
TEST_TIME 86 9114
TEST_TIME 87 9071
TEST_TIME 88 9083
TEST_TIME 89 9054
TEST_TIME 90 9097
TEST_TIME 91 9191
TEST_TIME 92 9225
TEST_TIME 93 9218
TEST_TIME 94 9223
TEST_TIME 95 9265
TEST_TIME 96 9237
TEST_TIME 97 9335
TEST_TIME 98 9304
TEST_TIME 99 9390
TEST_TIME 100 9363
TEST_TIME 101 9353
TEST_TIME 102 9291
TEST_TIME 103 9265
TEST_TIME 104 9198
TEST_TIME 105 9174
TEST_TIME 106 9198
TEST_TIME 107 9163
TEST_TIME 108 9139
TEST_TIME 109 9103
TEST_TIME 110 9066
TEST_TIME 111 9107
TEST_TIME 112 9082
TEST_TIME 113 9038
TEST_TIME 114 9109
TEST_TIME 115 9119
TEST_TIME 116 9113
TEST_TIME 117 9115
TEST_TIME 118 9145
TEST_TIME 119 9139
TEST_TIME 120 9080

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-12-2021 16:45:15 to 16-12-2021 16:45:19.

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 1510627 1510627 0 0.00 0 35111 7 0 0
doc_list_biud 1510627 1510627 0 0.00 0 56984 29 0 0
sp_add_invoice_to_stock 5095 4951 144 2.82 29 174468 18671 0 144
sp_cancel_adding_invoice 5669 5669 0 0.00 4 60876 839 0 0
sp_cancel_client_order 1686 1686 0 0.00 4 13457 417 0 0
sp_cancel_customer_reserve 548193 548193 0 0.00 1 56986 63 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 3 6448 71 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 2 6206 87 0 0
sp_cancel_supplier_invoice 6350 6337 13 0.20 2 15459 857 0 13
sp_cancel_supplier_order 388 388 0 0.00 5 1073210 113942 0 0
sp_cancel_write_off 10412 10412 0 0.00 1 15294 108 0 0
sp_client_order 20184 20184 0 0.00 3 29063 416 0 0
sp_customer_reserve 455628 455628 0 0.00 0 54794 185 0 0
sp_fill_shopping_cart 30845 30845 0 0.00 1 22216 45 0 0
sp_fill_shopping_cart_clo_res 455621 455621 0 0.00 0 17021 15 0 0
sp_get_clo_for_invoice 4945 4945 0 0.00 0 19011 343 0 0
sp_kill_cost_storno 17155 17155 0 0.00 0 21681 60 0 0
sp_kill_qstorno_ret_qs2qd 1113523 1113523 0 0.00 0 56981 34 0 0
sp_kill_qty_storno 587610 587610 0 0.00 0 56984 75 0 0
sp_lock_selected_doc 20558 20558 0 0.00 0 7656 5 0 0
sp_make_cost_storno 27713 27713 0 0.00 0 35097 73 0 0
sp_multiply_rows_for_pdistr 27713 27713 0 0.00 0 16513 37 0 0
sp_multiply_rows_for_qdistr 20184 20184 0 0.00 0 22058 187 0 0
sp_pay_from_customer 7043 7043 0 0.00 4 16080 149 0 0
sp_pay_to_supplier 5745 5745 0 0.00 5 16559 173 0 0
sp_payment_common 12788 12788 0 0.00 2 10672 34 0 0
sp_qd_handle_on_cancel_clo 1686 1686 0 0.00 0 13449 386 0 0
sp_qd_handle_on_invoice_upd_sts 10614 10614 0 0.00 0 26903 369 0 0
sp_qd_handle_on_reserve_upd_sts 9980 9980 0 0.00 0 16577 62 0 0
sp_reserve_write_off 9980 9980 0 0.00 7 16820 200 0 0
sp_supplier_invoice 5334 5334 0 0.00 6 86688 3740 0 0
sp_supplier_order 5331 5331 0 0.00 5 61020 1411 0 0
srv_aggregate_perf_data 102 102 0 0.00 2341 145546 20227 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2483 175950 43507 0 0
srv_find_qd_qs_mism 1042189 1042189 0 0.00 0 12396 5 0 0
srv_make_invnt_saldo 500 500 0 0.00 44 88164 1256 0 0
srv_make_money_saldo 843 843 0 0.00 1 533 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 206826 503859 303103 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4951 4951 0 0.00 0 19554 206 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 0 8786 137 0 0
t$perf-norm:sp_cancel_client_order 1686 1686 0 0.00 0 4405 16 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 1462 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 11935 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 14617 39 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 792 16 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 29389 1870 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 5186 16 0 0
t$perf-norm:sp_client_order 20185 20185 0 0.00 0 15668 22 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 1842 11 0 0
t$perf-norm:sp_pay_from_customer 7043 7043 0 0.00 0 7536 12 0 0
t$perf-norm:sp_pay_to_supplier 5745 5745 0 0.00 0 10551 17 0 0
t$perf-norm:sp_reserve_write_off 9980 9980 0 0.00 0 14613 19 0 0
t$perf-norm:sp_supplier_invoice 5334 5334 0 0.00 0 13545 29 0 0
t$perf-norm:sp_supplier_order 5331 5331 0 0.00 0 6560 23 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 844 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 7 0 0 0
t$perf-norm:srv_make_invnt_saldo 500 500 0 0.00 0 3074 37 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 3219 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2 2 0 0
v_add_invoice_to_stock 10178 9890 288 2.82 0 5560 20 0 288
v_all_customers 36864 36864 0 0.00 0 18 1 0 0
v_all_suppliers 29610 29610 0 0.00 0 21 1 0 0
v_all_wares 40367 40367 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 728 9 0 0
v_cancel_client_order 3372 3372 0 0.00 0 4665 4 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 2781 6 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 4025 12 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 5221 10 0 0
v_cancel_supplier_invoice 846 820 26 3.07 0 7189 26 0 26
v_cancel_supplier_order 776 776 0 0.00 0 4160 12 0 0
v_cancel_write_off 2030 2030 0 0.00 0 318 11 0 0
v_max_id_clo_ord 5329 5329 0 0.00 0 1836 2 0 0
v_max_id_clo_res 943 943 0 0.00 0 6301 23 0 0
v_max_id_ord_sup 5332 5332 0 0.00 0 9458 5 0 0
v_max_non_paid_invoice 1601 1601 0 0.00 0 897 3 0 0
v_max_non_paid_realizn 3672 3672 0 0.00 0 10668 8 0 0
v_min_id_clo_ord 5329 5329 0 0.00 0 9034 14 0 0
v_min_id_clo_res 943 943 0 0.00 0 8380 27 0 0
v_min_id_ord_sup 5332 5332 0 0.00 0 5347 8 0 0
v_min_non_paid_invoice 5745 5745 0 0.00 0 4934 25 0 0
v_min_non_paid_realizn 7043 7043 0 0.00 0 9320 28 0 0
v_our_firm 10244 10244 0 0.00 0 18 1 0 0
v_reserve_write_off 19960 19960 0 0.00 0 4637 5 0 0
x_lock_depdocs_on_canc_invoice 11596 11596 0 0.00 0 13021 113 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 6508 360 0 0
x_make_qstorno_1000_1200 5331 5331 0 0.00 1 61013 1318 0 0
x_make_qstorno_1200_2000 5334 5334 0 0.00 2 83777 3574 0 0
x_make_qstorno_2100_3300 455628 455628 0 0.00 0 54792 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2021 16:45:20 to 16-12-2021 16:45:24.

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
14:14:23 25 8192000000 8807207024 8821379072 10345792 0.126 4 2 3 0 10489536 68800 74944
14:15:24 22276 8192000000 9886598128 10196877312 976824176 10.653 100 86 172 0 1005933984 5746112 23363696
14:16:50 27803 8192000000 9939472752 10261032960 1023283696 11.104 100 88 176 0 1053756256 7741840 22730720
14:18:23 26812 8192000000 9980479328 10315427840 1060657264 11.463 100 79 158 0 1089899376 9210976 20031136
14:19:54 14522 8192000000 10024032496 10373550080 1114313040 11.973 100 81 162 0 1144044448 10109696 19621712
14:21:11 9883 8192000000 10088689952 10447044608 1168282624 12.481 100 83 166 0 1199501712 12300496 18918592
14:22:25 5114 8192000000 10136424304 10503970816 1220943056 12.970 100 78 153 3 1250064400 12203328 16918016
14:23:33 3165 8192000000 10156221632 10530811904 1248812704 13.227 100 93 184 1 1284270576 15686624 19771248
14:24:38 2401 8192000000 10196624928 10579091456 1276786432 13.484 100 89 180 2 1309635856 13262144 19587280
14:25:44 1942 8192000000 10223364672 10607157248 1301902112 13.713 100 95 190 0 1336569984 13289440 21378432
14:26:47 18472 8192000000 10226476976 10615218176 1309572304 13.782 100 79 158 1 1337390368 9351680 18466384
14:28:06 4126 8192000000 10199185872 10620538880 1313888480 13.821 100 32 64 1 1328779264 7925488 6965296
14:29:11 9001 8192000000 10212949664 10630692864 1324514336 13.918 100 17 34 0 1334176768 5758320 3904112
14:30:26 8489 8192000000 10230352368 10645004288 1335611968 14.018 100 23 46 1 1347258336 5970496 5675872
14:31:35 38189 8192000000 10258287200 10658791424 1338431840 14.043 100 67 135 3 1360195488 5280368 16483280
14:33:13 3310 8192000000 10235005008 10656563200 1341025664 14.067 100 34 68 1 1355799280 7133616 7640000
14:34:18 8823 8192000000 10205645296 10629140480 1301260656 13.707 98 24 48 1 1313468160 6254160 5953344
14:35:32 41226 8192000000 10185148048 10585849856 1249015872 13.229 96 50 101 2 1266635712 5599056 12020784
14:37:16 4598 8192000000 10118752032 10506743808 1171330912 12.509 94 23 47 1 1181758880 4832704 5595264
14:38:21 8788 8192000000 10163871472 10569879552 1262587648 13.354 99 21 42 0 1273108544 5442416 5078480
14:39:30 7617 8192000000 10167788480 10576424960 1276065536 13.477 100 25 48 2 1285782672 4595840 5121296
14:40:38 53126 8192000000 10179558384 10594267136 1282208288 13.533 99 25 50 2 1293082560 4961808 5912464
14:42:32 9905 8192000000 10200279072 10615308288 1308775328 13.775 100 19 38 0 1318913888 5505040 4633520
14:43:44 13396 8192000000 10223973808 10632495104 1319622752 13.873 100 37 74 0 1334774144 5378944 9772448
14:45:04 7617 8192000000 10255497024 10664353792 1349203392 14.140 100 49 96 2 1367741200 7843696 10694112
14:46:15 26588 8192000000 10271484944 10691067904 1367080320 14.301 100 48 96 2 1395097824 16581008 11436496
14:47:47 25524 8192000000 10290931408 10714472448 1379535984 14.412 100 45 88 1 1404123280 13723584 10863712
14:49:15 40272 8192000000 10307880848 10730602496 1389740512 14.504 100 41 82 0 1416627360 17056032 9830816
14:50:59 3738 8192000000 10317111584 10740846592 1400759568 14.602 100 55 110 0 1431902576 18192736 12950272
14:52:07 12757 8192000000 10330974880 10753507328 1406252624 14.651 100 61 117 1 1439786384 19725920 13807840
14:53:24 5995 8192000000 10332149296 10752860160 1406688672 14.655 100 61 121 1 1443516688 22690128 14137888
14:54:41 2973 8192000000 10351869568 10768601088 1417359872 14.749 100 82 164 0 1462730576 25825104 19545600
14:55:53 4672 8192000000 10363977184 10785722368 1419995712 14.773 99 65 129 1 1464303472 28052608 16255152
14:57:03 4955 8192000000 10368027632 10783510528 1421095568 14.782 100 76 151 0 1471565408 32352624 18117216
14:58:18 2482 8192000000 10365513520 10786684928 1418063728 14.756 100 70 139 3 1475405216 39904192 17437296
14:59:25 7663 8192000000 10372885520 10794291200 1407819968 14.665 99 72 143 0 1466936064 39900704 19215392
15:00:45 5996 8192000000 10331447408 10736726016 1351967664 14.165 98 80 160 4 1413052896 40681264 20403968
15:01:58 3912 8192000000 10327774272 10737537024 1375613040 14.377 99 69 137 2 1429198288 36494464 17090784
15:03:09 2911 8192000000 10338760304 10749366272 1384635200 14.458 100 77 154 0 1443034560 39321120 19078240
15:04:17 10309 8192000000 10328278688 10744975360 1375773488 14.379 100 43 86 0 1424559408 37343024 11442896
15:05:40 19891 8192000000 10323203440 10755919872 1369734224 14.325 99 26 52 3 1414285296 37572720 6978352
15:07:03 5675 8192000000 10328415856 10759462912 1388204064 14.490 100 30 60 0 1432871232 36761776 7905392
15:08:14 9985 8192000000 10321962544 10745651200 1366496640 14.296 99 31 62 0 1417569904 42585552 8487712
15:09:26 19694 8192000000 10322237616 10753409024 1376800928 14.388 100 28 56 1 1428555136 43316768 8437440
15:10:49 24410 8192000000 10316690560 10747457536 1362835072 14.263 99 29 57 1 1425700560 54555232 8310256
15:12:15 19243 8192000000 10314897840 10760388608 1365166240 14.284 100 29 58 0 1427918544 53893632 8858672
15:13:39 35788 8192000000 10285362032 10731974656 1340569456 14.063 99 27 54 1 1392077936 44823504 6684976
15:15:16 20751 8192000000 10268122800 10716188672 1352908800 14.174 99 7 14 1 1366336960 12138576 1289584
15:17:06 53380 8192000000 10251171488 10699788288 1334446128 14.007 98 11 22 1 1350664752 13812960 2405664
15:19:31 57207 8192000000 10272644176 10720481280 1348714000 14.136 99 11 22 0 1363043152 11895392 2433760
15:22:01 37701 8192000000 10264363472 10709352448 1360562480 14.242 99 22 44 0 1375821488 10156208 5102800
15:23:41 77224 8192000000 10287588224 10743332864 1378492560 14.403 100 32 65 1 1398602240 12779376 7330304
15:26:05 43301 8192000000 10299771952 10746429440 1379786384 14.415 99 33 65 0 1394717008 7189776 7740848
15:27:53 36191 8192000000 10291824640 10759434240 1395517392 14.555 100 6 12 0 1407261104 10010192 1733520
15:29:35 58436 8192000000 10289466912 10746245120 1387387840 14.483 100 17 35 1 1400839872 9694192 3757840
15:31:35 46053 8192000000 10295164032 10749276160 1395048720 14.551 100 21 42 0 1405633632 6635088 3949824
15:33:22 46222 8192000000 10294875888 10761060352 1401913200 14.612 100 36 71 0 1417476816 7836400 7727216
15:35:17 24797 8192000000 10331757904 10774609920 1408355584 14.669 100 49 97 1 1433951152 14873328 10722240
15:36:44 28716 8192000000 10303819008 10767491072 1408844912 14.674 100 17 34 0 1422697760 9848080 4004768
15:38:14 41033 8192000000 10311066976 10776535040 1416069184 14.738 100 28 56 2 1436069152 13707888 6292080
15:39:59 76319 8192000000 10311142960 10774937600 1395938128 14.559 100 20 40 1 1419391360 17571952 5881280
15:42:16 53860 8192000000 10287631664 10745233408 1367305696 14.303 98 19 38 0 1378439776 7108240 4025840
15:44:10 72271 8192000000 10286991552 10753032192 1368911984 14.317 98 24 48 0 1386527472 12246560 5368928
15:46:23 81992 8192000000 10269534416 10736472064 1342175376 14.077 97 20 40 0 1363395456 17087424 4132656
15:48:47 46442 8192000000 10293532704 10736537600 1341822368 14.074 97 38 76 1 1367387920 17293568 8271984
15:50:34 51850 8192000000 10299668848 10756149248 1356133216 14.203 98 17 34 0 1384057824 23698752 4225856
15:52:30 175950 8192000000 10270696352 10726113280 1214675632 12.912 88 13 26 0 1238403248 20979328 2748288
15:56:32 52053 8192000000 10262332816 10706284544 1323812400 13.911 97 11 22 0 1341739040 15020304 2906336
15:58:29 63688 8192000000 10268555952 10691207168 1323358208 13.907 98 32 64 0 1344015696 13442240 7215248
16:00:39 26084 8192000000 10259029472 10700193792 1345705472 14.109 99 32 65 0 1361940592 8777632 7457488
16:02:08 9539 8192000000 10242532288 10684194816 1332148528 13.987 99 42 83 0 1358282064 15944112 10189424
16:03:20 30826 8192000000 10243108416 10693447680 1335724624 14.019 100 17 34 2 1356674144 16805936 4143584
16:05:15 66511 8192000000 10249034944 10691416064 1310113616 13.787 97 13 26 1 1322624576 9418768 3092192
16:07:22 65481 8192000000 10261418128 10705104896 1346018928 14.112 99 18 36 0 1366075296 15891072 4165296
16:09:56 69021 8192000000 10256314736 10736861184 1337865040 14.038 98 11 22 0 1362309312 21616688 2827584
16:12:41 63406 8192000000 10294046016 10739081216 1363547424 14.269 99 16 32 1 1376587424 9260560 3779440
16:14:44 108408 8192000000 10297610032 10761740288 1374437488 14.367 99 17 34 1 1397168768 18687824 4043456
16:17:33 77863 8192000000 10300927200 10749153280 1379758736 14.414 99 15 29 0 1392934768 9873584 3302448
16:19:51 43110 8192000000 10330648608 10769784832 1401991776 14.613 100 44 88 0 1425714288 13970240 9752272
16:21:37 109270 8192000000 10323892960 10790703104 1401827232 14.611 100 17 34 0 1426231520 20374896 4029392
16:24:27 56647 8192000000 10327190464 10770931712 1372304256 14.348 98 28 56 1 1399683824 21233264 6146304
16:26:29 98011 8192000000 10278641840 10727878656 1322550016 13.900 95 27 52 3 1335588096 7050560 5987520
16:29:19 73246 8192000000 10284343488 10747953152 1388729760 14.495 100 24 48 2 1405993664 11787392 5476512
16:31:32 41011 8192000000 10276072032 10737807360 1372786064 14.352 99 13 27 0 1383610464 7953584 2870816
16:33:24 77781 8192000000 10290656592 10753245184 1383227296 14.445 99 12 25 0 1396740880 10466016 3047568
16:36:05 71533 8192000000 10285441008 10740002816 1325783408 13.929 95 6 12 0 1339822576 12619408 1419760
16:38:34 52389 8192000000 10252838768 10705887232 1320920480 13.885 97 15 30 0 1333690736 9364416 3405840
16:40:32 67611 8192000000 10266562368 10712371200 1319187984 13.869 97 18 37 0 1335675280 12259120 4228176
16:42:43 71630 8192000000 10261645472 10697814016 1305246496 13.743 96 14 28 0 1317038688 8635760 3156432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-12-2021 16:45:26 to 16-12-2021 16:45:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-12-2021 16:45:42 to 16-12-2021 16:45:44.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          236136
MON$OLDEST_ACTIVE               237796
MON$OLDEST_SNAPSHOT             237796
MON$NEXT_TRANSACTION            237796
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2020672
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33545 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33545 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33545 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33545 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Dec 16 16:44:20 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Dec 16 16:44:21 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Dec 16 16:44:22 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Thu Dec 16 16:44:26 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Thu Dec 16 16:44:26 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
--- end of diff output ---

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

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