Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.04.2022 10: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 16526772 kB
MemAvailable 31630196 kB
Buffers 0 kB
Cached 15213604 kB
SwapCached 2204 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.2
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 2022-04-01 10:37:11
DTS_END                         2022-04-01 10:37:11.4040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47206
TRN_ID                          212974
ATT_ID                          557
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: 8856.61 31645 1062793
customer order: creation 160.10 479 19212
customer order: refuse 14.16 482 1699
order to supplier: creation 42.52 1445 5102
order to supplier: removal 3.33 171520 400
invoice (draft): creation 43.83 3362 5260
invoice (draft): removal 49.32 1038 5918
invoice accept: apply 40.40 29638 4848
invoice accept: cancel 44.75 1284 5370
customer reserve: creation 3800.58 279 456069
customer reserve: removal 4377.27 101 525272
realization accept: apply 78.44 306 9413
realization accept: cancel 78.21 192 9385
payment from customer: creation 55.81 189 6697
payment from customer: removal 5.36 133 643
payment to supplier: creation 47.03 205 5644
payment to supplier: removal 3.37 167 404
service: total inventory turnovers 3.83 4535 459
service: total monetary turnovers 7.39 425 887
service: aggregate perf. data 0.89 16625 107
service: refresh index statistics 0.03 400489 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-04-2022 10:38:57 to 01-04-2022 10:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8920
TEST_TIME 2 7977
TEST_TIME 3 7596
TEST_TIME 4 7315
TEST_TIME 5 7579
TEST_TIME 6 7805
TEST_TIME 7 7538
TEST_TIME 8 7486
TEST_TIME 9 7221
TEST_TIME 10 6891
TEST_TIME 11 6678
TEST_TIME 12 6499
TEST_TIME 13 6504
TEST_TIME 14 6474
TEST_TIME 15 6491
TEST_TIME 16 6770
TEST_TIME 17 6668
TEST_TIME 18 6828
TEST_TIME 19 6866
TEST_TIME 20 6836
TEST_TIME 21 7137
TEST_TIME 22 7208
TEST_TIME 23 7456
TEST_TIME 24 7672
TEST_TIME 25 7721
TEST_TIME 26 7911
TEST_TIME 27 8031
TEST_TIME 28 8468
TEST_TIME 29 8583
TEST_TIME 30 8738
TEST_TIME 31 9204
TEST_TIME 32 9118
TEST_TIME 33 9216
TEST_TIME 34 9798
TEST_TIME 35 10075
TEST_TIME 36 10328
TEST_TIME 37 10280
TEST_TIME 38 10474
TEST_TIME 39 10468
TEST_TIME 40 10385
TEST_TIME 41 10263
TEST_TIME 42 10119
TEST_TIME 43 9999
TEST_TIME 44 9838
TEST_TIME 45 9916
TEST_TIME 46 9803
TEST_TIME 47 9662
TEST_TIME 48 9580
TEST_TIME 49 9581
TEST_TIME 50 9547
TEST_TIME 51 9543
TEST_TIME 52 9540
TEST_TIME 53 9524
TEST_TIME 54 9448
TEST_TIME 55 9339
TEST_TIME 56 9382
TEST_TIME 57 9240
TEST_TIME 58 9252
TEST_TIME 59 9297
TEST_TIME 60 9247
TEST_TIME 61 9248
TEST_TIME 62 9251
TEST_TIME 63 9189
TEST_TIME 64 9281
TEST_TIME 65 9232
TEST_TIME 66 9242
TEST_TIME 67 9247
TEST_TIME 68 9264
TEST_TIME 69 9296
TEST_TIME 70 9369
TEST_TIME 71 9464
TEST_TIME 72 9466
TEST_TIME 73 9455
TEST_TIME 74 9336
TEST_TIME 75 9327
TEST_TIME 76 9296
TEST_TIME 77 9247
TEST_TIME 78 9183
TEST_TIME 79 9173
TEST_TIME 80 9144
TEST_TIME 81 9115
TEST_TIME 82 9114
TEST_TIME 83 9104
TEST_TIME 84 9163
TEST_TIME 85 9110
TEST_TIME 86 9168
TEST_TIME 87 9123
TEST_TIME 88 9134
TEST_TIME 89 9158
TEST_TIME 90 9167
TEST_TIME 91 9178
TEST_TIME 92 9132
TEST_TIME 93 9081
TEST_TIME 94 9060
TEST_TIME 95 8995
TEST_TIME 96 9018
TEST_TIME 97 8973
TEST_TIME 98 8951
TEST_TIME 99 8891
TEST_TIME 100 8822
TEST_TIME 101 8859
TEST_TIME 102 8831
TEST_TIME 103 8845
TEST_TIME 104 8836
TEST_TIME 105 8847
TEST_TIME 106 8887
TEST_TIME 107 8874
TEST_TIME 108 8892
TEST_TIME 109 8867
TEST_TIME 110 8897
TEST_TIME 111 8868
TEST_TIME 112 8987
TEST_TIME 113 8991
TEST_TIME 114 8962
TEST_TIME 115 8968
TEST_TIME 116 9068
TEST_TIME 117 9036
TEST_TIME 118 8978
TEST_TIME 119 8943
TEST_TIME 120 8874

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-04-2022 10:39:00 to 01-04-2022 10:39:04.

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 1481507 1481507 0 0.00 0 27857 15 0 0
doc_list_biud 1481499 1481499 0 0.00 0 49323 42 0 0
sp_add_invoice_to_stock 4966 4848 118 2.37 59 230113 29639 0 118
sp_cancel_adding_invoice 5370 5370 0 0.00 5 84202 1284 0 0
sp_cancel_client_order 1699 1699 0 0.00 6 12550 483 0 0
sp_cancel_customer_reserve 525272 525272 0 0.00 1 49325 101 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 4 7371 133 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 7317 167 0 0
sp_cancel_supplier_invoice 5927 5918 9 0.15 2 43644 1039 0 9
sp_cancel_supplier_order 400 400 0 0.00 22 1536880 171521 0 0
sp_cancel_write_off 9385 9385 0 0.00 2 13221 193 0 0
sp_client_order 19212 19212 0 0.00 6 27803 480 0 0
sp_customer_reserve 456069 456069 0 0.00 1 139166 280 0 0
sp_fill_shopping_cart 29566 29566 0 0.00 2 20300 88 0 0
sp_fill_shopping_cart_clo_res 456037 456037 0 0.00 0 19977 20 0 0
sp_get_clo_for_invoice 4809 4809 0 0.00 0 23736 329 0 0
sp_kill_cost_storno 15802 15802 0 0.00 0 11677 85 0 0
sp_kill_qstorno_ret_qs2qd 1066247 1066247 0 0.00 0 49298 44 0 0
sp_kill_qty_storno 562266 562266 0 0.00 0 49322 102 0 0
sp_lock_selected_doc 19777 19777 0 0.00 0 6870 7 0 0
sp_make_cost_storno 26563 26563 0 0.00 0 27852 87 0 0
sp_multiply_rows_for_pdistr 26563 26563 0 0.00 0 8768 41 0 0
sp_multiply_rows_for_qdistr 19212 19212 0 0.00 0 27787 171 0 0
sp_pay_from_customer 6697 6697 0 0.00 6 14903 189 0 0
sp_pay_to_supplier 5644 5644 0 0.00 7 9472 206 0 0
sp_payment_common 12341 12341 0 0.00 2 11190 46 0 0
sp_qd_handle_on_cancel_clo 1699 1699 0 0.00 0 12540 406 0 0
sp_qd_handle_on_invoice_upd_sts 10179 10179 0 0.00 0 21945 552 0 0
sp_qd_handle_on_reserve_upd_sts 9413 9413 0 0.00 0 15498 67 0 0
sp_reserve_write_off 9413 9413 0 0.00 8 27894 306 0 0
sp_supplier_invoice 5260 5260 0 0.00 9 84273 3363 0 0
sp_supplier_order 5102 5102 0 0.00 8 79249 1445 0 0
srv_aggregate_perf_data 107 107 0 0.00 2361 168203 16625 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 817 155699 34496 0 0
srv_find_qd_qs_mism 1015205 1015205 0 0.00 0 25756 13 0 0
srv_make_invnt_saldo 459 459 0 0.00 68 86057 4536 0 0
srv_make_money_saldo 887 887 0 0.00 1 23095 425 0 0
srv_recalc_idx_stat 4 4 0 0.00 317638 477823 400490 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4852 4852 0 0.00 0 12719 1033 0 0
t$perf-norm:sp_cancel_adding_invoice 362 362 0 0.00 2 7532 619 0 0
t$perf-norm:sp_cancel_client_order 1699 1699 0 0.00 0 3663 33 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 702 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 1733 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 706 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 693 43 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 85748 7417 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 2282 24 0 0
t$perf-norm:sp_client_order 19212 19212 0 0.00 0 16493 39 0 0
t$perf-norm:sp_customer_reserve 902 902 0 0.00 0 343 13 0 0
t$perf-norm:sp_pay_from_customer 6697 6697 0 0.00 0 4048 19 0 0
t$perf-norm:sp_pay_to_supplier 5644 5644 0 0.00 0 2877 19 0 0
t$perf-norm:sp_reserve_write_off 9413 9413 0 0.00 0 10531 25 0 0
t$perf-norm:sp_supplier_invoice 5260 5260 0 0.00 0 4788 36 0 0
t$perf-norm:sp_supplier_order 5102 5102 0 0.00 0 4251 33 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 612 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 604 15 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 4855 29 0 0
t$perf-norm:srv_make_money_saldo 887 887 0 0.00 0 2961 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 192 55 0 0
v_add_invoice_to_stock 9844 9608 236 2.39 0 8213 26 0 236
v_all_customers 35428 35428 0 0.00 0 59 4 0 0
v_all_suppliers 28914 28914 0 0.00 0 107 4 0 0
v_all_wares 38422 38422 0 0.00 0 62 4 0 0
v_cancel_adding_invoice 724 724 0 0.00 0 1302 17 0 0
v_cancel_client_order 3398 3398 0 0.00 0 5424 13 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 912 12 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 4570 13 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 7294 34 0 0
v_cancel_supplier_invoice 784 766 18 2.29 0 1691 26 0 18
v_cancel_supplier_order 800 800 0 0.00 0 4860 18 0 0
v_cancel_write_off 1950 1950 0 0.00 0 10643 33 0 0
v_max_id_clo_ord 5099 5099 0 0.00 0 632 5 0 0
v_max_id_clo_res 902 902 0 0.00 0 1385 12 0 0
v_max_id_ord_sup 5252 5252 0 0.00 0 5295 8 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 764 8 0 0
v_max_non_paid_realizn 3453 3453 0 0.00 0 2207 9 0 0
v_min_id_clo_ord 5099 5099 0 0.00 0 2541 8 0 0
v_min_id_clo_res 902 902 0 0.00 0 2391 10 0 0
v_min_id_ord_sup 5252 5252 0 0.00 0 5117 11 0 0
v_min_non_paid_invoice 5644 5644 0 0.00 0 6357 14 0 0
v_min_non_paid_realizn 6697 6697 0 0.00 0 11157 18 0 0
v_our_firm 9482 9482 0 0.00 0 80 5 0 0
v_reserve_write_off 18826 18826 0 0.00 0 9534 12 0 0
x_lock_depdocs_on_canc_invoice 10905 10905 0 0.00 0 8973 125 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 2643 360 0 0
x_make_qstorno_1000_1200 5102 5102 0 0.00 3 79222 1261 0 0
x_make_qstorno_1200_2000 5260 5260 0 0.00 4 84140 3121 0 0
x_make_qstorno_2100_3300 456069 456069 0 0.00 0 139164 246 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-04-2022 10:39:05 to 01-04-2022 10:39:09.

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
08:07:15 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:15 33611 8192000000 9713136592 10011971584 791032368 8.805 100 90 180 0 820586416 7457568 22096480
08:09:56 8772 8192000000 9743287264 10050109440 821769824 9.116 100 90 180 0 856449840 12254368 22425648
08:11:10 12741 8192000000 9785113952 10104307712 855984240 9.460 100 84 169 0 897044192 21583984 19475968
08:12:29 14530 8192000000 9849810128 10180304896 907342512 9.971 100 85 168 2 960960112 34590736 19026864
08:13:49 5105 8192000000 9902484752 10245226496 948068944 10.372 100 80 157 0 1020040864 54804528 17167392
08:14:59 3091 8192000000 9954131536 10305347584 987079136 10.753 100 90 177 0 1074922272 69423616 18419520
08:16:05 1975 8192000000 9983188544 10341756928 1008086944 10.957 100 88 169 2 1102211296 77362880 16761472
08:17:10 1241 8192000000 10009004656 10376577024 1034612112 11.213 100 95 183 0 1132141408 80390784 17138512
08:18:13 884 8192000000 10043844768 10414346240 1050146288 11.362 100 96 185 1 1165916992 96878272 18892432
08:19:15 887 8192000000 10052259248 10432983040 1061549296 11.471 100 95 185 0 1171937040 91513840 18873904
08:20:16 8082 8192000000 10024373520 10401071104 1071018304 11.562 100 89 166 1 1138633600 49963472 17651824
08:21:27 905 8192000000 10059390864 10449027072 1077511184 11.624 100 87 174 0 1184506960 88871136 18124640
08:22:30 1231 8192000000 10058697872 10441326592 1083114560 11.677 100 96 183 1 1181645728 79370720 19160448
08:23:33 1255 8192000000 10052790160 10439122944 1083351328 11.679 100 92 183 2 1170834960 68302672 19180960
08:24:34 1247 8192000000 10054385840 10453282816 1090698160 11.749 100 72 139 3 1175158480 70682704 13777616
08:25:37 1376 8192000000 10069319872 10463236096 1091901200 11.761 100 83 161 0 1192014800 83410480 16703120
08:26:39 776 8192000000 10078014464 10470092800 1098838704 11.827 100 91 176 1 1195853440 78767312 18247424
08:27:43 2537 8192000000 10041040768 10428178432 1082565360 11.672 98 76 143 0 1156082960 59564976 13952624
08:28:47 1109 8192000000 10054235168 10443767808 1098399040 11.822 100 89 175 3 1172445952 54650976 19395936
08:29:50 1861 8192000000 10015835376 10403688448 1084151056 11.687 100 73 127 3 1136953552 39517120 13285376
08:30:52 1093 8192000000 10024710016 10410373120 1088414144 11.728 100 89 166 1 1140009216 35280576 16314496
08:31:54 1456 8192000000 10033918592 10417688576 1069764160 11.550 99 89 170 2 1152097168 65181408 17151600
08:32:56 4371 8192000000 10012013056 10399346688 1072387056 11.575 100 63 123 0 1138811504 54049392 12375056
08:34:02 997 8192000000 10008055472 10380636160 1045564336 11.318 100 97 188 2 1123728288 59230896 18933056
08:35:04 1114 8192000000 9988862288 10353991680 1018201680 11.055 100 92 180 1 1110044784 73911360 17931744
08:36:06 1048 8192000000 10006739168 10380058624 1039869280 11.263 100 92 179 3 1131722528 73555184 18298064
08:37:09 969 8192000000 9978953520 10356752384 1044922256 11.312 100 72 138 1 1094214896 36050880 13241760
08:38:19 1349 8192000000 10034775664 10410303488 1059786608 11.454 100 92 175 1 1154580464 76368592 18425264
08:39:27 2244 8192000000 10042119872 10422018048 1078671616 11.635 100 82 149 1 1155248592 61658912 14918064
08:40:34 1398 8192000000 10064932384 10445131776 1085755696 11.702 100 96 190 0 1181679392 76558192 19365504
08:41:42 851 8192000000 10109802800 10496843776 1092320192 11.765 100 85 160 1 1215994608 107495456 16178960
08:42:47 2303 8192000000 10105209728 10492411904 1104629904 11.882 100 83 155 2 1223319472 102149680 16539888
08:43:53 1855 8192000000 10138892000 10531041280 1118994512 12.017 100 95 188 0 1251078944 112476512 19607920
08:45:01 7581 8192000000 10146748976 10549100544 1123573104 12.061 100 70 128 0 1269345280 131390640 14381536
08:46:31 9752 8192000000 10149198464 10558521344 1117058432 11.999 98 74 142 0 1251372000 119105696 15207872
08:47:43 1033 8192000000 10216000656 10612170752 1152686544 12.335 100 87 158 1 1331124288 160583984 17853760
08:48:48 1923 8192000000 10264433104 10660446208 1169853760 12.495 100 88 175 0 1375786976 186131488 19801728
08:49:57 1193 8192000000 10322660096 10725380096 1181944928 12.608 100 96 185 0 1431598848 229164352 20489568
08:51:03 1195 8192000000 10351383680 10750865408 1192996080 12.711 100 95 186 2 1463814576 250250272 20568224
08:52:09 1704 8192000000 10394738928 10801004544 1195660784 12.736 100 91 175 1 1504992816 288547568 20784464
08:53:14 1245 8192000000 10431360352 10835877888 1200848544 12.784 100 94 186 1 1538120560 316545472 20726544
08:54:20 1325 8192000000 10433529664 10849767424 1197148480 12.750 100 93 182 1 1540402272 322308768 20945024
08:55:26 1533 8192000000 10479039200 10887159808 1202400816 12.799 100 91 174 0 1579918016 356670544 20846656
08:56:32 1230 8192000000 10496276992 10905231360 1197487120 12.753 100 87 166 0 1600412832 383662928 19262784
08:57:38 1063 8192000000 10537526784 10957279232 1203423456 12.808 100 97 191 2 1639143216 413258176 22461584
08:58:41 829 8192000000 10530945808 10956148736 1203731296 12.811 100 92 176 0 1633076224 408552144 20792784
08:59:45 1234 8192000000 10531315616 10975985664 1198870448 12.766 100 96 185 1 1633731632 412770160 22091024
09:00:52 1390 8192000000 10541669248 10989223936 1192767312 12.709 100 95 185 1 1644578816 429599936 22211568
09:02:00 815 8192000000 10540081472 10960486400 1182891168 12.617 100 90 175 2 1639032128 435900128 20240832
09:03:03 1791 8192000000 10549966784 10994671616 1192847936 12.710 100 93 182 1 1650508144 436115072 21545136
09:04:07 958 8192000000 10534591552 11003236352 1185265312 12.639 100 91 179 0 1635030672 427607808 22157552
09:05:11 7258 8192000000 10503276336 11020214272 1188856576 12.673 100 64 122 0 1637925040 432938704 16129760
09:06:26 7214 8192000000 10523079136 10961453056 1191636832 12.699 100 57 112 0 1627159472 421320192 14202448
09:07:50 29199 8192000000 10429726592 10985975808 1173856544 12.533 99 38 73 0 1587695696 404060784 9778368
09:09:23 27187 8192000000 10440889376 10874863616 1168963824 12.487 100 57 112 2 1542088176 358992688 14131664
09:11:22 16946 8192000000 10332180352 10878570496 1155982528 12.366 100 20 40 1 1430676272 269092448 5601296
09:12:42 40101 8192000000 10107887168 10658492416 1105827696 11.893 97 12 24 0 1259146960 149817664 3501600
09:14:53 81848 8192000000 10079259200 10548195328 1086926000 11.713 95 16 30 0 1162216528 71956368 3334160
09:17:16 75810 8192000000 10063224688 10524839936 1095120144 11.791 97 12 22 0 1133983456 36649360 2213952
09:19:32 30631 8192000000 10109877664 10539954176 1141807216 12.233 100 58 113 1 1202782400 49534336 11440848
09:21:10 108719 8192000000 10073744816 10531672064 1112847776 11.959 98 33 64 3 1178268048 59238896 6181376
09:24:01 58824 8192000000 10077334736 10518339584 1110871296 11.941 97 25 46 2 1153782096 37333456 5577344
09:26:02 35288 8192000000 10128466000 10594308096 1131412240 12.135 99 27 53 1 1245165552 107754352 5998960
09:27:39 12646 8192000000 10137409312 10590371840 1143788608 12.251 100 60 120 3 1226291664 68790928 13712128
09:28:53 155699 8192000000 10113951952 10574295040 1116612256 11.995 98 25 50 1 1209498672 87553408 5333008
09:32:30 30971 8192000000 10162174624 10615320576 1136762912 12.185 100 22 43 0 1262257680 120774128 4720640
09:36:24 31001 8192000000 10087136544 10600308736 1122757216 12.053 99 11 22 0 1234639936 109217920 2664800
09:38:00 49360 8192000000 10051441728 10484772864 1071430656 11.566 94 29 57 4 1115293520 38681792 5181072
09:39:50 39563 8192000000 10111109840 10545487872 1108760032 11.921 98 51 100 3 1148328432 28830048 10738352
09:41:35 46542 8192000000 10060356512 10492043264 1083326144 11.679 96 17 34 1 1136554672 49734656 3493872
09:43:23 24008 8192000000 10114857024 10548621312 1115331136 11.983 99 32 61 2 1204122624 81826720 6964768
09:44:49 69462 8192000000 10119725024 10556067840 1121513168 12.041 99 33 61 2 1213172176 85271968 6387040
09:46:59 68534 8192000000 10156176144 10642264064 1139772896 12.213 100 23 46 4 1285638704 140074928 5790880
09:49:17 64576 8192000000 10088620976 10545324032 1110486528 11.937 97 17 34 0 1158250848 44634528 3129792
09:51:27 66863 8192000000 10112370352 10545754112 1133616512 12.155 99 48 93 1 1203547264 59604048 10326704
09:53:37 71662 8192000000 10094407872 10576760832 1149407760 12.304 100 7 14 0 1201651344 50944784 1298800
09:55:49 93167 8192000000 10110668240 10586189824 1157436832 12.379 100 20 40 2 1217781760 55634320 4710608
09:58:51 61229 8192000000 10068823936 10523111424 1068998160 11.543 94 18 36 1 1153126784 79919872 4208752
10:01:01 89795 8192000000 10058488400 10500509696 1063551328 11.490 94 18 36 1 1106937136 38856160 4529648
10:03:31 44534 8192000000 10074806592 10525331456 1104647712 11.882 98 28 55 0 1160010496 49934080 5428704
10:05:17 13244 8192000000 10147791120 10579132416 1114839744 11.978 99 56 112 1 1208695872 82843952 11012176
10:06:34 47351 8192000000 10086261104 10548056064 1101220624 11.849 98 46 90 2 1179928320 69183360 9524336
10:08:25 8859 8192000000 10090644704 10517590016 1119144416 12.019 100 50 99 2 1177154144 48501664 9508064
10:09:35 112884 8192000000 10089622032 10531348480 1117095936 12.000 99 30 57 1 1184927504 62179520 5652048
10:12:38 21862 8192000000 10102421344 10537029632 1126122032 12.085 99 45 88 1 1181108032 45778608 9207392
10:14:04 58255 8192000000 10054730368 10517766144 1085113552 11.696 96 9 17 0 1132454864 45733872 1607440
10:16:02 84944 8192000000 10095672432 10553520128 1121619088 12.042 99 19 36 2 1187655328 61642240 4394000
10:20:49 42883 8192000000 10076527728 10534158336 1108623968 11.919 97 26 50 0 1140658176 26539376 5494832
10:22:35 36580 8192000000 10089516720 10527006720 1127600512 12.099 99 24 49 0 1190832752 58159568 5072672
10:24:14 40406 8192000000 10111342080 10548981760 1143498032 12.248 100 39 78 0 1207357168 55201408 8657728
10:25:55 37679 8192000000 10110527104 10565509120 1130868096 12.130 99 34 67 1 1210662176 73158944 6635136
10:27:34 22354 8192000000 10115967264 10553819136 1107648832 11.910 97 47 93 2 1200145520 83024208 9472480
10:28:58 58272 8192000000 10110997904 10642710528 1132932944 12.149 100 41 82 1 1270229392 128816176 8480272
10:31:03 26332 8192000000 10107206512 10528370688 1128499760 12.107 100 46 93 0 1191852640 53184432 10168448
10:32:34 76573 8192000000 10101247840 10594566144 1134078384 12.160 99 28 54 0 1252853552 113306240 5468928
10:34:53 105117 8192000000 10052654256 10510848000 1105964048 11.894 97 28 55 3 1139293904 27782976 5546880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-04-2022 10:39:11 to 01-04-2022 10:39:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-04-2022 10:39:27 to 01-04-2022 10:39:29.

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          212205
MON$OLDEST_ACTIVE               214137
MON$OLDEST_SNAPSHOT             214137
MON$NEXT_TRANSACTION            214137
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                       2055968
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.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:335: 
:336: mscs-test-161.budzdorov.ru    Fri Apr  1 10:37:16 2022
:337:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:338: 
--- end of diff output ---

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

01.04.22 10:39:30. 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
2022-04-01 10:39:33 No severe PSQL-related problems occured
01.04.22 10:39:34. End of report.