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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16402464 kB
MemAvailable 31301688 kB
Buffers 20 kB
Cached 15196084 kB
SwapCached 2388 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-30 04:37:10
DTS_END                         2021-12-30 04:37:10.5740
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/51870
TRN_ID                          234236
ATT_ID                          621
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: 8997.69 24589 1079723
customer order: creation 169.50 393 20340
customer order: refuse 14.37 312 1724
order to supplier: creation 45.23 1493 5427
order to supplier: removal 3.26 122934 391
invoice (draft): creation 45.19 3838 5423
invoice (draft): removal 49.77 1104 5972
invoice accept: apply 41.56 18669 4987
invoice accept: cancel 44.18 805 5301
customer reserve: creation 3910.78 180 469294
customer reserve: removal 4391.74 70 527009
realization accept: apply 83.03 157 9963
realization accept: cancel 72.38 126 8685
payment from customer: creation 58.83 102 7060
payment from customer: removal 5.28 63 634
payment to supplier: creation 48.13 112 5776
payment to supplier: removal 3.29 63 395
service: total inventory turnovers 3.94 3455 473
service: total monetary turnovers 6.27 469 752
service: aggregate perf. data 0.94 12969 113
service: refresh index statistics 0.03 324470 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-12-2021 04:37:55 to 30-12-2021 04:37:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10286
TEST_TIME 2 6390
TEST_TIME 3 6633
TEST_TIME 4 6845
TEST_TIME 5 6820
TEST_TIME 6 6233
TEST_TIME 7 6278
TEST_TIME 8 6299
TEST_TIME 9 6004
TEST_TIME 10 6564
TEST_TIME 11 6516
TEST_TIME 12 6844
TEST_TIME 13 7392
TEST_TIME 14 7673
TEST_TIME 15 7879
TEST_TIME 16 7747
TEST_TIME 17 8134
TEST_TIME 18 8425
TEST_TIME 19 8638
TEST_TIME 20 8453
TEST_TIME 21 9162
TEST_TIME 22 8874
TEST_TIME 23 8715
TEST_TIME 24 9297
TEST_TIME 25 9087
TEST_TIME 26 9207
TEST_TIME 27 9913
TEST_TIME 28 12140
TEST_TIME 29 11965
TEST_TIME 30 11674
TEST_TIME 31 11493
TEST_TIME 32 11440
TEST_TIME 33 11213
TEST_TIME 34 11000
TEST_TIME 35 10882
TEST_TIME 36 10815
TEST_TIME 37 10741
TEST_TIME 38 10526
TEST_TIME 39 10470
TEST_TIME 40 10304
TEST_TIME 41 10158
TEST_TIME 42 10085
TEST_TIME 43 9947
TEST_TIME 44 9956
TEST_TIME 45 9969
TEST_TIME 46 9864
TEST_TIME 47 9828
TEST_TIME 48 9871
TEST_TIME 49 9815
TEST_TIME 50 9813
TEST_TIME 51 9807
TEST_TIME 52 9777
TEST_TIME 53 9739
TEST_TIME 54 9685
TEST_TIME 55 9826
TEST_TIME 56 9889
TEST_TIME 57 9950
TEST_TIME 58 9791
TEST_TIME 59 9705
TEST_TIME 60 9643
TEST_TIME 61 9545
TEST_TIME 62 9499
TEST_TIME 63 9466
TEST_TIME 64 9374
TEST_TIME 65 9314
TEST_TIME 66 9221
TEST_TIME 67 9315
TEST_TIME 68 9303
TEST_TIME 69 9350
TEST_TIME 70 9328
TEST_TIME 71 9330
TEST_TIME 72 9320
TEST_TIME 73 9349
TEST_TIME 74 9348
TEST_TIME 75 9277
TEST_TIME 76 9280
TEST_TIME 77 9206
TEST_TIME 78 9276
TEST_TIME 79 9248
TEST_TIME 80 9301
TEST_TIME 81 9194
TEST_TIME 82 9169
TEST_TIME 83 9123
TEST_TIME 84 9115
TEST_TIME 85 9035
TEST_TIME 86 9008
TEST_TIME 87 9003
TEST_TIME 88 8991
TEST_TIME 89 9038
TEST_TIME 90 9061
TEST_TIME 91 9095
TEST_TIME 92 9088
TEST_TIME 93 9056
TEST_TIME 94 9068
TEST_TIME 95 9114
TEST_TIME 96 9063
TEST_TIME 97 9146
TEST_TIME 98 9158
TEST_TIME 99 9170
TEST_TIME 100 9179
TEST_TIME 101 9203
TEST_TIME 102 9118
TEST_TIME 103 9065
TEST_TIME 104 9057
TEST_TIME 105 9010
TEST_TIME 106 8966
TEST_TIME 107 8984
TEST_TIME 108 8959
TEST_TIME 109 8963
TEST_TIME 110 8938
TEST_TIME 111 8930
TEST_TIME 112 8942
TEST_TIME 113 8920
TEST_TIME 114 8933
TEST_TIME 115 9021
TEST_TIME 116 9006
TEST_TIME 117 9003
TEST_TIME 118 9046
TEST_TIME 119 9041
TEST_TIME 120 9002

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-12-2021 04:37:59 to 30-12-2021 04:38:03.

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 1511123 1511123 0 0.00 0 18120 6 0 0
doc_list_biud 1511123 1511123 0 0.00 0 31143 31 0 0
sp_add_invoice_to_stock 5107 4987 120 2.34 42 189857 18670 0 120
sp_cancel_adding_invoice 5301 5301 0 0.00 4 73815 805 0 0
sp_cancel_client_order 1724 1724 0 0.00 4 14802 312 0 0
sp_cancel_customer_reserve 527009 527009 0 0.00 1 31146 70 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 3 1704 63 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 2672 63 0 0
sp_cancel_supplier_invoice 5987 5972 15 0.25 1 20523 1104 0 15
sp_cancel_supplier_order 391 391 0 0.00 10 1293644 122935 0 0
sp_cancel_write_off 8685 8685 0 0.00 1 12056 126 0 0
sp_client_order 20340 20340 0 0.00 4 29291 393 0 0
sp_customer_reserve 469294 469294 0 0.00 0 65763 181 0 0
sp_fill_shopping_cart 31183 31183 0 0.00 1 22855 53 0 0
sp_fill_shopping_cart_clo_res 469288 469288 0 0.00 0 16412 15 0 0
sp_get_clo_for_invoice 4973 4973 0 0.00 0 25869 382 0 0
sp_kill_cost_storno 15015 15015 0 0.00 0 5621 61 0 0
sp_kill_qstorno_ret_qs2qd 1069066 1069066 0 0.00 0 31061 39 0 0
sp_kill_qty_storno 564017 564017 0 0.00 0 31143 83 0 0
sp_lock_selected_doc 20563 20563 0 0.00 0 7334 4 0 0
sp_make_cost_storno 27771 27771 0 0.00 0 12289 48 0 0
sp_multiply_rows_for_pdistr 27771 27771 0 0.00 0 4619 26 0 0
sp_multiply_rows_for_qdistr 20340 20340 0 0.00 0 12191 178 0 0
sp_pay_from_customer 7060 7060 0 0.00 4 23227 103 0 0
sp_pay_to_supplier 5776 5776 0 0.00 5 15376 112 0 0
sp_payment_common 12836 12836 0 0.00 1 13948 16 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 0 14797 286 0 0
sp_qd_handle_on_invoice_upd_sts 10273 10273 0 0.00 0 14401 363 0 0
sp_qd_handle_on_reserve_upd_sts 9963 9963 0 0.00 0 11708 48 0 0
sp_reserve_write_off 9963 9963 0 0.00 7 11740 157 0 0
sp_supplier_invoice 5423 5423 0 0.00 7 110646 3838 0 0
sp_supplier_order 5427 5427 0 0.00 5 30848 1494 0 0
srv_aggregate_perf_data 113 113 0 0.00 2478 147660 12969 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1510 144759 48581 0 0
srv_find_qd_qs_mism 1030643 1030643 0 0.00 0 18119 5 0 0
srv_make_invnt_saldo 473 473 0 0.00 42 120698 3456 0 0
srv_make_money_saldo 752 752 0 0.00 1 12284 469 0 0
srv_recalc_idx_stat 4 4 0 0.00 190460 680596 324471 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4987 4987 0 0.00 0 25162 163 0 0
t$perf-norm:sp_cancel_adding_invoice 371 371 0 0.00 0 5789 65 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 8028 19 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 3717 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 7425 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 60 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 435 435 0 0.00 0 1918 16 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 22138 1732 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 960 5 0 0
t$perf-norm:sp_client_order 20340 20340 0 0.00 0 10399 20 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 2155 6 0 0
t$perf-norm:sp_pay_from_customer 7060 7060 0 0.00 0 7903 11 0 0
t$perf-norm:sp_pay_to_supplier 5776 5776 0 0.00 0 4922 9 0 0
t$perf-norm:sp_reserve_write_off 9963 9963 0 0.00 0 8879 12 0 0
t$perf-norm:sp_supplier_invoice 5423 5423 0 0.00 0 4359 17 0 0
t$perf-norm:sp_supplier_order 5427 5427 0 0.00 0 3392 14 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 439 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 447 9 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 3050 20 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 1774 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10180 9940 240 2.35 0 8008 17 0 240
v_all_customers 36952 36952 0 0.00 0 17 1 0 0
v_all_suppliers 30088 30088 0 0.00 0 20 1 0 0
v_all_wares 40678 40678 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 742 742 0 0.00 0 1694 8 0 0
v_cancel_client_order 3448 3448 0 0.00 0 3571 8 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 344 4 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 723 4 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 2289 9 0 0
v_cancel_supplier_invoice 900 870 30 3.33 0 2661 16 0 30
v_cancel_supplier_order 782 782 0 0.00 0 155 3 0 0
v_cancel_write_off 1964 1964 0 0.00 0 6556 20 0 0
v_max_id_clo_ord 5427 5427 0 0.00 0 238 1 0 0
v_max_id_clo_res 967 967 0 0.00 0 4045 19 0 0
v_max_id_ord_sup 5417 5417 0 0.00 0 8509 7 0 0
v_max_non_paid_invoice 1576 1576 0 0.00 0 78 2 0 0
v_max_non_paid_realizn 3816 3816 0 0.00 0 1592 3 0 0
v_min_id_clo_ord 5427 5427 0 0.00 0 3802 7 0 0
v_min_id_clo_res 967 967 0 0.00 0 6899 11 0 0
v_min_id_ord_sup 5417 5417 0 0.00 0 7965 12 0 0
v_min_non_paid_invoice 5776 5776 0 0.00 0 13944 13 0 0
v_min_non_paid_realizn 7060 7060 0 0.00 0 3168 8 0 0
v_our_firm 10214 10214 0 0.00 0 18 1 0 0
v_reserve_write_off 19926 19926 0 0.00 0 5452 7 0 0
x_lock_depdocs_on_canc_invoice 10838 10838 0 0.00 0 12614 111 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 4446 345 0 0
x_make_qstorno_1000_1200 5427 5427 0 0.00 2 30840 1416 0 0
x_make_qstorno_1200_2000 5423 5423 0 0.00 3 109955 3614 0 0
x_make_qstorno_2100_3300 469294 469294 0 0.00 0 65757 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-12-2021 04:38:04 to 30-12-2021 04:38:08.

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
02:07:14 5 8192000000 8798424880 8809664512 4478816 0.054 2 1 2 0 4578208 63616 35776
02:08:16 25442 8192000000 9718219696 10016210944 795601120 8.852 100 89 177 0 824686320 7357904 21727296
02:09:47 13375 8192000000 9753626272 10060263424 832215072 9.222 100 84 168 0 863254688 10923888 20115728
02:11:07 21039 8192000000 9791826048 10107289600 859135104 9.492 100 81 162 0 895060368 16893232 19032032
02:12:36 11275 8192000000 9855154784 10187591680 912563040 10.023 100 77 154 0 966808160 37530976 16714144
02:13:54 6111 8192000000 9922251216 10272133120 960276944 10.492 100 73 147 2 1031589136 56470608 14841584
02:15:04 4526 8192000000 9958024368 10313908224 998740080 10.866 100 76 152 1 1072017936 58158928 15118928
02:16:15 3833 8192000000 10014368224 10379395072 1033723088 11.204 100 75 149 0 1119141472 70758544 14659840
02:17:21 1424 8192000000 10044345040 10411352064 1053155056 11.391 100 92 184 0 1159583056 87669648 18758352
02:18:24 1511 8192000000 10024951664 10394284032 1066427824 11.518 100 89 178 0 1136357072 51542992 18386256
02:19:26 22676 8192000000 10015081168 10403901440 1075873088 11.608 100 51 102 0 1140882848 53722912 11286848
02:20:50 9373 8192000000 10002509392 10410274816 1082544720 11.672 100 41 81 0 1118938352 28202832 8190800
02:22:00 8234 8192000000 9998390304 10399739904 1090586512 11.748 100 30 60 0 1117659728 20956320 6116896
02:23:08 43421 8192000000 9995593200 10397868032 1092842176 11.770 100 44 87 2 1119187808 15665216 10680416
02:24:54 7189 8192000000 10012633744 10416644096 1098083280 11.819 100 31 61 1 1124735504 20302560 6349664
02:26:02 5384 8192000000 10006469488 10413228032 1096552752 11.805 100 34 68 1 1126613008 22749376 7310880
02:27:09 57489 8192000000 9976182128 10394857472 1038113152 11.247 96 31 62 1 1067131488 22064416 6953920
02:29:07 6700 8192000000 9955603952 10350174208 1025647808 11.127 97 37 73 1 1058032064 24041296 8342960
02:30:18 3782 8192000000 9938369792 10315669504 1022973216 11.101 100 41 80 1 1065410992 32733792 9703984
02:31:22 6392 8192000000 9937753760 10315669504 1002994736 10.908 98 26 52 2 1032524704 23085536 6444432
02:32:29 24438 8192000000 9936701504 10338263040 997743504 10.857 97 24 48 1 1030710480 27988704 4978272
02:33:54 5699 8192000000 9978409920 10360610816 1043652976 11.300 100 43 86 0 1078424864 25522464 9249424
02:35:01 4903 8192000000 9965565792 10352152576 1059006608 11.447 100 44 87 1 1079316960 9950336 10360016
02:36:06 84178 8192000000 9995695200 10388168704 1069672528 11.549 100 39 78 1 1093144512 14840160 8631824
02:38:36 6203 8192000000 10055994032 10450980864 1097555792 11.814 100 55 110 1 1170993040 62255056 11182192
02:39:53 19045 8192000000 10114448512 10516455424 1112309856 11.954 100 52 102 2 1204400832 81840112 10250864
02:41:18 6601 8192000000 10113042336 10511560704 1124654208 12.071 100 56 112 0 1223964784 87725040 11585536
02:42:30 14222 8192000000 10146441424 10566733824 1135252240 12.171 100 39 78 0 1261870608 117714496 8903872
02:43:48 13306 8192000000 10164110336 10582458368 1146329520 12.275 100 52 104 1 1275622736 118434720 10858496
02:45:06 16329 8192000000 10227531984 10637959168 1159086304 12.395 100 56 112 0 1332193056 160858128 12248624
02:46:27 14426 8192000000 10285319344 10692415488 1163960224 12.440 100 66 132 0 1385509600 207179104 14370272
02:47:51 6040 8192000000 10264190560 10673049600 1171225216 12.508 100 75 149 0 1368284464 179299024 17760224
02:49:01 4634 8192000000 10346818720 10751160320 1182332752 12.612 100 71 141 1 1437588816 239279232 15976832
02:50:09 1509 8192000000 10377470816 10804314112 1191063984 12.693 100 82 165 1 1478099184 268486000 18549200
02:51:17 2590 8192000000 10397814640 10798907392 1179209248 12.583 100 94 186 0 1492673536 291913232 21551056
02:52:25 3483 8192000000 10397289600 10796670976 1186833136 12.654 100 79 156 0 1492688368 288344192 17511040
02:53:33 2420 8192000000 10398611424 10793586688 1157853888 12.383 99 85 169 2 1489074896 311768672 19452336
02:54:41 2324 8192000000 10441628816 10849751040 1158801920 12.392 100 89 178 0 1531487792 351757376 20928496
02:55:48 5736 8192000000 10505780912 10927046656 1175226192 12.546 100 71 142 1 1595672864 403807776 16638896
02:57:15 5400 8192000000 10492599360 10902499328 1149774416 12.307 99 69 139 0 1588223664 421671312 16777936
02:58:29 11626 8192000000 10474415072 10930102272 1157066208 12.376 100 37 74 0 1610022000 442992848 9962944
03:00:00 11042 8192000000 10510790304 10934706176 1147645392 12.287 100 38 76 0 1603430912 445695168 10090352
03:01:13 13129 8192000000 10528000704 11023278080 1147007296 12.281 100 32 64 0 1654708288 498706464 8994528
03:02:34 6996 8192000000 10576324672 11037261824 1135956400 12.177 100 40 79 1 1671217648 525210496 10050752
03:03:56 42654 8192000000 10492021616 10983084032 1107904912 11.913 100 28 56 0 1699358048 582640272 8812864
03:05:40 10658 8192000000 10070055808 10611101696 1095223504 11.792 99 13 26 0 1211681584 113305664 3152416
03:06:53 41748 8192000000 10031095264 10453200896 1084024848 11.686 98 25 50 2 1110899232 21949312 4925072
03:08:47 57879 8192000000 10038637008 10506969088 1112643552 11.957 100 17 34 1 1163389408 47783712 2962144
03:10:45 75787 8192000000 10076907408 10517045248 1115392928 11.983 99 12 24 0 1152357504 34400416 2564160
03:13:01 118705 8192000000 10059945904 10512961536 1105031280 11.885 98 22 44 1 1175554960 65561264 4962416
03:16:11 90544 8192000000 10054445808 10521776128 1098122592 11.820 97 19 38 1 1138304016 36584096 3597328
03:19:13 48823 8192000000 10087103232 10537299968 1134347360 12.162 100 30 59 2 1207815296 66576832 6891104
03:21:03 103581 8192000000 10080310656 10592468992 1119861632 12.026 99 25 50 2 1169667936 44751712 5054592
03:23:57 56696 8192000000 10096427104 10534592512 1144175936 12.255 100 38 75 1 1226330784 73025552 9129296
03:25:54 72553 8192000000 10089533024 10526072832 1119440544 12.022 98 12 24 0 1159417312 37502800 2473968
03:28:08 106064 8192000000 10073119216 10530418688 1122753776 12.053 98 8 16 0 1152047168 27772752 1520640
03:30:55 96007 8192000000 10164624768 10646560768 1150683808 12.316 100 17 34 0 1227101936 73471600 2946528
03:34:02 144759 8192000000 10073061280 10512551936 1133210608 12.152 98 8 16 0 1162379584 27585648 1583328
03:37:29 67560 8192000000 10071210304 10509426688 1116621184 11.995 97 30 60 0 1138502720 16112752 5768784
03:39:48 43624 8192000000 10085453376 10552123392 1118878352 12.016 98 19 38 0 1185048400 62503792 3666256
03:41:39 43859 8192000000 10084340128 10530172928 1125073392 12.075 99 21 42 0 1190038032 60281872 4682768
03:43:45 82842 8192000000 10082340576 10620915712 1104214368 11.878 97 11 22 0 1191617424 84838480 2564576
03:46:11 43811 8192000000 10025235728 10449219584 1047437152 11.336 93 14 28 1 1078311600 28072560 2801888
03:48:03 77782 8192000000 10104393744 10574979072 1116202592 11.991 99 27 54 0 1183790112 61603808 5983712
03:50:24 77487 8192000000 10047081216 10524307456 1092788128 11.769 97 20 39 1 1145145712 48226496 4131088
03:52:42 47019 8192000000 10062045056 10503290880 1104370112 11.879 99 28 56 0 1154354160 44186848 5797200
03:54:30 33032 8192000000 10079680992 10500698112 1089544944 11.738 98 38 76 1 1154723728 57269360 7909424
03:56:03 49688 8192000000 10059642000 10483138560 1073030384 11.581 96 31 62 0 1135474304 56470304 5973616
03:58:10 96283 8192000000 10053924528 10516529152 1101169056 11.849 99 18 36 0 1139295248 34171776 3954416
04:00:47 52122 8192000000 10032803808 10494435328 1072732592 11.578 97 26 52 0 1139970192 62032784 5204816
04:03:21 62994 8192000000 10077458544 10544959488 1092461344 11.766 98 14 28 0 1174802656 79067808 3273504
04:06:08 67597 8192000000 10044749760 10500435968 1079912912 11.647 97 12 24 0 1143720000 61199120 2607968
04:08:19 34002 8192000000 10037789488 10466922496 1088833392 11.732 98 36 73 0 1117519456 20693664 7992400
04:10:08 37584 8192000000 10082739104 10508005376 1112444768 11.956 99 30 60 0 1165878416 47700096 5733552
04:11:46 68085 8192000000 10084833920 10532986880 1119293280 12.020 99 17 34 0 1187921424 65275072 3353072
04:13:56 87889 8192000000 10062060880 10539417600 1102540432 11.862 97 13 26 0 1160705376 55565648 2599296
04:16:24 103875 8192000000 10104055040 10553450496 1121196368 12.038 98 17 35 1 1176052832 51418208 3438256
04:19:09 104399 8192000000 10094296608 10533892096 1125285344 12.077 98 33 67 1 1173358176 41549984 6522848
04:21:54 46735 8192000000 10085353456 10550210560 1142760256 12.241 100 9 18 0 1195965280 51044592 2160432
04:23:41 60203 8192000000 10081094432 10539450368 1131940576 12.140 99 15 30 1 1177741520 42379200 3421744
04:25:45 69787 8192000000 10047474048 10521100288 1110066304 11.933 97 8 16 0 1149161984 37294208 1801472
04:28:22 39029 8192000000 10106321024 10556039168 1139737376 12.213 100 32 64 1 1191353728 44769104 6847248
04:30:01 38986 8192000000 10119264352 10552627200 1126007616 12.084 98 28 56 0 1189470464 57535584 5927264
04:31:43 53526 8192000000 10105953664 10571669504 1126930992 12.092 98 23 46 2 1209531568 78026672 4573904
04:33:37 50277 8192000000 10096811264 10532102144 1089927216 11.742 95 23 46 0 1152652656 57861760 4863680
04:35:29 102780 8192000000 9980652528 10430361600 990963648 10.791 87 27 54 0 1080490688 84136736 5390304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-12-2021 04:38:09 to 30-12-2021 04:38:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-12-2021 04:38:25 to 30-12-2021 04:38:27.

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          234708
MON$OLDEST_ACTIVE               234709
MON$OLDEST_SNAPSHOT             234709
MON$NEXT_TRANSACTION            234709
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                       2043872
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             796
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2697 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2697 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2697 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2697 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 ---
:93: 
:94: mscs-test-161.budzdorov.ru    Thu Dec 30 04:37:25 2021
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:96: 
--- end of diff output ---

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

30.12.21 04:38:28. 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-30 04:38:30 No severe PSQL-related problems occured
30.12.21 04:38:32. End of report.