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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16677672 kB
MemAvailable 31437308 kB
Buffers 0 kB
Cached 14999100 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-12-16 13:37:07
DTS_END                         2021-12-16 13:37:07.5980
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/35992
TRN_ID                          218019
ATT_ID                          622
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: 8569.48 26394 1028338
customer order: creation 153.11 506 18373
customer order: refuse 12.79 375 1535
order to supplier: creation 41.56 1554 4987
order to supplier: removal 3.28 125690 393
invoice (draft): creation 40.82 3961 4898
invoice (draft): removal 51.30 1054 6156
invoice accept: apply 36.40 19707 4368
invoice accept: cancel 46.28 959 5554
customer reserve: creation 3389.57 192 406748
customer reserve: removal 4517.45 70 542094
realization accept: apply 74.24 201 8909
realization accept: cancel 87.58 126 10509
payment from customer: creation 52.41 142 6289
payment from customer: removal 4.93 96 591
payment to supplier: creation 44.30 142 5316
payment to supplier: removal 3.18 77 382
service: total inventory turnovers 3.85 3347 462
service: total monetary turnovers 5.57 492 668
service: aggregate perf. data 0.85 15866 102
service: refresh index statistics 0.03 353328 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-12-2021 13:38:23 to 16-12-2021 13:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4888
TEST_TIME 2 3727
TEST_TIME 3 3258
TEST_TIME 4 3686
TEST_TIME 5 3755
TEST_TIME 6 3605
TEST_TIME 7 3726
TEST_TIME 8 3910
TEST_TIME 9 3975
TEST_TIME 10 4063
TEST_TIME 11 4356
TEST_TIME 12 4864
TEST_TIME 13 5050
TEST_TIME 14 5341
TEST_TIME 15 5709
TEST_TIME 16 6103
TEST_TIME 17 6978
TEST_TIME 18 7429
TEST_TIME 19 7732
TEST_TIME 20 7758
TEST_TIME 21 8016
TEST_TIME 22 8428
TEST_TIME 23 8940
TEST_TIME 24 8889
TEST_TIME 25 9164
TEST_TIME 26 8967
TEST_TIME 27 9174
TEST_TIME 28 9829
TEST_TIME 29 9902
TEST_TIME 30 10050
TEST_TIME 31 10415
TEST_TIME 32 10327
TEST_TIME 33 10120
TEST_TIME 34 9949
TEST_TIME 35 9720
TEST_TIME 36 9559
TEST_TIME 37 9591
TEST_TIME 38 9772
TEST_TIME 39 9581
TEST_TIME 40 9583
TEST_TIME 41 9484
TEST_TIME 42 9474
TEST_TIME 43 9384
TEST_TIME 44 9255
TEST_TIME 45 9171
TEST_TIME 46 9114
TEST_TIME 47 9153
TEST_TIME 48 9071
TEST_TIME 49 8992
TEST_TIME 50 8947
TEST_TIME 51 8898
TEST_TIME 52 8808
TEST_TIME 53 8797
TEST_TIME 54 8703
TEST_TIME 55 8646
TEST_TIME 56 8661
TEST_TIME 57 8673
TEST_TIME 58 8719
TEST_TIME 59 8696
TEST_TIME 60 8661
TEST_TIME 61 8609
TEST_TIME 62 8769
TEST_TIME 63 8859
TEST_TIME 64 8899
TEST_TIME 65 8916
TEST_TIME 66 8948
TEST_TIME 67 9009
TEST_TIME 68 9081
TEST_TIME 69 9127
TEST_TIME 70 9123
TEST_TIME 71 9063
TEST_TIME 72 8957
TEST_TIME 73 9020
TEST_TIME 74 8955
TEST_TIME 75 8913
TEST_TIME 76 8883
TEST_TIME 77 8838
TEST_TIME 78 8790
TEST_TIME 79 8739
TEST_TIME 80 8667
TEST_TIME 81 8703
TEST_TIME 82 8773
TEST_TIME 83 8763
TEST_TIME 84 8720
TEST_TIME 85 8771
TEST_TIME 86 8837
TEST_TIME 87 8851
TEST_TIME 88 8807
TEST_TIME 89 8823
TEST_TIME 90 8905
TEST_TIME 91 8957
TEST_TIME 92 8909
TEST_TIME 93 8826
TEST_TIME 94 8816
TEST_TIME 95 8772
TEST_TIME 96 8708
TEST_TIME 97 8731
TEST_TIME 98 8705
TEST_TIME 99 8717
TEST_TIME 100 8687
TEST_TIME 101 8721
TEST_TIME 102 8677
TEST_TIME 103 8713
TEST_TIME 104 8717
TEST_TIME 105 8750
TEST_TIME 106 8716
TEST_TIME 107 8787
TEST_TIME 108 8842
TEST_TIME 109 8852
TEST_TIME 110 8810
TEST_TIME 111 8758
TEST_TIME 112 8760
TEST_TIME 113 8730
TEST_TIME 114 8678
TEST_TIME 115 8642
TEST_TIME 116 8611
TEST_TIME 117 8622
TEST_TIME 118 8664
TEST_TIME 119 8637
TEST_TIME 120 8571

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-12-2021 13:38:27 to 16-12-2021 13:38:30.

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 1406232 1406232 0 0.00 0 23765 7 0 0
doc_list_biud 1406232 1406232 0 0.00 0 32573 34 0 0
sp_add_invoice_to_stock 4470 4368 102 2.28 42 213647 19707 0 102
sp_cancel_adding_invoice 5554 5554 0 0.00 6 85154 960 0 0
sp_cancel_client_order 1535 1535 0 0.00 4 8493 376 0 0
sp_cancel_customer_reserve 542094 542094 0 0.00 1 19049 70 0 0
sp_cancel_pay_from_customer 591 591 0 0.00 2 7918 96 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 2060 78 0 0
sp_cancel_supplier_invoice 6168 6156 12 0.19 3 31125 1054 0 12
sp_cancel_supplier_order 393 393 0 0.00 7 1039799 125691 0 0
sp_cancel_write_off 10509 10509 0 0.00 1 17844 126 0 0
sp_client_order 18373 18373 0 0.00 4 29722 506 0 0
sp_customer_reserve 406748 406748 0 0.00 0 81769 192 0 0
sp_fill_shopping_cart 28240 28240 0 0.00 1 16589 44 0 0
sp_fill_shopping_cart_clo_res 406747 406747 0 0.00 0 20241 20 0 0
sp_get_clo_for_invoice 4365 4365 0 0.00 0 24645 438 0 0
sp_kill_cost_storno 17036 17036 0 0.00 0 23751 70 0 0
sp_kill_qstorno_ret_qs2qd 1101246 1101246 0 0.00 0 31118 39 0 0
sp_kill_qty_storno 579515 579515 0 0.00 0 32572 83 0 0
sp_lock_selected_doc 18381 18381 0 0.00 0 7868 6 0 0
sp_make_cost_storno 24879 24879 0 0.00 0 17653 69 0 0
sp_multiply_rows_for_pdistr 24879 24879 0 0.00 0 10628 38 0 0
sp_multiply_rows_for_qdistr 18373 18373 0 0.00 0 15422 267 0 0
sp_pay_from_customer 6289 6289 0 0.00 4 13675 143 0 0
sp_pay_to_supplier 5316 5316 0 0.00 5 10392 143 0 0
sp_payment_common 11605 11605 0 0.00 1 7819 27 0 0
sp_qd_handle_on_cancel_clo 1535 1535 0 0.00 0 8485 342 0 0
sp_qd_handle_on_invoice_upd_sts 9919 9919 0 0.00 0 32572 398 0 0
sp_qd_handle_on_reserve_upd_sts 8909 8909 0 0.00 0 18792 60 0 0
sp_reserve_write_off 8909 8909 0 0.00 6 18810 202 0 0
sp_supplier_invoice 4898 4898 0 0.00 6 105113 3962 0 0
sp_supplier_order 4987 4987 0 0.00 5 33851 1554 0 0
srv_aggregate_perf_data 102 102 0 0.00 3105 220056 15867 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1001 114646 48022 0 0
srv_find_qd_qs_mism 986594 986594 0 0.00 0 17650 5 0 0
srv_make_invnt_saldo 462 462 0 0.00 43 129955 3348 0 0
srv_make_money_saldo 668 668 0 0.00 1 12129 492 0 0
srv_recalc_idx_stat 4 4 0 0.00 260313 514576 353328 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4368 4368 0 0.00 0 12308 178 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 1 5679 116 0 0
t$perf-norm:sp_cancel_client_order 1535 1535 0 0.00 0 2260 17 0 0
t$perf-norm:sp_cancel_customer_reserve 586 586 0 0.00 0 5973 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 591 591 0 0.00 0 480 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 1883 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 344 344 0 0.00 0 3017 37 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 17837 1629 0 0
t$perf-norm:sp_cancel_write_off 903 903 0 0.00 0 6775 20 0 0
t$perf-norm:sp_client_order 18373 18373 0 0.00 0 16523 27 0 0
t$perf-norm:sp_customer_reserve 897 897 0 0.00 0 1398 8 0 0
t$perf-norm:sp_pay_from_customer 6289 6289 0 0.00 0 11582 16 0 0
t$perf-norm:sp_pay_to_supplier 5316 5316 0 0.00 0 6314 12 0 0
t$perf-norm:sp_reserve_write_off 8909 8909 0 0.00 0 15079 20 0 0
t$perf-norm:sp_supplier_invoice 4898 4898 0 0.00 0 19282 26 0 0
t$perf-norm:sp_supplier_order 4987 4987 0 0.00 0 6427 18 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 379 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 50 2 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 2324 11 0 0
t$perf-norm:srv_make_money_saldo 668 668 0 0.00 0 962 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 8934 8730 204 2.28 0 6076 18 0 204
v_all_customers 33658 33658 0 0.00 0 25 1 0 0
v_all_suppliers 27802 27802 0 0.00 0 32 1 0 0
v_all_wares 36744 36744 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 5642 18 0 0
v_cancel_client_order 3070 3070 0 0.00 0 2746 6 0 0
v_cancel_customer_prepayment 1182 1182 0 0.00 0 1656 9 0 0
v_cancel_customer_reserve 1172 1172 0 0.00 0 3864 14 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 1269 6 0 0
v_cancel_supplier_invoice 712 688 24 3.37 0 2236 14 0 24
v_cancel_supplier_order 786 786 0 0.00 0 2072 9 0 0
v_cancel_write_off 1806 1806 0 0.00 0 2658 16 0 0
v_max_id_clo_ord 4987 4987 0 0.00 0 766 2 0 0
v_max_id_clo_res 897 897 0 0.00 0 1031 8 0 0
v_max_id_ord_sup 4881 4881 0 0.00 0 3889 3 0 0
v_max_non_paid_invoice 1283 1283 0 0.00 0 309 2 0 0
v_max_non_paid_realizn 3241 3241 0 0.00 0 1361 3 0 0
v_min_id_clo_ord 4987 4987 0 0.00 0 3725 8 0 0
v_min_id_clo_res 897 897 0 0.00 0 6702 18 0 0
v_min_id_ord_sup 4881 4881 0 0.00 0 2994 6 0 0
v_min_non_paid_invoice 5316 5316 0 0.00 0 6958 16 0 0
v_min_non_paid_realizn 6289 6289 0 0.00 0 7814 21 0 0
v_our_firm 9182 9182 0 0.00 0 16 1 0 0
v_reserve_write_off 17818 17818 0 0.00 0 6504 6 0 0
x_lock_depdocs_on_canc_invoice 11366 11366 0 0.00 0 22178 123 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 20323 529 0 0
x_make_qstorno_1000_1200 4987 4987 0 0.00 2 33831 1473 0 0
x_make_qstorno_1200_2000 4898 4898 0 0.00 3 104745 3798 0 0
x_make_qstorno_2100_3300 406748 406748 0 0.00 0 81736 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2021 13:38:31 to 16-12-2021 13:38:35.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:11 3 8192000000 8798449840 8809664512 4478816 0.054 2 1 2 0 4578208 63616 35776
11:08:12 20238 8192000000 9720552080 10014162944 795974448 8.855 100 84 167 0 823239408 7288016 19976944
11:09:41 20066 8192000000 9758610432 10065764352 836019200 9.260 100 85 170 1 867536688 11147552 20369936
11:11:07 15453 8192000000 9795067472 10110595072 862952880 9.530 100 82 164 0 900383872 18492496 18938496
11:12:25 13824 8192000000 9852099904 10179981312 905907472 9.957 100 74 148 0 959502512 37288624 16306416
11:13:40 10474 8192000000 9923034384 10262814720 948598512 10.377 100 79 158 1 1030073600 64520832 16954256
11:14:59 8616 8192000000 9947148944 10299506688 984811520 10.731 100 84 164 2 1058094608 56303376 16979712
11:16:09 4115 8192000000 9996605312 10357428224 1019655744 11.069 100 80 158 1 1113298976 78096000 15547232
11:17:17 3739 8192000000 10015755488 10382032896 1044135024 11.304 100 84 167 1 1120334048 59141744 17057280
11:18:24 1294 8192000000 10066089200 10434306048 1056974336 11.428 100 93 186 0 1172215760 96477664 18763760
11:19:27 25841 8192000000 10014875344 10406268928 1070223856 11.554 100 55 110 0 1140087728 58569648 11294224
11:20:54 4933 8192000000 10009166720 10396975104 1082165392 11.668 100 49 98 1 1121854320 29420384 10268544
11:22:00 6592 8192000000 10004396416 10409631744 1086491232 11.709 100 39 79 1 1126638448 32054656 8092560
11:23:09 58219 8192000000 10017103696 10420912128 1092046064 11.762 100 45 90 0 1115922496 13901344 9975088
11:25:08 7723 8192000000 10008760768 10414706688 1095816528 11.798 100 30 60 1 1116920880 14496400 6607952
11:26:18 5004 8192000000 10011784208 10426941440 1104065200 11.876 100 46 90 2 1140158784 26707664 9385920
11:27:24 8341 8192000000 10008452192 10419871744 1084634192 11.692 99 21 42 1 1109434848 20171408 4629248
11:28:33 40161 8192000000 9985729504 10400972800 1072211312 11.573 98 48 96 3 1098513824 15837872 10464640
11:30:16 5133 8192000000 9982795376 10372980736 1050114240 11.362 99 49 98 0 1095032112 34713328 10204544
11:31:27 7657 8192000000 9930591888 10324209664 994822352 10.828 98 45 91 1 1033959376 29755920 9381104
11:32:35 6706 8192000000 9936092080 10324799488 1021239024 11.084 100 39 79 1 1050439680 20683520 8517136
11:33:43 35924 8192000000 9941745120 10342854656 1010747696 10.983 98 28 55 1 1036886624 20318064 5820864
11:35:20 6057 8192000000 9978048320 10369896448 1051391120 11.374 100 51 103 0 1093339440 30977280 10971040
11:36:28 6161 8192000000 9979289504 10365833216 1050879168 11.369 99 37 74 1 1081502400 21827056 8796176
11:37:37 41360 8192000000 10033372736 10421514240 1087973184 11.723 100 51 100 1 1122935328 25126288 9835856
11:39:24 114646 8192000000 10068365856 10476105728 1107559600 11.909 100 29 58 1 1181732656 67949632 6223424
11:42:38 17091 8192000000 10120854192 10531885056 1129305552 12.115 100 33 66 1 1237887760 101203472 7378736
11:43:59 21741 8192000000 10173730016 10582376448 1145295216 12.265 100 32 64 0 1277778816 124503696 7979904
11:45:25 14091 8192000000 10218581504 10627702784 1166236352 12.462 100 47 94 1 1328606608 150882624 11487632
11:46:42 10987 8192000000 10281126048 10703613952 1175091904 12.544 100 44 87 1 1390995632 205626400 10277328
11:48:03 4661 8192000000 10342562000 10747412480 1186075808 12.647 100 72 143 0 1440422592 238170256 16176528
11:49:22 3256 8192000000 10407969680 10821693440 1190016496 12.684 100 72 144 0 1513514288 306554112 16943680
11:50:33 6490 8192000000 10397509808 10822987776 1198659952 12.764 100 62 123 1 1496269872 282993712 14616208
11:51:48 3302 8192000000 10429110400 10869014528 1202720032 12.802 100 81 162 0 1532280512 311271264 18289216
11:52:58 2750 8192000000 10426142192 10840571904 1191414256 12.697 100 80 160 1 1522918960 312113728 19390976
11:54:07 1000 8192000000 10416095312 10853650432 1188517008 12.670 100 88 177 0 1522595600 313328064 20750528
11:55:11 20699 8192000000 10293527024 10725224448 1155486576 12.361 98 28 54 0 1394124832 231310080 7328176
11:56:45 20123 8192000000 10331446832 10761232384 1154474576 12.351 100 33 64 1 1415419840 253074192 7871072
11:58:09 15399 8192000000 10304377760 10771759104 1133898048 12.158 99 28 56 0 1429183312 287599248 7686016
11:59:27 22741 8192000000 10303381648 10748403712 1132074928 12.141 99 22 44 1 1405879552 267834352 5970272
12:01:06 2357 8192000000 10247567216 10691571712 1129951440 12.121 99 36 71 1 1341131360 202891824 8288096
12:02:14 18430 8192000000 10270217168 10744209408 1128044880 12.103 99 19 38 1 1354270144 221241872 4983392
12:03:38 14979 8192000000 10350210336 10784436224 1116722912 11.996 99 28 56 1 1428723792 304328096 7672784
12:05:03 12094 8192000000 10303514288 10736967680 1099888016 11.837 98 32 64 0 1401051792 293126816 8036960
12:06:27 38508 8192000000 10217154176 10637438976 1098898192 11.827 99 34 68 1 1290996176 184066464 8031520
12:08:06 8188 8192000000 10134760880 10555801600 1110211696 11.934 100 40 77 0 1230641120 112118640 8310784
12:09:24 44957 8192000000 10043070736 10462461952 1102528688 11.862 99 28 54 0 1128097824 19390608 6178528
12:11:12 96012 8192000000 10166288160 10610479104 1116809584 11.997 99 18 36 0 1214336320 93637984 3888752
12:14:05 89011 8192000000 10078249152 10565943296 1084671920 11.692 97 22 44 1 1218135920 128238192 5225808
12:17:33 47720 8192000000 10061049200 10498363392 1119820576 12.025 100 18 36 2 1176858192 53285552 3752064
12:19:32 33499 8192000000 10063082848 10488549376 1119211504 12.020 99 45 91 0 1148747024 19804256 9731264
12:21:14 31854 8192000000 10092810384 10521567232 1125673696 12.081 99 42 83 0 1195909840 61422528 8813616
12:22:51 42032 8192000000 10083610464 10546442240 1117170656 12.000 99 12 24 0 1187649248 67929168 2549424
12:24:34 64258 8192000000 10101945088 10587611136 1137161312 12.189 100 20 40 0 1206177440 64472448 4543680
12:26:40 58851 8192000000 10059637216 10515922944 1116973360 11.998 99 45 90 0 1163031104 36499072 9558672
12:28:52 65892 8192000000 10049292112 10497953792 1133737088 12.157 100 14 28 0 1156520784 19801984 2981712
12:31:02 52711 8192000000 10106205056 10569863168 1145473072 12.267 100 12 24 0 1224122544 76115888 2533584
12:32:56 50905 8192000000 10107146560 10576887808 1149570560 12.305 100 18 36 0 1234928304 81187264 4170480
12:35:01 67114 8192000000 10068636112 10522648576 1149150928 12.302 100 9 18 0 1207115216 56123072 1841216
12:37:26 60516 8192000000 10149003856 10617843712 1142483152 12.239 98 18 36 0 1225372848 79212208 3677488
12:39:38 57293 8192000000 10151816464 10598961152 1147217408 12.283 99 19 38 0 1225890048 74880992 3791648
12:41:45 57081 8192000000 10093536784 10558111744 1127238224 12.095 97 23 47 0 1173271744 41417424 4616096
12:43:42 33645 8192000000 10112694432 10558386176 1146888672 12.280 99 34 67 0 1211998096 58283088 6826336
12:45:19 47872 8192000000 10118382688 10593259520 1127972592 12.102 97 28 56 1 1188299616 54720944 5606080
12:47:09 71843 8192000000 10114141152 10574647296 1089443504 11.737 94 18 36 0 1159906288 66633904 3828880
12:49:22 92151 8192000000 10071579856 10512293888 1074668736 11.597 95 27 54 2 1113431424 33495408 5267280
12:52:00 25197 8192000000 10034200128 10470637568 1079493744 11.643 96 23 43 0 1113512304 29524640 4493920
12:53:50 85926 8192000000 10067143520 10509029376 1110431744 11.937 98 13 26 0 1164964080 52002144 2530192
12:56:16 66137 8192000000 10114827856 10583556096 1122416784 12.050 100 15 30 0 1212128672 86382448 3329440
12:58:43 78075 8192000000 10111508224 10607796224 1103443072 11.870 97 25 50 2 1214258672 105282272 5533328
13:01:19 88517 8192000000 10091719936 10523836416 1061126976 11.467 95 21 40 1 1111112624 45630912 4354736
13:03:48 51384 8192000000 10075673296 10508414976 1098122032 11.820 99 32 64 0 1148318752 43658672 6538048
13:06:00 32199 8192000000 10098826688 10545532928 1097606400 11.815 98 34 67 0 1173918496 69467280 6844816
13:07:32 103910 8192000000 10048760960 10504859648 1074033904 11.591 96 9 18 0 1142069280 66696112 1339264
13:10:17 73965 8192000000 10069781024 10496847872 1109325568 11.926 99 43 85 1 1170028992 52255152 8448272
13:12:34 71315 8192000000 10067453520 10516631552 1121897632 12.045 100 6 12 1 1162701952 39492368 1311952
13:15:02 110121 8192000000 10054267280 10536468480 1056112368 11.419 94 18 36 0 1122505712 62493968 3899376
13:17:52 89693 8192000000 10081839568 10553778176 1131738784 12.138 100 17 36 0 1185698480 50223488 3736208
13:20:24 73380 8192000000 10099548400 10533146624 1128612464 12.108 99 17 33 1 1194555648 62315296 3627888
13:22:39 56882 8192000000 10115434352 10540421120 1121110080 12.037 98 30 61 1 1167961472 41059248 5792144
13:24:41 79064 8192000000 10091288336 10566942720 1140616384 12.221 100 19 39 0 1259481584 114659056 4206144
13:27:01 69589 8192000000 10116438304 10557751296 1146333808 12.275 100 31 62 0 1206785136 54414416 6036912
13:29:19 22931 8192000000 10083333648 10545459200 1149161856 12.302 100 33 66 0 1197982432 42209984 6610592
13:30:46 46001 8192000000 10081644848 10538078208 1141410464 12.229 99 7 14 0 1182384208 39453600 1520144
13:32:59 78164 8192000000 10123164704 10600923136 1154279696 12.350 100 15 30 0 1221262208 63397248 3585264
13:35:23 94720 8192000000 10093101232 10532921344 1133567840 12.155 98 16 32 0 1188608736 51758576 3282320

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 13:38:37 to 16-12-2021 13:38:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-12-2021 13:38:53 to 16-12-2021 13:38:55.

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          216262
MON$OLDEST_ACTIVE               218208
MON$OLDEST_SNAPSHOT             218208
MON$NEXT_TRANSACTION            218208
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                       2032160
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2690 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2690 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2690 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2690 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 ---
:16: 
:17: mscs-test-161.budzdorov.ru    Thu Dec 16 13:37:12 2021
:18:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:19: 
:20: 
:21: mscs-test-161.budzdorov.ru    Thu Dec 16 13:37:12 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Thu Dec 16 13:37:12 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

16.12.21 13:38:56. 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-12-16 13:38:58 No severe PSQL-related problems occured
16.12.21 13:38:59. End of report.