Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.10.2021 04:39
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 15383256 kB
MemAvailable 30629540 kB
Buffers 20 kB
Cached 16287584 kB
SwapCached 3704 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-10-23 04:37:10
DTS_END                         2021-10-23 04:37:10.0630
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/37272
TRN_ID                          239573
ATT_ID                          670
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: 8769.23 22189 1052308
customer order: creation 173.76 404 20851
customer order: refuse 14.68 332 1761
order to supplier: creation 45.73 1444 5488
order to supplier: removal 3.33 128148 399
invoice (draft): creation 44.98 4172 5397
invoice (draft): removal 48.78 1065 5853
invoice accept: apply 40.38 18762 4846
invoice accept: cancel 43.94 989 5273
customer reserve: creation 3725.14 183 447017
customer reserve: removal 4341.61 77 520993
realization accept: apply 82.38 214 9885
realization accept: cancel 75.68 126 9082
payment from customer: creation 58.89 129 7067
payment from customer: removal 5.67 89 680
payment to supplier: creation 49.36 149 5923
payment to supplier: removal 3.60 109 432
service: total inventory turnovers 3.99 3611 479
service: total monetary turnovers 6.39 612 767
service: aggregate perf. data 0.93 16337 111
service: refresh index statistics 0.03 266827 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-10-2021 04:39:26 to 23-10-2021 04:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3417
TEST_TIME 2 2889
TEST_TIME 3 4753
TEST_TIME 4 5645
TEST_TIME 5 5269
TEST_TIME 6 5389
TEST_TIME 7 5474
TEST_TIME 8 5345
TEST_TIME 9 5617
TEST_TIME 10 5673
TEST_TIME 11 5717
TEST_TIME 12 6188
TEST_TIME 13 6407
TEST_TIME 14 6986
TEST_TIME 15 7053
TEST_TIME 16 7302
TEST_TIME 17 7602
TEST_TIME 18 7745
TEST_TIME 19 8006
TEST_TIME 20 8322
TEST_TIME 21 8389
TEST_TIME 22 8547
TEST_TIME 23 8502
TEST_TIME 24 8701
TEST_TIME 25 8870
TEST_TIME 26 9157
TEST_TIME 27 9396
TEST_TIME 28 9666
TEST_TIME 29 10783
TEST_TIME 30 11501
TEST_TIME 31 11192
TEST_TIME 32 11313
TEST_TIME 33 11349
TEST_TIME 34 11135
TEST_TIME 35 10937
TEST_TIME 36 10769
TEST_TIME 37 10662
TEST_TIME 38 10541
TEST_TIME 39 10340
TEST_TIME 40 10237
TEST_TIME 41 10115
TEST_TIME 42 10166
TEST_TIME 43 10133
TEST_TIME 44 10108
TEST_TIME 45 9978
TEST_TIME 46 9901
TEST_TIME 47 9844
TEST_TIME 48 9654
TEST_TIME 49 9529
TEST_TIME 50 9466
TEST_TIME 51 9364
TEST_TIME 52 9268
TEST_TIME 53 9149
TEST_TIME 54 9151
TEST_TIME 55 9091
TEST_TIME 56 9123
TEST_TIME 57 9018
TEST_TIME 58 8913
TEST_TIME 59 8864
TEST_TIME 60 8849
TEST_TIME 61 8828
TEST_TIME 62 8843
TEST_TIME 63 8875
TEST_TIME 64 8972
TEST_TIME 65 8966
TEST_TIME 66 9063
TEST_TIME 67 9064
TEST_TIME 68 9101
TEST_TIME 69 9130
TEST_TIME 70 9097
TEST_TIME 71 9235
TEST_TIME 72 9254
TEST_TIME 73 9312
TEST_TIME 74 9254
TEST_TIME 75 9161
TEST_TIME 76 9117
TEST_TIME 77 9054
TEST_TIME 78 9034
TEST_TIME 79 8995
TEST_TIME 80 9010
TEST_TIME 81 8961
TEST_TIME 82 8952
TEST_TIME 83 8938
TEST_TIME 84 8960
TEST_TIME 85 8937
TEST_TIME 86 8931
TEST_TIME 87 8936
TEST_TIME 88 8960
TEST_TIME 89 9031
TEST_TIME 90 9041
TEST_TIME 91 9009
TEST_TIME 92 9066
TEST_TIME 93 9051
TEST_TIME 94 9089
TEST_TIME 95 9121
TEST_TIME 96 9031
TEST_TIME 97 8963
TEST_TIME 98 8963
TEST_TIME 99 8945
TEST_TIME 100 8894
TEST_TIME 101 8875
TEST_TIME 102 8836
TEST_TIME 103 8848
TEST_TIME 104 8822
TEST_TIME 105 8833
TEST_TIME 106 8806
TEST_TIME 107 8789
TEST_TIME 108 8783
TEST_TIME 109 8820
TEST_TIME 110 8836
TEST_TIME 111 8904
TEST_TIME 112 8928
TEST_TIME 113 8980
TEST_TIME 114 8973
TEST_TIME 115 8994
TEST_TIME 116 8942
TEST_TIME 117 8899
TEST_TIME 118 8872
TEST_TIME 119 8831
TEST_TIME 120 8770

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-10-2021 04:39:30 to 23-10-2021 04:39:33.

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 1467104 1467104 0 0.00 0 23032 7 0 0
doc_list_biud 1467102 1467102 0 0.00 0 50849 35 0 0
sp_add_invoice_to_stock 4968 4846 122 2.45 37 147207 18763 0 122
sp_cancel_adding_invoice 5273 5273 0 0.00 6 79431 990 0 0
sp_cancel_client_order 1761 1761 0 0.00 5 13569 332 0 0
sp_cancel_customer_reserve 520993 520993 0 0.00 1 17631 78 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 14321 90 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 5492 109 0 0
sp_cancel_supplier_invoice 5864 5853 11 0.18 3 50854 1066 0 11
sp_cancel_supplier_order 399 399 0 0.00 7 1169861 128148 0 0
sp_cancel_write_off 9082 9082 0 0.00 1 9455 127 0 0
sp_client_order 20851 20851 0 0.00 4 43366 405 0 0
sp_customer_reserve 447017 447017 0 0.00 0 61883 184 0 0
sp_fill_shopping_cart 31731 31731 0 0.00 1 21095 57 0 0
sp_fill_shopping_cart_clo_res 447016 447016 0 0.00 0 27197 18 0 0
sp_get_clo_for_invoice 4844 4844 0 0.00 0 24745 408 0 0
sp_kill_cost_storno 15467 15467 0 0.00 0 14316 65 0 0
sp_kill_qstorno_ret_qs2qd 1057320 1057320 0 0.00 0 50847 42 0 0
sp_kill_qty_storno 558088 558088 0 0.00 0 50848 90 0 0
sp_lock_selected_doc 20499 20499 0 0.00 0 8180 4 0 0
sp_make_cost_storno 27719 27719 0 0.00 0 23015 63 0 0
sp_multiply_rows_for_pdistr 27717 27717 0 0.00 0 14884 35 0 0
sp_multiply_rows_for_qdistr 20851 20851 0 0.00 0 29998 213 0 0
sp_pay_from_customer 7067 7067 0 0.00 4 18043 130 0 0
sp_pay_to_supplier 5923 5923 0 0.00 5 14144 149 0 0
sp_payment_common 12990 12990 0 0.00 1 13220 35 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 0 13560 307 0 0
sp_qd_handle_on_invoice_upd_sts 10117 10117 0 0.00 0 31059 414 0 0
sp_qd_handle_on_reserve_upd_sts 9883 9883 0 0.00 0 18552 79 0 0
sp_reserve_write_off 9885 9885 0 0.00 7 18606 214 0 0
sp_supplier_invoice 5397 5397 0 0.00 7 80590 4172 0 0
sp_supplier_order 5488 5488 0 0.00 5 33192 1444 0 0
srv_aggregate_perf_data 111 111 0 0.00 2815 198204 16337 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1442 128071 41852 0 0
srv_find_qd_qs_mism 1005547 1005547 0 0.00 0 13664 6 0 0
srv_make_invnt_saldo 479 479 0 0.00 23 107711 3611 0 0
srv_make_money_saldo 767 767 0 0.00 1 15890 612 0 0
srv_recalc_idx_stat 4 4 0 0.00 156889 526245 266827 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4846 4846 0 0.00 0 6757 151 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 7841 124 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 2113 12 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 664 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 4405 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 2849 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 2048 23 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 25634 1516 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 662 3 0 0
t$perf-norm:sp_client_order 20853 20853 0 0.00 0 6806 19 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 1405 7 0 0
t$perf-norm:sp_pay_from_customer 7067 7067 0 0.00 0 15014 8 0 0
t$perf-norm:sp_pay_to_supplier 5923 5923 0 0.00 0 5084 9 0 0
t$perf-norm:sp_reserve_write_off 9885 9885 0 0.00 0 9853 12 0 0
t$perf-norm:sp_supplier_invoice 5398 5398 0 0.00 0 5814 17 0 0
t$perf-norm:sp_supplier_order 5488 5488 0 0.00 0 3897 15 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 507 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 37 2 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 462 8 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 926 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 8 0 0
v_add_invoice_to_stock 9930 9686 244 2.45 0 6145 19 0 244
v_all_customers 37566 37566 0 0.00 0 17 1 0 0
v_all_suppliers 30414 30414 0 0.00 0 84 1 0 0
v_all_wares 41702 41702 0 0.00 0 126 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1422 11 0 0
v_cancel_client_order 3522 3522 0 0.00 0 2126 5 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 3947 7 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 2935 5 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 3972 13 0 0
v_cancel_supplier_invoice 818 796 22 2.68 0 4123 22 0 22
v_cancel_supplier_order 798 798 0 0.00 0 3966 12 0 0
v_cancel_write_off 1982 1982 0 0.00 0 3155 16 0 0
v_max_id_clo_ord 5488 5488 0 0.00 0 523 2 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 3785 16 0 0
v_max_id_ord_sup 5392 5392 0 0.00 0 10059 9 0 0
v_max_non_paid_invoice 1596 1596 0 0.00 0 276 2 0 0
v_max_non_paid_realizn 3856 3856 0 0.00 0 4950 5 0 0
v_min_id_clo_ord 5488 5488 0 0.00 0 3777 10 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 8726 18 0 0
v_min_id_ord_sup 5392 5392 0 0.00 0 4671 11 0 0
v_min_non_paid_invoice 5923 5923 0 0.00 0 8738 19 0 0
v_min_non_paid_realizn 7067 7067 0 0.00 0 12976 31 0 0
v_our_firm 10558 10558 0 0.00 0 17 1 0 0
v_reserve_write_off 19764 19764 0 0.00 0 4214 6 0 0
x_lock_depdocs_on_canc_invoice 10728 10728 0 0.00 0 14549 135 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 7483 392 0 0
x_make_qstorno_1000_1200 5488 5488 0 0.00 2 33094 1345 0 0
x_make_qstorno_1200_2000 5397 5397 0 0.00 3 80534 3944 0 0
x_make_qstorno_2100_3300 447017 447017 0 0.00 0 61883 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2021 04:39:34 to 23-10-2021 04:39:38.

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:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:15 25953 8192000000 9718559184 10013270016 804686368 8.944 100 93 186 0 834508896 7769376 22053152
02:09:49 1300 8192000000 9751569200 10053283840 828827120 9.187 100 88 176 0 862520688 11458176 22235392
02:10:53 16711 8192000000 9788396224 10103508992 860057696 9.501 100 83 166 0 895358320 16171872 19128752
02:12:26 12016 8192000000 9847703504 10175254528 903253728 9.931 100 81 162 0 956684784 35843504 17587552
02:13:42 9046 8192000000 9893584096 10230566912 943349472 10.326 100 86 172 2 1007227408 45886976 17990960
02:14:56 5901 8192000000 9963190320 10313490432 981203168 10.696 100 73 146 0 1070134784 74087488 14844128
02:16:04 2869 8192000000 9995382128 10357235712 1013652304 11.011 100 80 159 2 1110115088 80783584 15679200
02:17:08 3667 8192000000 10010607456 10378670080 1041853680 11.282 100 90 179 0 1120297344 60281008 18162656
02:18:13 1268 8192000000 10035882064 10405548032 1059562144 11.452 100 92 186 0 1148043792 69400368 19081280
02:19:15 12089 8192000000 10020760704 10400108544 1066639616 11.520 100 78 161 2 1129438800 45916000 16883184
02:20:29 6116 8192000000 9990863776 10387087360 1081031440 11.657 100 37 68 2 1113728288 25894464 6802384
02:21:37 5984 8192000000 10035100896 10431123456 1087905904 11.723 100 51 102 0 1145716672 46988880 10821888
02:22:45 21255 8192000000 10012572880 10418741248 1101368784 11.851 100 21 41 2 1122021920 16237648 4415488
02:24:06 18511 8192000000 10016940128 10417876992 1099669648 11.835 100 42 83 0 1119656320 11056320 8930352
02:25:25 7142 8192000000 10024240192 10428567552 1101267376 11.850 100 42 83 0 1126616672 16782160 8567136
02:26:36 8535 8192000000 10001420032 10415271936 1087963264 11.723 99 29 58 0 1120419120 26026864 6428992
02:27:46 10004 8192000000 9974991488 10378084352 1071037984 11.562 100 31 63 1 1098807344 21564016 6205344
02:28:58 16522 8192000000 9970018640 10370232320 1013994384 11.014 95 22 43 0 1044996704 25790912 5211408
02:30:16 3300 8192000000 9982948448 10350784512 1001975568 10.898 97 48 93 0 1050699968 39610992 9113408
02:31:21 8520 8192000000 9953989440 10338779136 1021432688 11.086 100 40 79 2 1054574256 24714016 8427552
02:32:30 14960 8192000000 9945292784 10337579008 1031266576 11.181 99 21 42 1 1049414384 13550160 4597648
02:33:46 16818 8192000000 9960976992 10365882368 1035445296 11.221 99 13 26 0 1060242688 22283200 2514192
02:35:03 7120 8192000000 9985822832 10390077440 1056962656 11.427 100 50 97 2 1096810112 29334848 10512608
02:36:14 8840 8192000000 9998614800 10394972160 1071108240 11.563 100 49 98 2 1100173712 18427984 10637488
02:37:26 61624 8192000000 10032159184 10420740096 1098283632 11.821 100 48 95 3 1129026112 20554848 10187632
02:39:30 42035 8192000000 10073698736 10481737728 1106975888 11.904 100 32 64 0 1172582944 59173328 6433728
02:41:15 2913 8192000000 10085786608 10495324160 1120817504 12.035 100 50 100 2 1196595760 65007936 10770320
02:42:35 16270 8192000000 10130006800 10536660992 1139968784 12.215 100 50 100 3 1229750288 78698928 11082576
02:43:57 3696 8192000000 10163675200 10563674112 1151323616 12.322 100 59 119 1 1259706480 95312288 13070576
02:45:14 9773 8192000000 10180657072 10595336192 1162505376 12.427 100 37 74 2 1286790192 116259856 8024960
02:46:33 3936 8192000000 10226282576 10631376896 1164690880 12.447 100 65 130 0 1328607712 149576352 14340480
02:47:41 18628 8192000000 10252505008 10664943616 1164119104 12.442 100 56 110 1 1353936640 177745376 12072160
02:49:09 3887 8192000000 10293614496 10713153536 1174739920 12.541 100 68 135 1 1400306432 210089856 15476656
02:50:16 4804 8192000000 10316943104 10721017856 1161002272 12.413 99 77 156 0 1409455200 231005552 17447376
02:51:25 3044 8192000000 10345717120 10752331776 1174537824 12.539 100 78 156 1 1444294688 252450256 17306608
02:52:32 4941 8192000000 10346720432 10748399616 1158643760 12.391 100 74 148 1 1445292528 269361552 17287216
02:53:42 2996 8192000000 10342234448 10764750848 1158754352 12.392 100 87 174 0 1458348160 279362688 20231120
02:54:49 2451 8192000000 10388038944 10782904320 1154919216 12.356 100 95 190 1 1483891376 306618896 22353264
02:55:58 1849 8192000000 10387295088 10787131392 1132907728 12.149 99 73 148 0 1479605152 328482336 18215088
02:57:03 1438 8192000000 10421604880 10820554752 1158148560 12.386 100 93 181 2 1519421648 339267856 22005232
02:58:08 3479 8192000000 10423175344 10835333120 1149517008 12.305 100 55 110 0 1519050096 355571072 13962016
02:59:24 8621 8192000000 10431725040 10880049152 1146834624 12.280 99 42 84 0 1528977680 371386848 10756208
03:00:38 7610 8192000000 10485618352 10903216128 1145174960 12.264 99 48 96 0 1572219808 414567856 12476992
03:01:51 6965 8192000000 10535320192 10954178560 1163038896 12.432 100 58 117 1 1641134208 463364768 14730544
03:03:10 9415 8192000000 10514910640 10961620992 1167273824 12.471 100 36 72 1 1628904448 452274240 9356384
03:04:28 14543 8192000000 10509243168 11008266240 1153359808 12.341 100 36 72 1 1609364672 446008064 9996800
03:05:44 13361 8192000000 10459909488 10991800320 1120330832 12.030 99 27 54 0 1591995696 463733840 7931024
03:07:07 19896 8192000000 10174889248 10639056896 1121677440 12.043 100 20 40 2 1296635008 170292896 4664672
03:08:37 54461 8192000000 10156923168 10625662976 1137138848 12.189 99 25 49 0 1259844384 117511264 5194272
03:10:32 105176 8192000000 10066281680 10497368064 1139040592 12.207 100 21 42 0 1187942912 43965392 4936928
03:13:21 53037 8192000000 10110878176 10547134464 1153300288 12.340 100 16 31 0 1193182944 36821152 3061504
03:15:14 17527 8192000000 10133141744 10572296192 1166432176 12.463 100 28 53 1 1233529072 61481136 5615760
03:16:33 42504 8192000000 10187217360 10632245248 1156359200 12.369 99 25 50 1 1265232432 103312064 5561168
03:18:23 61311 8192000000 10157261616 10637451264 1164929152 12.449 100 19 38 0 1280219600 110900096 4390352
03:20:31 103068 8192000000 10098510912 10588651520 1161495072 12.417 100 12 24 0 1237211136 73428976 2287088
03:23:16 41624 8192000000 10096931552 10555019264 1131743776 12.138 98 33 66 1 1192899680 54147040 7008864
03:25:01 122466 8192000000 10083922256 10546941952 1150071904 12.310 100 12 24 1 1171157024 18879152 2205968
03:28:04 50025 8192000000 10064018272 10514231296 1130280128 12.124 98 11 21 1 1155712016 23443520 1988368
03:29:57 51182 8192000000 10119194048 10551123968 1138485040 12.201 99 31 63 2 1197732656 52658048 6589568
03:31:50 60931 8192000000 10089749744 10572132352 1137643952 12.193 99 8 15 0 1192116016 53110960 1361104
03:33:51 54541 8192000000 10039480176 10488410112 1110724960 11.939 97 11 22 0 1129783712 17307392 1751360
03:36:05 53046 8192000000 10066364272 10545127424 1137663376 12.194 100 7 14 0 1166048592 26925056 1460160
03:38:00 33204 8192000000 10060069600 10510520320 1123440464 12.059 98 15 30 1 1151980096 25862464 2677168
03:39:38 62452 8192000000 10112141376 10574540800 1099565888 11.834 96 14 28 0 1174650320 72280944 2803488
03:41:41 57662 8192000000 10107060912 10569805824 1143729536 12.251 100 13 25 2 1213650112 67163808 2756768
03:43:47 75262 8192000000 10100981440 10546315264 1064145360 11.496 93 12 24 0 1112973968 45926112 2902496
03:46:02 48334 8192000000 10100437216 10559025152 1094604496 11.786 97 23 45 0 1182370928 82854352 4912080
03:48:14 44796 8192000000 10095433872 10578468864 1111961712 11.951 100 39 78 0 1240978608 121037840 7979056
03:50:03 52314 8192000000 10055631440 10520006656 1092189456 11.763 99 32 64 0 1155672592 57230592 6252544
03:51:56 58857 8192000000 10032268784 10463387648 1096218576 11.802 99 28 52 0 1120217472 18268864 5730032
03:54:03 28770 8192000000 10044835824 10474225664 1096848720 11.808 98 44 88 5 1137368048 31765824 8753504
03:55:35 48630 8192000000 10081427776 10537582592 1104491776 11.880 99 20 40 0 1190684928 81549440 4643712
03:57:24 55435 8192000000 10047316512 10515386368 1069847088 11.551 96 8 16 0 1131654736 60076960 1730688
03:59:19 64762 8192000000 10078878704 10558095360 1114996320 11.980 100 14 28 1 1175415456 57197680 3221456
04:01:38 27161 8192000000 10047419280 10488672256 1110699200 11.939 99 25 50 0 1143458096 27757632 5001264
04:03:06 47396 8192000000 10108738400 10551246848 1131097264 12.132 100 25 50 0 1188587488 51807760 5682464
04:04:57 69116 8192000000 10090591568 10558791680 1126513888 12.088 99 25 50 0 1234100048 102119984 5466176
04:07:10 54848 8192000000 10081284720 10547355648 1129953184 12.121 99 19 38 3 1205892768 72089744 3849840
04:09:11 39698 8192000000 10052906144 10487074816 1120509536 12.032 99 25 49 0 1155304368 30223072 4571760
04:10:53 49841 8192000000 10089492192 10533847040 1113863664 11.969 97 35 70 0 1190320928 69270640 7186624
04:12:49 73820 8192000000 10060126784 10488942592 1131941984 12.140 99 20 40 0 1166906384 30915360 4049040
04:15:04 11052 8192000000 10097495968 10522570752 1145955584 12.272 100 58 115 0 1196099264 38026768 12116912
04:16:16 72881 8192000000 10073467376 10562703360 1138237856 12.199 99 12 24 0 1194466880 53633728 2595296
04:18:56 48043 8192000000 10068919040 10529275904 1129857504 12.120 99 16 32 0 1178581024 45275984 3447536
04:21:07 87409 8192000000 10070217760 10529968128 1122067312 12.047 98 25 50 1 1175214960 47719296 5428352
04:23:40 47231 8192000000 10103187088 10552832000 1137665696 12.194 99 21 42 0 1188806048 46685104 4455248
04:25:31 73477 8192000000 10138071712 10616246272 1129413968 12.116 98 24 48 1 1216156528 81394352 5348208
04:28:03 52726 8192000000 10092499440 10552909824 1122281008 12.049 99 18 36 1 1235945280 109560976 4103296
04:29:56 102176 8192000000 10064743376 10528604160 1098595920 11.824 97 9 18 0 1153062608 52690368 1776320
04:32:46 128070 8192000000 10082416400 10523996160 1126173968 12.085 100 26 52 0 1163627344 32235056 5218320
04:35:56 49561 8192000000 10074295904 10521726976 1096972640 11.809 97 13 26 1 1157331568 57513680 2845248

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-10-2021 04:39:40 to 23-10-2021 04:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2021 04:39:56 to 23-10-2021 04:39:58.

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          237683
MON$OLDEST_ACTIVE               239961
MON$OLDEST_SNAPSHOT             239961
MON$NEXT_TRANSACTION            239961
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                       2090496
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             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2637 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2637 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2637 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2637 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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Sat Oct 23 04:37:19 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Sat Oct 23 04:37:30 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

23.10.21 04:39:59. 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-10-23 04:40:02 No severe PSQL-related problems occured
23.10.21 04:40:03. End of report.