Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.12.2022 10: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 14692304 kB
MemAvailable 30128040 kB
Buffers 0 kB
Cached 17027676 kB
SwapCached 3596 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.3
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-12-28 10:37:23
DTS_END                         2022-12-28 10:37:23.8390
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/47944
TRN_ID                          218035
ATT_ID                          591
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: 8889.52 26737 1066742
customer order: creation 167.77 471 20132
customer order: refuse 14.23 414 1708
order to supplier: creation 44.93 1383 5391
order to supplier: removal 3.43 173829 412
invoice (draft): creation 43.38 2990 5206
invoice (draft): removal 50.36 1103 6043
invoice accept: apply 39.72 25671 4766
invoice accept: cancel 45.94 1246 5513
customer reserve: creation 3680.47 243 441656
customer reserve: removal 4510.83 104 541299
realization accept: apply 81.40 285 9768
realization accept: cancel 81.48 148 9778
payment from customer: creation 57.15 191 6858
payment from customer: removal 5.38 126 645
payment to supplier: creation 47.64 189 5717
payment to supplier: removal 3.24 111 389
service: total inventory turnovers 3.58 4939 430
service: total monetary turnovers 7.56 423 907
service: aggregate perf. data 1.00 17121 120
service: refresh index statistics 0.03 303754 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-12-2022 10:39:37 to 28-12-2022 10:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9640
TEST_TIME 2 7089
TEST_TIME 3 6501
TEST_TIME 4 6487
TEST_TIME 5 6482
TEST_TIME 6 6864
TEST_TIME 7 6860
TEST_TIME 8 6917
TEST_TIME 9 7050
TEST_TIME 10 6972
TEST_TIME 11 6957
TEST_TIME 12 7279
TEST_TIME 13 7498
TEST_TIME 14 7609
TEST_TIME 15 7834
TEST_TIME 16 7870
TEST_TIME 17 7922
TEST_TIME 18 8002
TEST_TIME 19 8247
TEST_TIME 20 8317
TEST_TIME 21 8310
TEST_TIME 22 8427
TEST_TIME 23 8471
TEST_TIME 24 8401
TEST_TIME 25 8575
TEST_TIME 26 8570
TEST_TIME 27 8969
TEST_TIME 28 9096
TEST_TIME 29 9242
TEST_TIME 30 9838
TEST_TIME 31 10199
TEST_TIME 32 10759
TEST_TIME 33 11014
TEST_TIME 34 10978
TEST_TIME 35 11248
TEST_TIME 36 10944
TEST_TIME 37 10814
TEST_TIME 38 10655
TEST_TIME 39 10626
TEST_TIME 40 10662
TEST_TIME 41 10526
TEST_TIME 42 10478
TEST_TIME 43 10387
TEST_TIME 44 10273
TEST_TIME 45 10140
TEST_TIME 46 10057
TEST_TIME 47 10030
TEST_TIME 48 9874
TEST_TIME 49 9770
TEST_TIME 50 9725
TEST_TIME 51 9650
TEST_TIME 52 9672
TEST_TIME 53 9608
TEST_TIME 54 9499
TEST_TIME 55 9492
TEST_TIME 56 9495
TEST_TIME 57 9462
TEST_TIME 58 9417
TEST_TIME 59 9383
TEST_TIME 60 9356
TEST_TIME 61 9352
TEST_TIME 62 9484
TEST_TIME 63 9567
TEST_TIME 64 9533
TEST_TIME 65 9542
TEST_TIME 66 9537
TEST_TIME 67 9555
TEST_TIME 68 9583
TEST_TIME 69 9606
TEST_TIME 70 9608
TEST_TIME 71 9491
TEST_TIME 72 9408
TEST_TIME 73 9423
TEST_TIME 74 9336
TEST_TIME 75 9362
TEST_TIME 76 9275
TEST_TIME 77 9195
TEST_TIME 78 9196
TEST_TIME 79 9115
TEST_TIME 80 9074
TEST_TIME 81 9022
TEST_TIME 82 9057
TEST_TIME 83 9020
TEST_TIME 84 9087
TEST_TIME 85 9089
TEST_TIME 86 9044
TEST_TIME 87 9053
TEST_TIME 88 9074
TEST_TIME 89 9122
TEST_TIME 90 9168
TEST_TIME 91 9296
TEST_TIME 92 9412
TEST_TIME 93 9343
TEST_TIME 94 9306
TEST_TIME 95 9280
TEST_TIME 96 9284
TEST_TIME 97 9246
TEST_TIME 98 9264
TEST_TIME 99 9247
TEST_TIME 100 9204
TEST_TIME 101 9222
TEST_TIME 102 9251
TEST_TIME 103 9198
TEST_TIME 104 9237
TEST_TIME 105 9292
TEST_TIME 106 9272
TEST_TIME 107 9249
TEST_TIME 108 9238
TEST_TIME 109 9177
TEST_TIME 110 9187
TEST_TIME 111 9145
TEST_TIME 112 9114
TEST_TIME 113 9118
TEST_TIME 114 9094
TEST_TIME 115 9088
TEST_TIME 116 9064
TEST_TIME 117 9030
TEST_TIME 118 8978
TEST_TIME 119 8925
TEST_TIME 120 8915

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-12-2022 10:39:40 to 28-12-2022 10:39:44.

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 1474483 1474483 0 0.00 0 23774 13 0 0
doc_list_biud 1474479 1474479 0 0.00 0 80321 44 0 0
sp_add_invoice_to_stock 4882 4766 116 2.37 35 200206 25672 0 116
sp_cancel_adding_invoice 5513 5513 0 0.00 6 107295 1246 0 0
sp_cancel_client_order 1708 1708 0 0.00 6 15879 414 0 0
sp_cancel_customer_reserve 541299 541299 0 0.00 1 64376 105 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 4 2290 127 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 4 1377 111 0 0
sp_cancel_supplier_invoice 6057 6043 14 0.23 3 80552 1104 0 14
sp_cancel_supplier_order 412 412 0 0.00 9 1419829 173829 0 0
sp_cancel_write_off 9778 9778 0 0.00 2 23070 149 0 0
sp_client_order 20132 20132 0 0.00 5 28541 472 0 0
sp_customer_reserve 441656 441656 0 0.00 0 87459 243 0 0
sp_fill_shopping_cart 30725 30725 0 0.00 1 9619 86 0 0
sp_fill_shopping_cart_clo_res 441635 441635 0 0.00 0 10973 16 0 0
sp_get_clo_for_invoice 4724 4724 0 0.00 0 24952 302 0 0
sp_kill_cost_storno 16325 16325 0 0.00 0 16899 68 0 0
sp_kill_qstorno_ret_qs2qd 1098831 1098831 0 0.00 0 80312 46 0 0
sp_kill_qty_storno 579242 579242 0 0.00 0 80317 105 0 0
sp_lock_selected_doc 20099 20099 0 0.00 0 6977 7 0 0
sp_make_cost_storno 27065 27065 0 0.00 0 16669 81 0 0
sp_multiply_rows_for_pdistr 27064 27064 0 0.00 0 7747 36 0 0
sp_multiply_rows_for_qdistr 20131 20131 0 0.00 0 25031 190 0 0
sp_pay_from_customer 6858 6858 0 0.00 5 17237 192 0 0
sp_pay_to_supplier 5717 5717 0 0.00 7 15762 189 0 0
sp_payment_common 12575 12575 0 0.00 2 15974 54 0 0
sp_qd_handle_on_cancel_clo 1708 1708 0 0.00 0 15870 335 0 0
sp_qd_handle_on_invoice_upd_sts 10234 10234 0 0.00 0 23096 540 0 0
sp_qd_handle_on_reserve_upd_sts 9768 9768 0 0.00 0 11712 65 0 0
sp_reserve_write_off 9768 9768 0 0.00 8 16549 285 0 0
sp_supplier_invoice 5206 5206 0 0.00 8 74357 2991 0 0
sp_supplier_order 5391 5391 0 0.00 7 25180 1383 0 0
srv_aggregate_perf_data 120 120 0 0.00 2549 183325 17122 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 659 158519 36406 0 0
srv_find_qd_qs_mism 1020064 1020064 0 0.00 0 23771 10 0 0
srv_make_invnt_saldo 430 430 0 0.00 60 152888 4940 0 0
srv_make_money_saldo 907 907 0 0.00 1 12105 424 0 0
srv_recalc_idx_stat 4 4 0 0.00 32282 550650 303754 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4770 4770 0 0.00 1 51743 1060 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 6962 494 0 0
t$perf-norm:sp_cancel_client_order 1708 1708 0 0.00 0 9354 38 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 12381 47 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 718 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 690 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1561 37 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 102720 6586 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 4381 21 0 0
t$perf-norm:sp_client_order 20132 20132 0 0.00 0 12435 42 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 5880 22 0 0
t$perf-norm:sp_pay_from_customer 6858 6858 0 0.00 0 12357 23 0 0
t$perf-norm:sp_pay_to_supplier 5717 5717 0 0.00 0 6101 19 0 0
t$perf-norm:sp_reserve_write_off 9768 9768 0 0.00 0 9980 26 0 0
t$perf-norm:sp_supplier_invoice 5207 5207 0 0.00 0 11627 35 0 0
t$perf-norm:sp_supplier_order 5391 5391 0 0.00 0 3649 31 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 609 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 3181 53 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 1742 16 0 0
t$perf-norm:srv_make_money_saldo 907 907 0 0.00 0 990 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 178 73 0 0
v_add_invoice_to_stock 9672 9440 232 2.39 0 5485 27 0 232
v_all_customers 36898 36898 0 0.00 0 222 4 0 0
v_all_suppliers 29684 29684 0 0.00 0 231 5 0 0
v_all_wares 40260 40260 0 0.00 0 64 4 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1015 16 0 0
v_cancel_client_order 3416 3416 0 0.00 0 4877 12 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 2242 17 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 1 1832 13 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 701 12 0 0
v_cancel_supplier_invoice 808 780 28 3.46 0 438 25 0 28
v_cancel_supplier_order 824 824 0 0.00 0 938 11 0 0
v_cancel_write_off 1968 1968 0 0.00 0 4561 32 0 0
v_max_id_clo_ord 5391 5391 0 0.00 0 1599 6 0 0
v_max_id_clo_res 993 993 0 0.00 0 3005 15 0 0
v_max_id_ord_sup 5203 5203 0 0.00 0 3002 7 0 0
v_max_non_paid_invoice 1469 1469 0 0.00 0 104 8 0 0
v_max_non_paid_realizn 3583 3583 0 0.00 0 901 8 0 0
v_min_id_clo_ord 5391 5391 0 0.00 0 3629 12 0 0
v_min_id_clo_res 993 993 0 0.00 0 317 8 0 0
v_min_id_ord_sup 5203 5203 0 0.00 0 2703 7 0 0
v_min_non_paid_invoice 5717 5717 0 0.00 0 6919 15 0 0
v_min_non_paid_realizn 6858 6858 0 0.00 0 15966 29 0 0
v_our_firm 9912 9912 0 0.00 0 52 5 0 0
v_reserve_write_off 19534 19534 0 0.00 0 5480 12 0 0
x_lock_depdocs_on_canc_invoice 11166 11166 0 0.00 0 14014 140 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 6453 340 0 0
x_make_qstorno_1000_1200 5391 5391 0 0.00 3 23249 1197 0 0
x_make_qstorno_1200_2000 5206 5206 0 0.00 3 74290 2776 0 0
x_make_qstorno_2100_3300 441656 441656 0 0.00 0 87457 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2022 10:39:45 to 28-12-2022 10:39:49.

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:27 2 8192000000 8799469680 8810979328 5349584 0.065 2 1 2 0 5447200 61840 35776
08:08:28 34916 8192000000 9729076432 10032209920 808101024 8.978 100 90 180 0 837700576 7994768 21604784
08:10:09 24629 8192000000 9775536480 10083799040 845365664 9.354 100 94 188 0 881619728 14078720 22175344
08:11:56 14156 8192000000 9827646064 10153136128 880103888 9.701 100 80 159 0 938548048 40290256 18153904
08:13:15 7432 8192000000 9881936288 10218303488 933699104 10.231 100 81 158 0 995033936 44048096 17286736
08:14:28 3772 8192000000 9947206208 10292940800 978186784 10.667 100 92 182 1 1057862608 61468672 18207152
08:15:36 1002 8192000000 9982737712 10338684928 1001850000 10.896 100 88 172 0 1102708928 83288928 17570000
08:16:39 1261 8192000000 9998317488 10361778176 1032759392 11.195 100 95 186 0 1112949216 60824352 19365472
08:17:42 1336 8192000000 10039443408 10416619520 1055169488 11.410 100 95 177 3 1152491104 79315776 18005840
08:18:44 956 8192000000 10052894784 10433888256 1068872272 11.541 100 96 179 1 1177203168 89949424 18381472
08:19:46 1011 8192000000 10038040064 10419576832 1071534448 11.567 100 97 183 3 1161733328 71101616 19097264
08:20:48 5268 8192000000 10059156000 10437505024 1079020896 11.638 100 85 164 1 1180175440 85698960 15455584
08:21:57 961 8192000000 10035645344 10418544640 1091769552 11.759 100 97 184 2 1161655840 50835680 19050608
08:22:58 951 8192000000 10070393616 10461466624 1093085104 11.772 100 85 165 1 1185823584 75935792 16802688
08:24:00 1299 8192000000 10085809488 10477654016 1097489376 11.814 100 98 188 1 1211301008 94753264 19058368
08:25:02 1383 8192000000 10083259808 10469236736 1103815872 11.874 100 99 200 0 1200105216 77083136 19206208
08:26:04 1243 8192000000 10077072560 10469466112 1110858352 11.941 100 90 170 2 1197688224 68618640 18211232
08:27:06 3944 8192000000 10051873712 10441240576 1089605712 11.739 99 77 140 3 1164945888 60604384 14735792
08:28:12 1518 8192000000 10052875936 10450706432 1103089968 11.867 100 72 137 2 1171397200 54504784 13802448
08:29:15 1051 8192000000 10072031152 10467188736 1106017360 11.895 100 90 165 2 1191994800 67263792 18713648
08:30:17 969 8192000000 10047998752 10435514368 1089227568 11.735 100 94 184 0 1171813008 62419056 20166384
08:31:19 1369 8192000000 10033014080 10419503104 1082562448 11.672 100 88 159 1 1157906864 59429936 15914480
08:32:21 1642 8192000000 10020540672 10402287616 1053935680 11.398 100 85 162 1 1147421840 77226592 16259568
08:33:24 932 8192000000 9987611152 10359029760 1027830688 11.148 99 80 150 1 1111214608 67866656 15517264
08:34:25 1058 8192000000 9972433616 10343870464 1029327744 11.162 100 94 173 1 1094110784 47155024 17628016
08:35:27 1633 8192000000 9955509296 10333433856 1031473984 11.183 100 77 134 1 1085117952 38570704 15073264
08:36:31 986 8192000000 9999541248 10378530816 1050204000 11.363 100 94 185 2 1125406208 55541232 19660976
08:37:34 727 8192000000 10017233920 10396241920 1058327728 11.440 100 91 178 0 1140333760 64423376 17582656
08:38:39 3049 8192000000 10039103392 10426511360 1067419568 11.527 100 80 151 0 1157987440 75766416 14801456
08:39:50 3154 8192000000 10045010640 10432098304 1086900080 11.713 100 81 147 1 1164678896 62572448 15206368
08:40:58 1364 8192000000 10060875104 10452701184 1096320416 11.803 100 91 173 1 1185324096 70470512 18533168
08:42:04 2408 8192000000 10100644960 10488647680 1103449344 11.870 100 70 132 0 1220010832 102984480 13577008
08:43:09 1284 8192000000 10117850640 10509463552 1123005936 12.055 100 92 178 1 1232351104 91410608 17934560
08:44:13 8958 8192000000 10143190464 10547281920 1127701168 12.100 100 70 137 0 1275587168 133631696 14254304
08:45:26 3362 8192000000 10180290960 10576355328 1139079488 12.207 100 78 151 0 1299824096 145429664 15314944
08:46:31 4424 8192000000 10209928864 10616541184 1148106896 12.292 100 75 146 2 1326227536 162749952 15370688
08:47:40 726 8192000000 10240383488 10639433728 1158830912 12.392 100 92 179 0 1358487296 180240288 19416096
08:48:44 981 8192000000 10271849728 10668417024 1159633648 12.400 100 89 174 2 1380747056 200272960 20840448
08:49:47 1179 8192000000 10303961792 10722942976 1160678416 12.410 100 90 177 1 1414774432 233430896 20665120
08:50:52 1614 8192000000 10327806336 10731642880 1171624992 12.512 100 88 177 0 1438040736 246358656 20057088
08:51:57 986 8192000000 10344509936 10765418496 1180748976 12.597 100 96 188 2 1454523696 252456816 21317904
08:53:01 884 8192000000 10372901216 10778644480 1183311744 12.621 100 93 178 2 1483037584 278074128 21651712
08:54:04 650 8192000000 10380294784 10803359744 1185763072 12.644 100 96 190 0 1489221184 280858848 22599264
08:55:07 1386 8192000000 10396777920 10813345792 1166268784 12.462 99 93 178 3 1506059776 320026912 19764080
08:56:12 966 8192000000 10387243232 10831249408 1176591312 12.558 100 93 182 0 1493106912 293951488 22564112
08:57:15 820 8192000000 10429363760 10845528064 1170922336 12.505 100 96 183 1 1534664608 342368336 21373936
08:58:17 1029 8192000000 10479362832 10885287936 1190497504 12.688 100 97 194 0 1582976128 369950624 22528000
08:59:21 997 8192000000 10518672096 10937872384 1190398992 12.687 100 84 162 0 1625232592 414410272 20423328
09:00:27 704 8192000000 10531862096 10963103744 1201873600 12.794 100 95 183 0 1632456096 410359424 20223072
09:01:30 755 8192000000 10562264960 10995867648 1189188032 12.676 100 97 183 1 1665189984 452421760 23580192
09:02:36 849 8192000000 10601120768 11030708224 1196307840 12.742 100 93 186 0 1703574752 484211376 23055536
09:03:40 4165 8192000000 10608095888 11065434112 1194718496 12.727 100 67 129 1 1710853680 499594912 16540272
09:04:53 13229 8192000000 10557808160 11007049728 1182502704 12.614 100 47 94 0 1678825104 483070592 13251808
09:06:10 32273 8192000000 10496250032 10975752192 1174656016 12.540 100 46 90 1 1629415408 442415040 12344352
09:07:50 27183 8192000000 10391351536 10877489152 1172474288 12.520 100 26 52 0 1513862080 334812176 6575616
09:09:18 58036 8192000000 10216346784 10718687232 1154197504 12.349 99 18 36 0 1399879232 241576464 4105264
09:11:29 30120 8192000000 10120254880 10612195328 1113508224 11.966 97 27 52 0 1207330416 88140320 5681872
09:13:00 92498 8192000000 10125979168 10574991360 1133918640 12.158 99 49 93 1 1205645344 62189888 9536816
09:15:46 97169 8192000000 10095839968 10549665792 1092032784 11.762 95 32 63 0 1159035552 60676128 6326640
09:18:26 96497 8192000000 10077001712 10551164928 1099118752 11.829 96 14 27 0 1141607856 39981136 2507968
09:21:04 95625 8192000000 10104188992 10552119296 1133942416 12.159 99 24 45 0 1192118656 52825056 5351184
09:23:45 22274 8192000000 10130744976 10601295872 1151269488 12.321 100 12 24 0 1227391328 73286864 2834976
09:25:08 158515 8192000000 10117356432 10629488640 1123423472 12.059 98 14 28 0 1228709232 101979424 3306336
09:28:47 11874 8192000000 10088244832 10539438080 1125383568 12.078 99 30 58 1 1184694976 52758800 6552608
09:30:03 56833 8192000000 10108861264 10582609920 1143408592 12.248 100 13 26 0 1213705072 67464224 2832256
09:32:38 80951 8192000000 10060784576 10577043456 1106564480 11.900 98 10 18 0 1184138144 75225168 2348496
09:35:26 48450 8192000000 10109251440 10557104128 1095676848 11.797 96 39 78 0 1156681104 53173312 7830944
09:37:24 60315 8192000000 10146150480 10615537664 1154103504 12.348 100 23 44 0 1261781408 102937952 4739952
09:39:43 57454 8192000000 10119753632 10593959936 1090540320 11.748 95 18 36 0 1158010144 63779120 3690704
09:41:41 39842 8192000000 10095330656 10535620608 1122494048 12.051 98 27 53 0 1150352000 22740160 5117792
09:43:24 15936 8192000000 10095440112 10522361856 1115881488 11.988 98 43 84 0 1176491392 52224864 8385040
09:44:54 49027 8192000000 10133720160 10594684928 1136753488 12.185 100 35 70 0 1218475040 74573104 7148448
09:46:47 109830 8192000000 10097981184 10575380480 1133330768 12.153 99 22 44 0 1192764752 54915216 4518768
09:49:56 34871 8192000000 10088558176 10558799872 1137430368 12.191 100 27 49 1 1211369520 68147728 5791424
09:51:34 101524 8192000000 10083327552 10586095616 1133620624 12.155 99 11 22 0 1197358864 61340320 2397920
09:54:57 94138 8192000000 10081138368 10568568832 1127968288 12.102 99 24 47 0 1186306496 53204336 5133872
09:57:31 67311 8192000000 10160307312 10605744128 1090888624 11.751 95 17 34 0 1211990544 116960944 4140976
09:59:55 41957 8192000000 10218622336 10670350336 1156040528 12.366 99 30 60 0 1303816496 140761984 7013984
10:01:40 33802 8192000000 10212169152 10656182272 1150863504 12.318 99 32 64 0 1314567072 156189296 7514272
10:03:22 31917 8192000000 10254972016 10693992448 1170628704 12.503 100 35 70 0 1343121872 164237152 8256016
10:05:03 20047 8192000000 10232490432 10706288640 1159341664 12.397 100 30 60 1 1360236656 193308368 7586624
10:06:31 48017 8192000000 10230205120 10708303872 1150644704 12.316 99 40 79 0 1342590816 183380192 8565920
10:08:25 91740 8192000000 10068288144 10558013440 1110002112 11.932 97 16 32 0 1194457280 81111888 3343280
10:11:09 75435 8192000000 10085053856 10560258048 1126318416 12.087 99 11 22 0 1181327664 52507920 2501328
10:13:33 84065 8192000000 10118054528 10578731008 1076775568 11.617 95 7 14 0 1113772800 36011776 985456
10:15:58 36609 8192000000 10050053376 10472648704 1094351536 11.784 98 26 52 0 1136015184 36262432 5401216
10:17:39 38457 8192000000 10102802928 10541338624 1072822992 11.579 96 37 68 1 1159117040 79340640 6953408
10:19:22 55554 8192000000 10107485696 10541264896 1111458208 11.946 99 31 62 0 1162130544 44310080 6362256
10:21:22 16583 8192000000 10106586480 10528759808 1126063472 12.084 100 68 130 0 1196920896 57727296 13130128
10:22:39 49006 8192000000 10070777968 10512609280 1112527504 11.956 99 22 45 0 1153410528 36426960 4456064
10:24:30 11781 8192000000 10112059056 10545713152 1133687456 12.156 100 55 110 0 1198107168 53783664 10636048
10:25:42 86195 8192000000 10100064208 10573651968 1138017024 12.197 100 27 54 0 1217125968 73635040 5473904
10:28:35 18656 8192000000 10059147440 10536505344 1141259024 12.227 100 10 20 0 1170267072 27445520 1562528
10:29:55 33335 8192000000 10102917872 10567073792 1142883088 12.243 100 39 76 0 1213533488 62854448 7795952
10:31:29 49175 8192000000 10068732336 10528862208 1096963424 11.809 96 27 53 0 1137146896 34758560 5424912
10:33:18 72222 8192000000 10113283584 10574389248 1130891984 12.130 99 14 28 0 1204183248 69926992 3364272
10:36:06 54242 8192000000 10144332000 10626252800 1137621904 12.193 100 13 26 0 1247467104 106742720 3102480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-12-2022 10:39:51 to 28-12-2022 10:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-12-2022 10:40:08 to 28-12-2022 10:40:10.

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          214846
MON$OLDEST_ACTIVE               218186
MON$OLDEST_SNAPSHOT             218186
MON$NEXT_TRANSACTION            218186
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                       2077984
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:623: 
:624: mscs-test-161.budzdorov.ru    Wed Dec 28 10:37:34 2022
:625:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:626: 
:627: 
:628: mscs-test-161.budzdorov.ru    Wed Dec 28 10:37:39 2022
:629:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:630: 
:631: 
:632: mscs-test-161.budzdorov.ru    Wed Dec 28 10:37:41 2022
:633:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:634: 
:635: 
:636: mscs-test-161.budzdorov.ru    Wed Dec 28 10:37:42 2022
:637:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:638: 
:639: 
:640: mscs-test-161.budzdorov.ru    Wed Dec 28 10:37:44 2022
:641:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:642: 
--- end of diff output ---

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

28.12.22 10:40:11. 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-12-28 10:40:14 No severe PSQL-related problems occured
28.12.22 10:40:15. End of report.