Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2022 13: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 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 15480456 kB
MemAvailable 30950312 kB
Buffers 0 kB
Cached 16251880 kB
SwapCached 2508 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-08-12 13:37:20
DTS_END                         2022-08-12 13:37:20.3180
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/47138
TRN_ID                          228425
ATT_ID                          622
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9401.71 23425 1128205
customer order: creation 180.51 455 21661
customer order: refuse 14.98 383 1797
order to supplier: creation 47.21 1396 5665
order to supplier: removal 3.33 163516 400
invoice (draft): creation 47.27 3150 5672
invoice (draft): removal 52.04 899 6245
invoice accept: apply 43.98 25296 5278
invoice accept: cancel 47.04 1128 5645
customer reserve: creation 4046.87 242 485624
customer reserve: removal 4616.66 93 553999
realization accept: apply 85.80 281 10296
realization accept: cancel 80.73 173 9688
payment from customer: creation 60.83 182 7300
payment from customer: removal 6.09 125 731
payment to supplier: creation 51.02 189 6122
payment to supplier: removal 3.86 112 463
service: total inventory turnovers 4.25 3749 510
service: total monetary turnovers 8.20 347 984
service: aggregate perf. data 1.01 16029 121
service: refresh index statistics 0.03 250764 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-08-2022 13:39:30 to 12-08-2022 13:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11643
TEST_TIME 2 9024
TEST_TIME 3 7951
TEST_TIME 4 7034
TEST_TIME 5 7029
TEST_TIME 6 6736
TEST_TIME 7 7493
TEST_TIME 8 7787
TEST_TIME 9 8060
TEST_TIME 10 8082
TEST_TIME 11 8054
TEST_TIME 12 8567
TEST_TIME 13 8502
TEST_TIME 14 8466
TEST_TIME 15 8520
TEST_TIME 16 8472
TEST_TIME 17 8484
TEST_TIME 18 8600
TEST_TIME 19 8545
TEST_TIME 20 8799
TEST_TIME 21 8861
TEST_TIME 22 8919
TEST_TIME 23 9104
TEST_TIME 24 9162
TEST_TIME 25 9247
TEST_TIME 26 9565
TEST_TIME 27 9744
TEST_TIME 28 9532
TEST_TIME 29 9542
TEST_TIME 30 10228
TEST_TIME 31 10273
TEST_TIME 32 10446
TEST_TIME 33 10823
TEST_TIME 34 11642
TEST_TIME 35 11510
TEST_TIME 36 11453
TEST_TIME 37 11344
TEST_TIME 38 11236
TEST_TIME 39 11227
TEST_TIME 40 11256
TEST_TIME 41 11126
TEST_TIME 42 10965
TEST_TIME 43 10738
TEST_TIME 44 10721
TEST_TIME 45 10690
TEST_TIME 46 10540
TEST_TIME 47 10473
TEST_TIME 48 10466
TEST_TIME 49 10474
TEST_TIME 50 10428
TEST_TIME 51 10463
TEST_TIME 52 10347
TEST_TIME 53 10298
TEST_TIME 54 10204
TEST_TIME 55 10308
TEST_TIME 56 10256
TEST_TIME 57 10343
TEST_TIME 58 10247
TEST_TIME 59 10292
TEST_TIME 60 10280
TEST_TIME 61 10289
TEST_TIME 62 10277
TEST_TIME 63 10373
TEST_TIME 64 10338
TEST_TIME 65 10386
TEST_TIME 66 10332
TEST_TIME 67 10356
TEST_TIME 68 10235
TEST_TIME 69 10166
TEST_TIME 70 10205
TEST_TIME 71 10137
TEST_TIME 72 10089
TEST_TIME 73 10066
TEST_TIME 74 9995
TEST_TIME 75 9915
TEST_TIME 76 9825
TEST_TIME 77 9816
TEST_TIME 78 9793
TEST_TIME 79 9795
TEST_TIME 80 9821
TEST_TIME 81 9793
TEST_TIME 82 9751
TEST_TIME 83 9731
TEST_TIME 84 9783
TEST_TIME 85 9731
TEST_TIME 86 9746
TEST_TIME 87 9703
TEST_TIME 88 9774
TEST_TIME 89 9806
TEST_TIME 90 9832
TEST_TIME 91 9860
TEST_TIME 92 9870
TEST_TIME 93 9838
TEST_TIME 94 9741
TEST_TIME 95 9730
TEST_TIME 96 9688
TEST_TIME 97 9646
TEST_TIME 98 9621
TEST_TIME 99 9558
TEST_TIME 100 9490
TEST_TIME 101 9513
TEST_TIME 102 9492
TEST_TIME 103 9482
TEST_TIME 104 9460
TEST_TIME 105 9413
TEST_TIME 106 9433
TEST_TIME 107 9410
TEST_TIME 108 9403
TEST_TIME 109 9386
TEST_TIME 110 9374
TEST_TIME 111 9367
TEST_TIME 112 9376
TEST_TIME 113 9452
TEST_TIME 114 9563
TEST_TIME 115 9602
TEST_TIME 116 9613
TEST_TIME 117 9543
TEST_TIME 118 9482
TEST_TIME 119 9470
TEST_TIME 120 9427

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-08-2022 13:39:34 to 12-08-2022 13:39:37.

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 1575122 1575122 0 0.00 0 44478 13 0 0
doc_list_biud 1575115 1575115 0 0.00 0 49022 39 0 0
sp_add_invoice_to_stock 5396 5278 118 2.18 50 178687 25296 0 118
sp_cancel_adding_invoice 5645 5645 0 0.00 6 86599 1128 0 0
sp_cancel_client_order 1797 1797 0 0.00 5 19409 383 0 0
sp_cancel_customer_reserve 553999 553999 0 0.00 1 53825 94 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 3 2222 126 0 0
sp_cancel_pay_to_supplier 463 463 0 0.00 4 1655 113 0 0
sp_cancel_supplier_invoice 6254 6245 9 0.14 2 27272 900 0 9
sp_cancel_supplier_order 400 400 0 0.00 12 1336891 163516 0 0
sp_cancel_write_off 9688 9688 0 0.00 2 40703 174 0 0
sp_client_order 21661 21661 0 0.00 6 39621 456 0 0
sp_customer_reserve 485624 485624 0 0.00 1 69322 242 0 0
sp_fill_shopping_cart 32995 32995 0 0.00 1 10034 86 0 0
sp_fill_shopping_cart_clo_res 485582 485582 0 0.00 0 14806 17 0 0
sp_get_clo_for_invoice 5228 5228 0 0.00 0 17592 272 0 0
sp_kill_cost_storno 16527 16527 0 0.00 0 39878 70 0 0
sp_kill_qstorno_ret_qs2qd 1124331 1124331 0 0.00 0 48719 40 0 0
sp_kill_qty_storno 593297 593297 0 0.00 0 49020 94 0 0
sp_lock_selected_doc 21433 21433 0 0.00 0 8755 9 0 0
sp_make_cost_storno 28946 28946 0 0.00 0 44473 77 0 0
sp_multiply_rows_for_pdistr 28945 28945 0 0.00 0 7411 36 0 0
sp_multiply_rows_for_qdistr 21661 21661 0 0.00 0 39597 175 0 0
sp_pay_from_customer 7300 7300 0 0.00 5 15081 182 0 0
sp_pay_to_supplier 6122 6122 0 0.00 6 13971 190 0 0
sp_payment_common 13422 13422 0 0.00 2 15062 51 0 0
sp_qd_handle_on_cancel_clo 1797 1797 0 0.00 0 19386 305 0 0
sp_qd_handle_on_invoice_upd_sts 10872 10872 0 0.00 0 47584 522 0 0
sp_qd_handle_on_reserve_upd_sts 10296 10296 0 0.00 0 20227 66 0 0
sp_reserve_write_off 10296 10296 0 0.00 8 44495 281 0 0
sp_supplier_invoice 5672 5672 0 0.00 12 103451 3151 0 0
sp_supplier_order 5665 5665 0 0.00 6 45379 1396 0 0
srv_aggregate_perf_data 121 121 0 0.00 2340 125945 16030 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 821 125818 32130 0 0
srv_find_qd_qs_mism 1076902 1076902 0 0.00 0 9473 10 0 0
srv_make_invnt_saldo 510 510 0 0.00 57 59223 3750 0 0
srv_make_money_saldo 984 984 0 0.00 1 6294 347 0 0
srv_recalc_idx_stat 4 4 0 0.00 61928 412511 250764 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 5281 5281 0 0.00 0 9837 976 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 30115 519 0 0
t$perf-norm:sp_cancel_client_order 1797 1797 0 0.00 0 4958 33 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 1651 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 4331 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 463 463 0 0.00 0 383 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 457 27 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 75007 6734 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 2862 29 0 0
t$perf-norm:sp_client_order 21661 21661 0 0.00 0 10767 36 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 3259 18 0 0
t$perf-norm:sp_pay_from_customer 7300 7300 0 0.00 0 16435 23 0 0
t$perf-norm:sp_pay_to_supplier 6122 6122 0 0.00 0 13567 24 0 0
t$perf-norm:sp_reserve_write_off 10296 10296 0 0.00 0 12785 28 0 0
t$perf-norm:sp_supplier_invoice 5672 5672 0 0.00 0 9581 31 0 0
t$perf-norm:sp_supplier_order 5665 5665 0 0.00 0 5807 33 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 176 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 1553 25 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 965 12 0 0
t$perf-norm:srv_make_money_saldo 984 984 0 0.00 0 325 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 9 193 83 0 0
v_add_invoice_to_stock 10690 10454 236 2.20 0 5256 29 0 236
v_all_customers 39038 39038 0 0.00 0 147 4 0 0
v_all_suppliers 31498 31498 0 0.00 0 78 5 0 0
v_all_wares 43322 43322 0 0.00 0 71 4 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 1106 17 0 0
v_cancel_client_order 3594 3594 0 0.00 0 2092 12 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 1216 12 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 1 1415 13 0 0
v_cancel_payment_to_supplier 926 926 0 0.00 0 1219 11 0 0
v_cancel_supplier_invoice 822 804 18 2.18 0 1358 31 0 18
v_cancel_supplier_order 800 800 0 0.00 0 1153 14 0 0
v_cancel_write_off 2006 2006 0 0.00 0 1518 29 0 0
v_max_id_clo_ord 5664 5664 0 0.00 0 4190 6 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 1280 12 0 0
v_max_id_ord_sup 5670 5670 0 0.00 0 3786 8 0 0
v_max_non_paid_invoice 1707 1707 0 0.00 0 573 9 0 0
v_max_non_paid_realizn 4075 4075 0 0.00 0 1138 8 0 0
v_min_id_clo_ord 5664 5664 0 0.00 0 3875 9 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 1206 10 0 0
v_min_id_ord_sup 5670 5670 0 0.00 0 6262 12 0 0
v_min_non_paid_invoice 6122 6122 0 0.00 0 8014 18 0 0
v_min_non_paid_realizn 7300 7300 0 0.00 0 15045 22 0 0
v_our_firm 10734 10734 0 0.00 0 61 5 0 0
v_reserve_write_off 20592 20592 0 0.00 0 2530 11 0 0
x_lock_depdocs_on_canc_invoice 11488 11488 0 0.00 0 10062 112 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 13287 396 0 0
x_make_qstorno_1000_1200 5665 5665 0 0.00 2 45367 1219 0 0
x_make_qstorno_1200_2000 5672 5672 0 0.00 4 103046 2925 0 0
x_make_qstorno_2100_3300 485624 485624 0 0.00 0 58485 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2022 13:39:38 to 12-08-2022 13:39:42.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:23 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:25 30444 8192000000 9730414672 10030628864 812208016 9.020 100 89 178 0 842052416 7783904 22060496
11:10:02 15701 8192000000 9763162448 10073321472 841251248 9.312 100 93 186 0 876141552 12857088 22033216
11:11:33 10033 8192000000 9808172672 10129199104 876473040 9.665 100 84 168 0 914146000 18069168 19603792
11:12:46 6680 8192000000 9861133616 10192924672 912323984 10.020 100 86 172 0 976393072 45048944 19020144
11:13:59 3774 8192000000 9934533184 10275962880 953989696 10.430 100 93 186 0 1045888080 71399376 20499008
11:15:05 3061 8192000000 9962519104 10314637312 995213200 10.832 100 89 176 1 1080034400 66807344 18013856
11:16:11 1231 8192000000 10012751232 10373971968 1019946000 11.071 100 95 183 0 1126419072 87678400 18794672
11:17:15 1155 8192000000 10028428272 10393694208 1045729968 11.320 100 88 168 2 1141556928 79180464 16646496
11:18:17 842 8192000000 10022341280 10395340800 1060109728 11.458 100 97 183 1 1143395008 64304224 18981056
11:19:20 1014 8192000000 10028308016 10407227392 1069969520 11.552 100 93 180 3 1150592160 61814496 18808144
11:20:21 5136 8192000000 10032637104 10423308288 1077014288 11.619 100 63 118 3 1173853232 85733280 11105664
11:21:28 997 8192000000 10035684592 10424168448 1081977968 11.666 100 92 177 2 1156807904 56512048 18317888
11:22:30 1694 8192000000 10046581584 10442203136 1085520352 11.700 100 77 154 1 1162089472 61782592 14786528
11:23:35 1290 8192000000 10033189712 10425044992 1096038464 11.800 100 84 155 3 1163583440 52691664 14853312
11:24:37 4114 8192000000 10037676672 10430476288 1099944512 11.837 100 79 144 1 1169747696 56552384 13250800
11:25:42 4096 8192000000 10055495920 10449301504 1100875632 11.846 100 69 131 0 1175750448 62441744 12433072
11:26:46 1097 8192000000 10048748384 10436939776 1090660256 11.749 99 82 150 3 1168535248 61701888 16173104
11:27:48 4447 8192000000 10057873248 10458140672 1101331536 11.850 100 65 125 2 1180768528 67681152 11755840
11:28:55 1062 8192000000 10064980064 10455601152 1101991872 11.857 100 97 185 1 1188149856 66351776 19806208
11:29:56 2552 8192000000 10033406160 10419355648 1075358544 11.603 99 77 143 3 1152909952 63205792 14345616
11:30:59 3749 8192000000 10006315888 10401280000 1055627168 11.415 99 43 83 0 1126038832 61605168 8806496
11:32:05 908 8192000000 10003018736 10384752640 1049129184 11.352 100 86 156 2 1125419008 58753408 17536416
11:33:08 1820 8192000000 9977611232 10357075968 1040727072 11.272 99 90 173 3 1103420336 43034848 19658416
11:34:11 1068 8192000000 9982335808 10359013376 1035787504 11.224 100 91 158 2 1104588016 51965344 16835168
11:35:13 3965 8192000000 9986819696 10368278528 1037935808 11.245 100 63 122 1 1124876000 75375632 11564560
11:36:17 978 8192000000 10017493520 10395234304 1044603936 11.309 100 93 167 0 1135655024 73250720 17800368
11:37:19 6780 8192000000 10007778000 10394148864 1058077952 11.438 100 56 112 0 1126224512 57285072 10861488
11:38:33 5066 8192000000 10045685408 10429648896 1086713392 11.711 100 75 146 0 1171978192 71276464 13988336
11:39:43 11368 8192000000 10061229088 10452312064 1092744976 11.769 100 78 148 2 1187147648 79274128 15128544
11:40:59 1914 8192000000 10074625072 10459688960 1106494464 11.899 100 74 144 1 1194834432 74266624 14073344
11:42:04 8364 8192000000 10114382224 10514681856 1103200224 11.868 100 67 126 1 1241262000 124994160 13067616
11:43:17 1289 8192000000 10122903744 10512764928 1118559776 12.013 100 85 154 0 1242166096 108274240 15332080
11:44:22 1439 8192000000 10166783120 10578018304 1124045648 12.065 99 94 177 1 1284595584 140495824 20054112
11:45:27 1485 8192000000 10184660784 10583007232 1142827232 12.242 100 84 162 0 1300385520 140024240 17534048
11:46:33 3511 8192000000 10207357888 10619904000 1148733040 12.298 100 77 150 1 1322498832 157072640 16693152
11:47:39 1130 8192000000 10222167920 10624905216 1159408336 12.398 100 92 183 1 1328759296 149763504 19587456
11:48:42 9187 8192000000 10210685376 10648489984 1154481328 12.352 100 68 132 0 1339235328 170122192 14631808
11:49:57 1134 8192000000 10229065888 10630656000 1163683584 12.438 100 86 166 4 1339736288 158398880 17653824
11:51:02 3216 8192000000 10246610896 10674761728 1166665088 12.466 100 60 118 2 1353135680 173226480 13244112
11:52:11 918 8192000000 10286201712 10697195520 1166644464 12.465 100 94 179 2 1392491776 205892480 19954832
11:53:15 1259 8192000000 10312394320 10715238400 1170022480 12.497 99 94 183 3 1421595920 230256736 21316704
11:54:19 1131 8192000000 10350067312 10754097152 1172112688 12.517 99 93 182 1 1453622240 259928080 21581472
11:55:23 938 8192000000 10380596544 10806059008 1179797808 12.588 100 98 194 1 1488199648 285598528 22803312
11:56:29 1460 8192000000 10389525488 10789572608 1167281680 12.471 99 94 185 2 1492722608 304132992 21307936
11:57:33 1164 8192000000 10392540368 10823548928 1181517312 12.604 100 94 182 3 1498837056 296701744 20618000
11:58:36 850 8192000000 10415997376 10829590528 1178615120 12.577 100 96 180 2 1520961888 321019024 21327744
11:59:39 1287 8192000000 10425662624 10857000960 1172206768 12.517 100 87 168 2 1533038336 340154048 20677520
12:00:44 2643 8192000000 10432906512 10847543296 1162878848 12.430 100 79 156 3 1534621488 352950896 18791744
12:01:53 2533 8192000000 10448091584 10887188480 1170761296 12.504 100 70 139 1 1554046320 366545616 16739408
12:02:58 808 8192000000 10478698096 10934333440 1187729232 12.662 100 91 180 1 1579617824 370507600 21380992
12:04:01 4118 8192000000 10491571936 10934472704 1169033888 12.488 100 56 112 2 1599314800 416024352 14256560
12:05:11 4598 8192000000 10490350208 10917482496 1170157152 12.498 100 64 118 0 1592130672 407994672 13978848
12:06:35 13230 8192000000 10546761968 11000823808 1166208640 12.461 100 51 100 0 1661847792 482838800 12800352
12:07:51 33391 8192000000 10399887824 10837975040 1151646720 12.325 99 39 78 0 1502037088 340698208 9692160
12:09:25 24608 8192000000 10376242480 10869436416 1157408544 12.379 100 24 46 0 1491324880 328047552 5868784
12:10:51 26908 8192000000 10250109632 10765328384 1146815456 12.280 100 13 26 0 1393446288 243419776 3211056
12:12:18 59185 8192000000 10083488896 10553303040 1095754608 11.797 96 16 30 0 1150387440 51509792 3123040
12:14:17 86690 8192000000 10111936032 10554875904 1118615456 12.014 99 16 32 0 1174180096 52088576 3476064
12:16:44 32207 8192000000 10076279232 10547990528 1129371808 12.115 100 23 44 0 1189311520 55509248 4430464
12:18:23 42147 8192000000 10083658848 10511958016 1131032912 12.131 99 34 66 0 1165387184 27647984 6706288
12:20:13 51198 8192000000 10066858576 10514747392 1107145104 11.905 97 37 69 0 1150679920 35588368 7946448
12:22:14 72985 8192000000 10100627168 10581544960 1126940976 12.093 99 32 64 0 1202367312 68693632 6732704
12:24:31 946 8192000000 10104929792 10524389376 1141578160 12.230 100 94 182 1 1197112672 35523600 20010912
12:25:33 8592 8192000000 10129013792 10561478656 1138190960 12.199 100 43 84 0 1230698096 83280192 9226944
12:26:46 8721 8192000000 10120521696 10544967680 1134383920 12.163 99 63 115 1 1203055296 54484656 14186720
12:27:59 11990 8192000000 10142330192 10580983808 1139470464 12.211 100 45 90 1 1244777200 95036880 10269856
12:29:16 62532 8192000000 10135472304 10594193408 1125979504 12.083 98 23 44 0 1212617440 81548976 5088960
12:31:30 47798 8192000000 10151434224 10635964416 1135887440 12.177 99 24 46 0 1254721312 113348992 5484880
12:33:25 59919 8192000000 10108519248 10551439360 1131834368 12.139 99 22 42 0 1206527776 69722512 4970896
12:35:27 32454 8192000000 10113269360 10548568064 1108932944 11.922 97 48 92 1 1181024080 62204080 9887056
12:37:00 38261 8192000000 10097800352 10552119296 1119357072 12.021 98 25 50 0 1171616656 47155680 5103904
12:38:39 56085 8192000000 10119517648 10559737856 1122905248 12.054 98 20 41 0 1213454912 86127232 4422432
12:40:35 56885 8192000000 10132323664 10564173824 1123146016 12.057 98 20 37 0 1198793520 71662704 3984800
12:42:34 68154 8192000000 10100899664 10567159808 1048835264 11.350 92 18 35 0 1093450160 41312192 3302704
12:44:44 125817 8192000000 10145352016 10587934720 1125031680 12.075 100 21 42 0 1230585248 100448944 5104624
12:48:00 35661 8192000000 10063747920 10509602816 1106691040 11.901 98 37 71 1 1167214128 53637520 6885568
12:49:37 52547 8192000000 10059629696 10537119744 1102764608 11.864 98 12 24 0 1160556992 55141184 2651200
12:51:36 100046 8192000000 10108814128 10555924480 1094654160 11.787 96 21 42 0 1173882176 74079504 5148512
12:54:16 49344 8192000000 10122156496 10583449600 1125276736 12.077 100 13 25 0 1215528128 87439488 2811904
12:56:17 63649 8192000000 10059633280 10558824448 1121954272 12.045 99 12 22 0 1201622912 77090560 2578080
12:59:09 29929 8192000000 10076076752 10513879040 1112512656 11.956 99 26 50 1 1152798192 34549824 5735712
13:00:43 55978 8192000000 10071789456 10525884416 1089453424 11.737 97 23 46 0 1145283648 51383488 4446736
13:02:40 17593 8192000000 10088828848 10517794816 1094779408 11.788 97 44 87 0 1158836176 55392912 8663856
13:04:00 27334 8192000000 10127114144 10572673024 1119275536 12.020 100 37 73 0 1226426848 99673808 7477504
13:05:30 25970 8192000000 10095749456 10549547008 1121054864 12.037 100 40 76 1 1206664848 77987152 7622832
13:07:17 33638 8192000000 10115871680 10576433152 1132030560 12.141 100 38 72 0 1207885584 68779680 7075344
13:08:51 46862 8192000000 10099486320 10557505536 1128270304 12.105 99 21 40 0 1172344096 40138224 3935568
13:10:40 105037 8192000000 10095226032 10539794432 1120828512 12.035 99 28 56 0 1198973328 71950496 6194320
13:13:31 61054 8192000000 10071237696 10522959872 1142716080 12.241 100 27 54 0 1203439056 55422160 5300816
13:15:33 38645 8192000000 10082482256 10561863680 1145533600 12.268 100 9 18 0 1178694800 30825872 2335328
13:17:14 58463 8192000000 10111575696 10577391616 1139286384 12.209 99 18 36 0 1198121584 54837376 3997824
13:19:13 87343 8192000000 10100562560 10576904192 1150495936 12.314 100 7 14 0 1194431088 42205360 1729792
13:21:41 62416 8192000000 10117165056 10557628416 1140732400 12.222 98 24 46 0 1181749056 36078992 4937664
13:23:51 34825 8192000000 10134991360 10578583552 1137515776 12.192 99 29 58 0 1228923040 84774976 6632288
13:25:30 25275 8192000000 10168918832 10623918080 1145053568 12.263 100 38 76 0 1260621168 107550656 8016944
13:27:03 18138 8192000000 10193896272 10626838528 1138338880 12.200 99 46 91 0 1292660320 144069104 10252336
13:28:27 63708 8192000000 10214546192 10714898432 1151183664 12.321 100 33 66 0 1336133680 177300000 7650016
13:30:33 47155 8192000000 10236884512 10694098944 1154042944 12.347 100 37 70 1 1337013728 175641136 7329648
13:32:25 78044 8192000000 10090029600 10559180800 1133779344 12.157 99 21 41 0 1179474896 41748592 3946960
13:34:50 66847 8192000000 10062350304 10505699328 1079711472 11.645 95 31 62 0 1105221968 19237696 6272800
13:37:03 23637 8192000000 9708843392 10136915968 792300832 8.818 70 10 20 0 827514352 33430192 1783328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-08-2022 13:39:44 to 12-08-2022 13:40:00.

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 12-08-2022 13:40:01 to 12-08-2022 13:40:03.

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          228207
MON$OLDEST_ACTIVE               228651
MON$OLDEST_SNAPSHOT             228651
MON$NEXT_TRANSACTION            228651
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                       2086688
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:176: 
:177: mscs-test-161.budzdorov.ru    Fri Aug 12 13:37:35 2022
:178:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:179: 
:180: 
:181: mscs-test-161.budzdorov.ru    Fri Aug 12 13:37:35 2022
:182:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:183: 
:184: 
:185: mscs-test-161.budzdorov.ru    Fri Aug 12 13:37:40 2022
:186:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:187: 
--- end of diff output ---

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

12.08.22 13:40:04. 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-08-12 13:40:07 No severe PSQL-related problems occured
12.08.22 13:40:08. End of report.