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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16132088 kB
MemAvailable 30763080 kB
Buffers 0 kB
Cached 15541596 kB
SwapCached 668 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.8
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-09-23 22:37:49
DTS_END                         2021-09-23 22:37:49.7910
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/56406
TRN_ID                          237392
ATT_ID                          710
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: 8753.38 28215 1050406
customer order: creation 165.65 395 19878
customer order: refuse 13.69 363 1643
order to supplier: creation 43.12 1442 5174
order to supplier: removal 3.22 114223 386
invoice (draft): creation 44.35 3661 5322
invoice (draft): removal 51.22 821 6146
invoice accept: apply 39.03 18164 4683
invoice accept: cancel 45.57 878 5468
customer reserve: creation 3604.89 177 432587
customer reserve: removal 4460.92 64 535310
realization accept: apply 79.27 234 9512
realization accept: cancel 80.11 112 9613
payment from customer: creation 56.41 156 6769
payment from customer: removal 5.21 140 625
payment to supplier: creation 46.02 172 5522
payment to supplier: removal 3.23 121 388
service: total inventory turnovers 4.18 1207 501
service: total monetary turnovers 6.48 32 778
service: aggregate perf. data 0.82 21034 98
service: refresh index statistics 0.03 400908 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-09-2021 22:38:53 to 23-09-2021 22:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5884
TEST_TIME 2 4448
TEST_TIME 3 3904
TEST_TIME 4 3836
TEST_TIME 5 3978
TEST_TIME 6 4042
TEST_TIME 7 4403
TEST_TIME 8 4409
TEST_TIME 9 4928
TEST_TIME 10 4908
TEST_TIME 11 5137
TEST_TIME 12 5182
TEST_TIME 13 5782
TEST_TIME 14 5676
TEST_TIME 15 5941
TEST_TIME 16 6113
TEST_TIME 17 6395
TEST_TIME 18 6461
TEST_TIME 19 6625
TEST_TIME 20 6808
TEST_TIME 21 6767
TEST_TIME 22 6975
TEST_TIME 23 7028
TEST_TIME 24 7427
TEST_TIME 25 8116
TEST_TIME 26 8109
TEST_TIME 27 8428
TEST_TIME 28 8865
TEST_TIME 29 9773
TEST_TIME 30 9835
TEST_TIME 31 10038
TEST_TIME 32 9845
TEST_TIME 33 9787
TEST_TIME 34 9624
TEST_TIME 35 9513
TEST_TIME 36 9356
TEST_TIME 37 9396
TEST_TIME 38 9341
TEST_TIME 39 9328
TEST_TIME 40 9244
TEST_TIME 41 9168
TEST_TIME 42 9030
TEST_TIME 43 8929
TEST_TIME 44 8959
TEST_TIME 45 8922
TEST_TIME 46 8844
TEST_TIME 47 8792
TEST_TIME 48 8855
TEST_TIME 49 8804
TEST_TIME 50 8761
TEST_TIME 51 8663
TEST_TIME 52 8601
TEST_TIME 53 8526
TEST_TIME 54 8593
TEST_TIME 55 8508
TEST_TIME 56 8584
TEST_TIME 57 8555
TEST_TIME 58 8576
TEST_TIME 59 8607
TEST_TIME 60 8566
TEST_TIME 61 8689
TEST_TIME 62 8621
TEST_TIME 63 8741
TEST_TIME 64 8829
TEST_TIME 65 8897
TEST_TIME 66 8920
TEST_TIME 67 8870
TEST_TIME 68 8855
TEST_TIME 69 9029
TEST_TIME 70 9001
TEST_TIME 71 9045
TEST_TIME 72 9021
TEST_TIME 73 8908
TEST_TIME 74 8872
TEST_TIME 75 8905
TEST_TIME 76 8867
TEST_TIME 77 8907
TEST_TIME 78 8863
TEST_TIME 79 8856
TEST_TIME 80 8825
TEST_TIME 81 8797
TEST_TIME 82 8734
TEST_TIME 83 8750
TEST_TIME 84 8734
TEST_TIME 85 8774
TEST_TIME 86 8784
TEST_TIME 87 8773
TEST_TIME 88 8785
TEST_TIME 89 8784
TEST_TIME 90 8800
TEST_TIME 91 8823
TEST_TIME 92 8855
TEST_TIME 93 8894
TEST_TIME 94 8918
TEST_TIME 95 8961
TEST_TIME 96 8975
TEST_TIME 97 8975
TEST_TIME 98 9046
TEST_TIME 99 9067
TEST_TIME 100 9034
TEST_TIME 101 8961
TEST_TIME 102 9028
TEST_TIME 103 8996
TEST_TIME 104 8935
TEST_TIME 105 8966
TEST_TIME 106 8944
TEST_TIME 107 8916
TEST_TIME 108 8884
TEST_TIME 109 8846
TEST_TIME 110 8858
TEST_TIME 111 8829
TEST_TIME 112 8814
TEST_TIME 113 8791
TEST_TIME 114 8726
TEST_TIME 115 8671
TEST_TIME 116 8729
TEST_TIME 117 8753
TEST_TIME 118 8730
TEST_TIME 119 8772
TEST_TIME 120 8755

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-09-2021 22:38:56 to 23-09-2021 22:39:00.

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 1452684 1452684 0 0.00 0 30886 7 0 0
doc_list_biud 1452683 1452683 0 0.00 0 31106 30 0 0
sp_add_invoice_to_stock 4791 4683 108 2.25 44 146339 18164 0 108
sp_cancel_adding_invoice 5468 5468 0 0.00 4 74851 878 0 0
sp_cancel_client_order 1643 1643 0 0.00 4 16906 363 0 0
sp_cancel_customer_reserve 535310 535310 0 0.00 1 31109 65 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 3 24358 141 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 4507 121 0 0
sp_cancel_supplier_invoice 6153 6146 7 0.11 1 21040 821 0 7
sp_cancel_supplier_order 386 386 0 0.00 7 957016 114224 0 0
sp_cancel_write_off 9613 9613 0 0.00 1 8871 112 0 0
sp_client_order 19878 19878 0 0.00 4 26421 396 0 0
sp_customer_reserve 432587 432587 0 0.00 0 80596 178 0 0
sp_fill_shopping_cart 30355 30355 0 0.00 1 20492 46 0 0
sp_fill_shopping_cart_clo_res 432581 432581 0 0.00 0 14988 13 0 0
sp_get_clo_for_invoice 4677 4677 0 0.00 0 23271 303 0 0
sp_kill_cost_storno 16094 16094 0 0.00 0 24346 59 0 0
sp_kill_qstorno_ret_qs2qd 1086765 1086765 0 0.00 0 31078 35 0 0
sp_kill_qty_storno 572755 572755 0 0.00 0 31105 76 0 0
sp_lock_selected_doc 19584 19584 0 0.00 0 7055 5 0 0
sp_make_cost_storno 26480 26480 0 0.00 0 27288 81 0 0
sp_multiply_rows_for_pdistr 26480 26480 0 0.00 0 24417 36 0 0
sp_multiply_rows_for_qdistr 19877 19877 0 0.00 0 14511 156 0 0
sp_pay_from_customer 6769 6769 0 0.00 4 25363 157 0 0
sp_pay_to_supplier 5522 5522 0 0.00 5 30874 173 0 0
sp_payment_common 12291 12291 0 0.00 1 25353 42 0 0
sp_qd_handle_on_cancel_clo 1643 1643 0 0.00 0 16899 319 0 0
sp_qd_handle_on_invoice_upd_sts 10145 10145 0 0.00 0 30497 383 0 0
sp_qd_handle_on_reserve_upd_sts 9512 9512 0 0.00 0 22384 73 0 0
sp_reserve_write_off 9512 9512 0 0.00 6 30830 234 0 0
sp_supplier_invoice 5322 5322 0 0.00 6 84638 3661 0 0
sp_supplier_order 5174 5174 0 0.00 6 45590 1442 0 0
srv_aggregate_perf_data 98 98 0 0.00 3038 393246 21035 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1794 115893 47868 0 0
srv_find_qd_qs_mism 1006080 1006080 0 0.00 0 13684 5 0 0
srv_make_invnt_saldo 501 501 0 0.00 35 87555 1207 0 0
srv_make_money_saldo 778 778 0 0.00 0 9032 33 0 0
srv_recalc_idx_stat 3 3 0 0.00 270409 597197 400908 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4683 4683 0 0.00 0 13464 207 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 0 13514 184 0 0
t$perf-norm:sp_cancel_client_order 1643 1643 0 0.00 0 9450 22 0 0
t$perf-norm:sp_cancel_customer_reserve 615 615 0 0.00 0 3784 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 7190 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 7870 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1290 24 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 21268 1854 0 0
t$perf-norm:sp_cancel_write_off 935 935 0 0.00 0 3943 7 0 0
t$perf-norm:sp_client_order 19878 19878 0 0.00 0 7182 22 0 0
t$perf-norm:sp_customer_reserve 937 937 0 0.00 0 6897 17 0 0
t$perf-norm:sp_pay_from_customer 6769 6769 0 0.00 0 6102 15 0 0
t$perf-norm:sp_pay_to_supplier 5522 5522 0 0.00 0 8291 14 0 0
t$perf-norm:sp_reserve_write_off 9512 9512 0 0.00 0 10224 15 0 0
t$perf-norm:sp_supplier_invoice 5322 5322 0 0.00 0 11382 27 0 0
t$perf-norm:sp_supplier_order 5174 5174 0 0.00 0 7426 21 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 204 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 7 0 0 0
t$perf-norm:srv_make_invnt_saldo 501 501 0 0.00 0 1044 16 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 2932 19 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 9570 9354 216 2.25 0 11778 28 0 216
v_all_customers 36194 36194 0 0.00 0 21 1 0 0
v_all_suppliers 28852 28852 0 0.00 0 16 1 0 0
v_all_wares 39754 39754 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 1398 9 0 0
v_cancel_client_order 3286 3286 0 0.00 0 4903 8 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 5421 14 0 0
v_cancel_customer_reserve 1230 1230 0 0.00 0 1525 7 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 4493 13 0 0
v_cancel_supplier_invoice 836 822 14 1.67 0 4895 28 0 14
v_cancel_supplier_order 772 772 0 0.00 0 2360 8 0 0
v_cancel_write_off 1870 1870 0 0.00 0 1995 17 0 0
v_max_id_clo_ord 5173 5173 0 0.00 0 1768 2 0 0
v_max_id_clo_res 937 937 0 0.00 0 1982 14 0 0
v_max_id_ord_sup 5304 5304 0 0.00 0 5879 7 0 0
v_max_non_paid_invoice 1573 1573 0 0.00 0 7114 12 0 0
v_max_non_paid_realizn 3632 3632 0 0.00 0 9629 7 0 0
v_min_id_clo_ord 5173 5173 0 0.00 0 12622 14 0 0
v_min_id_clo_res 937 937 0 0.00 0 2477 8 0 0
v_min_id_ord_sup 5304 5304 0 0.00 0 12271 13 0 0
v_min_non_paid_invoice 5522 5522 0 0.00 0 8174 21 0 0
v_min_non_paid_realizn 6769 6769 0 0.00 0 25348 35 0 0
v_our_firm 9834 9834 0 0.00 0 12 1 0 0
v_reserve_write_off 19024 19024 0 0.00 0 5621 10 0 0
x_lock_depdocs_on_canc_invoice 11203 11203 0 0.00 0 21837 123 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 21642 467 0 0
x_make_qstorno_1000_1200 5174 5174 0 0.00 2 40347 1342 0 0
x_make_qstorno_1200_2000 5322 5322 0 0.00 3 81810 3497 0 0
x_make_qstorno_2100_3300 432587 432587 0 0.00 0 80595 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-09-2021 22:39:01 to 23-09-2021 22:39:05.

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
20:07:53 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:54 38577 8192000000 10385933840 10705981440 1425099936 14.818 100 89 178 0 1461068288 6578288 29390064
20:10:44 29120 8192000000 10456017776 10788810752 1503330368 15.505 100 94 186 1 1543006976 9261792 30414816
20:12:16 15502 8192000000 10499580352 10843643904 1552069936 15.928 100 81 163 0 1588114880 10230864 25814080
20:13:33 9996 8192000000 10589051680 10948452352 1644422176 16.717 100 80 161 1 1680518240 11555680 24540384
20:14:49 7374 8192000000 10651685696 11023417344 1708387184 17.255 100 74 148 1 1743847360 13612512 21847664
20:15:58 4382 8192000000 10718412960 11096834048 1777793968 17.831 100 73 146 1 1811055664 12393648 20868048
20:17:06 3284 8192000000 10762625168 11148390400 1829449120 18.255 100 77 151 3 1862061264 11499344 21112800
20:18:10 1722 8192000000 10800357488 11189886976 1862746368 18.526 100 83 163 2 1896800432 10688256 23365808
20:19:14 1526 8192000000 10814464368 11201380352 1871311392 18.595 100 91 182 2 1908612448 11918192 25382864
20:20:16 6781 8192000000 10792421600 11207499776 1870501632 18.588 100 38 76 2 1888711184 7718352 10491200
20:21:26 7509 8192000000 10777154864 11212394496 1880419824 18.668 100 27 55 3 1894580336 6212640 7947872
20:22:38 13061 8192000000 10792425760 11227832320 1886947664 18.721 100 22 44 0 1902901216 9911024 6042528
20:23:51 55516 8192000000 10819979280 11248033792 1897323552 18.805 100 41 83 1 1915244624 5501920 12419152
20:25:54 5472 8192000000 10831668000 11261759488 1911428448 18.918 100 33 66 1 1928528464 8062864 9037152
20:27:01 10606 8192000000 10807366352 11243446272 1882715760 18.687 99 14 28 1 1892657888 5877680 4064448
20:28:14 16540 8192000000 10767017792 11193122816 1830639856 18.265 98 35 72 2 1846857712 5994416 10223440
20:29:36 18459 8192000000 10710760192 11133886464 1705801664 17.234 93 18 36 1 1717644144 6212864 5629616
20:30:54 6196 8192000000 10667738800 11069882368 1689810832 17.100 95 34 68 2 1706312736 6517776 9984128
20:32:03 6761 8192000000 10740864320 11151462400 1801667600 18.028 99 27 54 0 1819073248 9491744 7913904
20:33:13 32963 8192000000 10748535232 11180834816 1831699328 18.273 99 30 60 1 1846802496 5269648 9833520
20:34:46 11781 8192000000 10771408016 11203395584 1858516352 18.491 100 26 51 2 1870803216 5834848 6452016
20:35:59 6981 8192000000 10798661520 11219255296 1880522528 18.669 100 28 57 1 1894415184 5509008 8383648
20:37:08 12773 8192000000 10811572064 11245916160 1900674496 18.832 100 13 26 0 1909174064 4623328 3876240
20:38:27 48586 8192000000 10856935696 11281321984 1931314384 19.077 100 29 59 0 1946368448 6390224 8663840
20:40:18 34895 8192000000 10870393360 11300397056 1946257472 19.197 100 55 110 3 1970871808 8055632 16558704
20:42:06 14705 8192000000 10891188320 11323281408 1961112592 19.315 100 43 86 1 1982923408 8713104 13097712
20:43:30 12447 8192000000 10905858896 11342901248 1977623808 19.446 100 39 78 1 1998659712 9622752 11413152
20:44:46 26257 8192000000 10919259200 11359989760 1968545408 19.374 99 38 76 3 1992177424 12029728 11602288
20:46:17 13285 8192000000 10936298352 11361615872 1987454016 19.524 100 60 120 0 2021674656 16684832 17535808
20:47:33 7085 8192000000 10949901216 11377254400 1998257344 19.609 100 61 122 1 2034075712 17654016 18164352
20:48:47 11840 8192000000 10945796384 11381800960 1989627232 19.541 99 44 88 0 2018676096 14940464 14108400
20:50:03 5431 8192000000 10910445712 11343998976 1968052688 19.370 100 58 116 1 2004062384 18407312 17602384
20:51:13 15816 8192000000 10902194416 11336269824 1938658512 19.136 98 57 114 0 1979110768 22633520 17818736
20:52:33 1793 8192000000 10925545152 11351724032 1967771968 19.368 100 78 154 2 2016652192 24800928 24079296
20:53:40 2440 8192000000 10917834992 11337977856 1954363872 19.261 100 73 146 0 2002961296 25776192 22821232
20:54:50 2272 8192000000 10929676272 11348811776 1950474672 19.230 99 89 179 3 2004005392 27133728 26396992
20:55:56 3813 8192000000 10942375232 11364954112 1975930736 19.432 100 71 141 3 2027346512 29896544 21519232
20:57:06 3125 8192000000 10920065328 11337039872 1949144160 19.220 100 73 146 0 2006983888 34400912 23438816
20:58:18 3178 8192000000 10955231472 11368062976 1973862352 19.416 100 86 171 0 2040189808 39609344 26718112
20:59:26 6618 8192000000 10950389120 11373772800 1959894496 19.305 99 63 123 0 2020335408 40435040 20005872
21:00:42 2485 8192000000 10968305440 11374723072 1970462928 19.389 100 94 189 1 2049801680 48139024 31199728
21:01:53 8223 8192000000 10960068752 11403776000 1986508080 19.516 100 57 114 1 2055806960 49558288 19740592
21:03:08 22195 8192000000 10936728736 11383169024 1956917200 19.282 99 31 62 2 2010923264 43209952 10796112
21:04:33 7876 8192000000 10943709088 11382857728 1963186288 19.331 100 42 84 3 2025173776 47358272 14629216
21:05:43 36054 8192000000 10907089120 11402477568 1955693744 19.272 100 23 46 1 2021788720 57680304 8414672
21:07:22 60649 8192000000 10894400176 11340980224 1930215136 19.069 99 27 54 0 1964158720 25569792 8373792
21:09:30 47287 8192000000 10880465632 11331596288 1948617920 19.215 100 24 48 0 1964032688 8783008 6631760
21:11:23 100735 8192000000 10886927200 11347062784 1943162160 19.172 99 15 30 0 1958581664 11319808 4099696
21:14:26 85292 8192000000 10879372688 11344244736 1937570928 19.127 99 11 22 0 1959374656 18575488 3228240
21:17:32 40132 8192000000 10884460768 11347079168 1963267456 19.332 100 23 45 1 1979610304 8884352 7458496
21:19:17 62715 8192000000 10927091888 11373342720 1986486672 19.516 100 27 54 0 2008644160 14941632 7215856
21:21:20 96777 8192000000 10907274432 11362836480 1941800240 19.161 98 16 32 0 1959315024 12204688 5310096
21:24:00 115893 8192000000 10915351216 11392290816 1984482592 19.500 100 17 34 0 2006877120 17262464 5132064
21:26:58 88094 8192000000 10911963456 11391864832 1973830208 19.416 99 8 16 0 1992164160 16136352 2197600
21:29:40 65106 8192000000 10898756192 11371393024 1953756624 19.256 98 15 30 1 1968928512 11288560 3883328
21:31:53 71972 8192000000 10909020608 11383463936 1980962992 19.472 99 15 30 0 1995290192 9550128 4777072
21:34:14 42737 8192000000 10885222272 11363622912 1930651808 19.072 97 8 16 1 1939199120 6721408 1825904
21:35:57 69055 8192000000 10888695696 11366154240 1940684976 19.152 98 24 48 2 1963346352 14977984 7683392
21:38:08 80877 8192000000 10897668960 11357995008 1926690704 19.040 97 13 26 0 1937457104 7553600 3212800
21:40:29 92109 8192000000 10863035456 11338686464 1853243984 18.448 94 16 32 0 1875618880 18134656 4240240
21:43:02 87105 8192000000 10859605216 11311472640 1843055488 18.366 94 32 64 1 1864408672 12327056 9026128
21:45:31 74165 8192000000 10864036992 11349078016 1932572576 19.087 100 27 54 0 1958865696 18343488 7949632
21:47:53 46481 8192000000 10877138768 11332087808 1893071920 18.771 97 27 54 1 1911147728 10365248 7710560
21:51:15 69650 8192000000 10862357632 11319189504 1884105296 18.698 97 31 62 1 1906090512 13314176 8671040
21:53:25 63105 8192000000 10838698432 11307921408 1865084256 18.544 96 32 61 3 1883254000 8998512 9171232
21:55:29 76532 8192000000 10843087696 11312279552 1893357680 18.773 98 10 20 0 1903919680 7557584 3004416
21:58:05 44186 8192000000 10838799248 11318763520 1925185488 19.028 100 4 8 0 1936331392 10172416 973488
22:00:18 63290 8192000000 10846963088 11332304896 1913978560 18.939 99 12 24 0 1935451920 18130928 3342432
22:02:22 67839 8192000000 10856346512 11321151488 1911107232 18.916 99 12 23 0 1921295584 7272704 2915648
22:04:30 28794 8192000000 10862625104 11319300096 1904733472 18.864 98 32 63 1 1921003088 7912368 8357248
22:05:59 42250 8192000000 10873225584 11333107712 1889420784 18.741 97 32 62 2 1906470336 8430512 8619040
22:07:48 73044 8192000000 10889848400 11348467712 1916287120 18.957 98 17 34 0 1935614384 14879712 4447552
22:10:02 68550 8192000000 10904149648 11363749888 1965555776 19.350 100 10 20 0 1981032208 12603968 2872464
22:12:13 53553 8192000000 10906017056 11375628288 1943868352 19.178 98 21 42 0 1962157648 12687968 5601328
22:14:09 39467 8192000000 10905353776 11363971072 1936695200 19.120 98 43 86 2 1959840144 10749376 12395568
22:15:54 81440 8192000000 10925586176 11389943808 1937184432 19.124 98 8 16 0 1958014976 18659584 2170960
22:18:24 113400 8192000000 10920536432 11405819904 1961581552 19.319 99 18 36 2 1986026720 19414800 5030368
22:22:38 82021 8192000000 10882255952 11370156032 1905147152 18.868 96 8 16 0 1919827072 12528720 2151200
22:25:37 69712 8192000000 10897533056 11373355008 1975788224 19.431 100 12 24 1 1990706000 11527312 3390464
22:28:32 11000 8192000000 10902329456 11358797824 1964538688 19.342 99 63 124 1 1988669424 6253072 17877664
22:29:51 83584 8192000000 10891857008 11366584320 1910656544 18.912 96 15 30 0 1926152512 11027872 4468096
22:32:16 91040 8192000000 10858886336 11342180352 1922153792 19.004 97 10 20 0 1943441808 18275664 3012352
22:34:48 29090 8192000000 10897215344 11361501184 1915824176 18.953 97 24 48 0 1930655808 7895312 6936320
22:36:19 50687 8192000000 10904706736 11346309120 1901495392 18.838 96 33 60 1 1915577184 6606240 7475552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-09-2021 22:39:06 to 23-09-2021 22:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-09-2021 22:39:22 to 23-09-2021 22:39:24.

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          235196
MON$OLDEST_ACTIVE               237596
MON$OLDEST_SNAPSHOT             237596
MON$NEXT_TRANSACTION            237596
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                       2022528
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

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

23.09.21 22:39:25. 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-09-23 22:39:27 No severe PSQL-related problems occured
23.09.21 22:39:28. End of report.