Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.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 14778352 kB
MemAvailable 30118448 kB
Buffers 0 kB
Cached 16951688 kB
SwapCached 3296 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-11 10:37:22
DTS_END                         2022-12-11 10:37:22.4250
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/44620
TRN_ID                          218664
ATT_ID                          662
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: 8614.88 21484 1033786
customer order: creation 167.56 490 20107
customer order: refuse 13.65 406 1638
order to supplier: creation 44.53 1463 5344
order to supplier: removal 3.08 162633 369
invoice (draft): creation 44.27 3384 5312
invoice (draft): removal 46.82 1018 5618
invoice accept: apply 40.92 27255 4910
invoice accept: cancel 42.55 1194 5106
customer reserve: creation 3765.87 263 451904
customer reserve: removal 4162.62 95 499514
realization accept: apply 80.81 287 9697
realization accept: cancel 75.78 166 9093
payment from customer: creation 57.41 199 6889
payment from customer: removal 5.69 124 683
payment to supplier: creation 48.57 198 5828
payment to supplier: removal 3.42 135 410
service: total inventory turnovers 3.41 4451 409
service: total monetary turnovers 7.09 338 851
service: aggregate perf. data 0.83 16753 99
service: refresh index statistics 0.04 208824 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-12-2022 10:40:02 to 11-12-2022 10:40:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9634
TEST_TIME 2 9048
TEST_TIME 3 7449
TEST_TIME 4 6706
TEST_TIME 5 6266
TEST_TIME 6 6117
TEST_TIME 7 6534
TEST_TIME 8 6759
TEST_TIME 9 6788
TEST_TIME 10 6937
TEST_TIME 11 7008
TEST_TIME 12 7271
TEST_TIME 13 7206
TEST_TIME 14 7097
TEST_TIME 15 7251
TEST_TIME 16 7395
TEST_TIME 17 7541
TEST_TIME 18 7835
TEST_TIME 19 7975
TEST_TIME 20 8609
TEST_TIME 21 9099
TEST_TIME 22 9101
TEST_TIME 23 8982
TEST_TIME 24 9096
TEST_TIME 25 9296
TEST_TIME 26 9237
TEST_TIME 27 9852
TEST_TIME 28 9884
TEST_TIME 29 10192
TEST_TIME 30 10341
TEST_TIME 31 10410
TEST_TIME 32 10457
TEST_TIME 33 10461
TEST_TIME 34 10370
TEST_TIME 35 10321
TEST_TIME 36 10277
TEST_TIME 37 10284
TEST_TIME 38 10115
TEST_TIME 39 10478
TEST_TIME 40 10436
TEST_TIME 41 10281
TEST_TIME 42 10136
TEST_TIME 43 9982
TEST_TIME 44 9959
TEST_TIME 45 10034
TEST_TIME 46 9982
TEST_TIME 47 9853
TEST_TIME 48 9731
TEST_TIME 49 9616
TEST_TIME 50 9575
TEST_TIME 51 9459
TEST_TIME 52 9296
TEST_TIME 53 9309
TEST_TIME 54 9215
TEST_TIME 55 9084
TEST_TIME 56 9061
TEST_TIME 57 9006
TEST_TIME 58 8987
TEST_TIME 59 8992
TEST_TIME 60 8968
TEST_TIME 61 8962
TEST_TIME 62 8912
TEST_TIME 63 8832
TEST_TIME 64 8771
TEST_TIME 65 8743
TEST_TIME 66 8833
TEST_TIME 67 9045
TEST_TIME 68 9051
TEST_TIME 69 8988
TEST_TIME 70 9058
TEST_TIME 71 9000
TEST_TIME 72 9028
TEST_TIME 73 9064
TEST_TIME 74 9015
TEST_TIME 75 8939
TEST_TIME 76 9028
TEST_TIME 77 8944
TEST_TIME 78 8927
TEST_TIME 79 8886
TEST_TIME 80 8843
TEST_TIME 81 8828
TEST_TIME 82 8741
TEST_TIME 83 8662
TEST_TIME 84 8664
TEST_TIME 85 8795
TEST_TIME 86 8760
TEST_TIME 87 8759
TEST_TIME 88 8769
TEST_TIME 89 8757
TEST_TIME 90 8803
TEST_TIME 91 8849
TEST_TIME 92 8881
TEST_TIME 93 8845
TEST_TIME 94 8883
TEST_TIME 95 8912
TEST_TIME 96 8924
TEST_TIME 97 8904
TEST_TIME 98 8850
TEST_TIME 99 8807
TEST_TIME 100 8785
TEST_TIME 101 8781
TEST_TIME 102 8714
TEST_TIME 103 8656
TEST_TIME 104 8643
TEST_TIME 105 8649
TEST_TIME 106 8691
TEST_TIME 107 8657
TEST_TIME 108 8638
TEST_TIME 109 8673
TEST_TIME 110 8688
TEST_TIME 111 8670
TEST_TIME 112 8722
TEST_TIME 113 8734
TEST_TIME 114 8797
TEST_TIME 115 8793
TEST_TIME 116 8780
TEST_TIME 117 8742
TEST_TIME 118 8724
TEST_TIME 119 8678
TEST_TIME 120 8644

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-12-2022 10:40:06 to 11-12-2022 10:40:09.

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 1449021 1449021 0 0.00 0 17593 14 0 0
doc_list_biud 1449008 1449008 0 0.00 0 24797 39 0 0
sp_add_invoice_to_stock 5057 4910 147 2.90 33 231794 27255 0 147
sp_cancel_adding_invoice 5106 5106 0 0.00 5 96778 1195 0 0
sp_cancel_client_order 1638 1638 0 0.00 6 19213 406 0 0
sp_cancel_customer_reserve 499514 499514 0 0.00 1 24809 96 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 4 4440 125 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 4 3863 136 0 0
sp_cancel_supplier_invoice 5632 5618 14 0.24 3 21760 1018 0 14
sp_cancel_supplier_order 369 369 0 0.00 11 1096687 162634 0 0
sp_cancel_write_off 9093 9093 0 0.00 2 9573 167 0 0
sp_client_order 20107 20107 0 0.00 6 22894 490 0 0
sp_customer_reserve 451904 451904 0 0.00 0 63112 263 0 0
sp_fill_shopping_cart 30748 30748 0 0.00 1 14395 86 0 0
sp_fill_shopping_cart_clo_res 451871 451871 0 0.00 0 22635 20 0 0
sp_get_clo_for_invoice 4871 4871 0 0.00 0 56577 350 0 0
sp_kill_cost_storno 15292 15292 0 0.00 0 9569 72 0 0
sp_kill_qstorno_ret_qs2qd 1014108 1014108 0 0.00 0 24588 43 0 0
sp_kill_qty_storno 535904 535904 0 0.00 0 24789 98 0 0
sp_lock_selected_doc 20171 20171 0 0.00 0 10596 7 0 0
sp_make_cost_storno 27282 27282 0 0.00 0 17551 80 0 0
sp_multiply_rows_for_pdistr 27281 27281 0 0.00 0 7197 37 0 0
sp_multiply_rows_for_qdistr 20107 20107 0 0.00 0 10805 187 0 0
sp_pay_from_customer 6889 6889 0 0.00 5 22184 199 0 0
sp_pay_to_supplier 5828 5828 0 0.00 6 23394 198 0 0
sp_payment_common 12715 12715 0 0.00 2 7393 44 0 0
sp_qd_handle_on_cancel_clo 1638 1638 0 0.00 0 11220 307 0 0
sp_qd_handle_on_invoice_upd_sts 9975 9975 0 0.00 0 23806 478 0 0
sp_qd_handle_on_reserve_upd_sts 9697 9697 0 0.00 0 10380 63 0 0
sp_reserve_write_off 9697 9697 0 0.00 8 22155 287 0 0
sp_supplier_invoice 5312 5312 0 0.00 8 105097 3384 0 0
sp_supplier_order 5344 5344 0 0.00 7 64046 1464 0 0
srv_aggregate_perf_data 99 99 0 0.00 2334 169390 16754 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 648 137431 40130 0 0
srv_find_qd_qs_mism 985571 985571 0 0.00 0 15535 11 0 0
srv_make_invnt_saldo 409 409 0 0.00 43 155138 4451 0 0
srv_make_money_saldo 851 851 0 0.00 1 15850 338 0 0
srv_recalc_idx_stat 5 5 0 0.00 45199 337796 208825 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 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 4916 4916 0 0.00 1 27246 882 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 1 7638 421 0 0
t$perf-norm:sp_cancel_client_order 1638 1638 0 0.00 0 6118 27 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 1093 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 257 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1121 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 773 28 0 0
t$perf-norm:sp_cancel_supplier_order 369 369 0 0.00 0 87689 6324 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 3895 24 0 0
t$perf-norm:sp_client_order 20107 20107 0 0.00 0 9738 36 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 2174 16 0 0
t$perf-norm:sp_pay_from_customer 6889 6889 0 0.00 0 10077 24 0 0
t$perf-norm:sp_pay_to_supplier 5828 5828 0 0.00 0 5727 20 0 0
t$perf-norm:sp_reserve_write_off 9697 9697 0 0.00 0 7014 22 0 0
t$perf-norm:sp_supplier_invoice 5312 5312 0 0.00 0 5530 29 0 0
t$perf-norm:sp_supplier_order 5344 5344 0 0.00 0 8184 34 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 147 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 176 6 0 0
t$perf-norm:srv_make_invnt_saldo 409 409 0 0.00 0 1397 15 0 0
t$perf-norm:srv_make_money_saldo 851 851 0 0.00 0 330 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 200 85 0 0
v_add_invoice_to_stock 10026 9732 294 2.93 0 20705 37 0 294
v_all_customers 36790 36790 0 0.00 0 70 4 0 0
v_all_suppliers 29692 29692 0 0.00 0 80 4 0 0
v_all_wares 40212 40212 0 0.00 0 89 4 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 4373 23 0 0
v_cancel_client_order 3276 3276 0 0.00 0 18976 23 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 4363 16 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 4060 22 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 3817 17 0 0
v_cancel_supplier_invoice 824 796 28 3.39 0 2355 32 0 28
v_cancel_supplier_order 738 738 0 0.00 0 3870 27 0 0
v_cancel_write_off 1992 1992 0 0.00 0 2047 26 0 0
v_max_id_clo_ord 5339 5339 0 0.00 0 387 5 0 0
v_max_id_clo_res 961 961 0 0.00 0 1938 12 0 0
v_max_id_ord_sup 5300 5300 0 0.00 0 5939 9 0 0
v_max_non_paid_invoice 1621 1621 0 0.00 0 90 7 0 0
v_max_non_paid_realizn 3551 3551 0 0.00 0 4265 9 0 0
v_min_id_clo_ord 5339 5339 0 0.00 0 3432 9 0 0
v_min_id_clo_res 961 961 0 0.00 0 1808 10 0 0
v_min_id_ord_sup 5300 5300 0 0.00 0 6214 11 0 0
v_min_non_paid_invoice 5827 5827 0 0.00 0 6541 15 0 0
v_min_non_paid_realizn 6887 6887 0 0.00 0 6326 18 0 0
v_our_firm 10094 10094 0 0.00 0 69 4 0 0
v_reserve_write_off 19394 19394 0 0.00 0 19821 14 0 0
x_lock_depdocs_on_canc_invoice 10326 10326 0 0.00 0 14525 125 0 0
x_lock_depdocs_on_canc_sup_ord 369 369 0 0.00 0 23725 415 0 0
x_make_qstorno_1000_1200 5344 5344 0 0.00 3 64037 1298 0 0
x_make_qstorno_1200_2000 5312 5312 0 0.00 3 105077 3133 0 0
x_make_qstorno_2100_3300 451904 451904 0 0.00 0 53192 231 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-12-2022 10:40:10 to 11-12-2022 10:40:14.

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:26 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 18738 8192000000 9724552816 10024656896 799732640 8.894 100 82 164 0 827382096 7576944 20072512
08:09:50 11459 8192000000 9759920704 10067492864 843228112 9.332 100 88 176 0 874915168 11165824 20521232
08:11:13 7806 8192000000 9789988848 10107490304 864793056 9.548 100 83 166 0 902129296 18559104 18777136
08:12:24 13421 8192000000 9847257616 10178412544 903279536 9.931 100 85 168 0 965766496 43816144 18670816
08:13:41 4450 8192000000 9896251200 10235199488 943220304 10.325 100 85 167 0 1016333360 54923440 18189616
08:14:51 1782 8192000000 9939597088 10291986432 979635056 10.681 100 84 164 0 1053308976 57096496 16577424
08:15:54 1612 8192000000 10004225728 10366193664 1011133952 10.986 100 92 176 0 1120113728 90862016 18117760
08:16:58 849 8192000000 10010433264 10377039872 1037695696 11.243 100 97 188 2 1130952112 73708128 19548288
08:18:00 1020 8192000000 10030739600 10404990976 1053620880 11.395 100 97 192 1 1157260448 84409296 19230272
08:19:03 987 8192000000 10036289168 10411556864 1058154368 11.439 100 95 183 2 1157522240 81326208 18041664
08:20:07 1002 8192000000 10013678016 10391441408 1065501776 11.509 100 95 182 1 1133635696 49756704 18377216
08:21:09 1294 8192000000 10041506048 10415099904 1070510400 11.557 100 96 185 4 1158959264 69634752 18814112
08:22:11 1235 8192000000 10039918960 10424856576 1077282352 11.622 100 96 182 0 1161990992 66255904 18452736
08:23:14 1300 8192000000 10062765040 10449874944 1080997152 11.657 100 96 181 2 1182766224 84081888 17687184
08:24:16 1576 8192000000 10041606416 10430951424 1087473568 11.719 100 94 179 1 1164976304 59352624 18150112
08:25:19 9719 8192000000 10053318960 10459860992 1089814480 11.741 100 60 116 0 1167700672 66315904 11570288
08:26:29 980 8192000000 10062703664 10455601152 1097437360 11.813 100 90 172 0 1186012256 71722272 16852624
08:27:31 1221 8192000000 10091651136 10484555776 1100885296 11.846 100 90 177 0 1210400080 91715824 17798960
08:28:34 1090 8192000000 10039517088 10426937344 1081430400 11.661 99 94 188 1 1162401760 61749280 19222080
08:29:36 1021 8192000000 10026319968 10416128000 1088147616 11.725 100 92 175 2 1143572144 35878224 19546304
08:30:37 2208 8192000000 10021438704 10416316416 1090786144 11.750 100 72 135 2 1154087696 48807232 14494320
08:31:40 2746 8192000000 10042424320 10426716160 1071661232 11.568 100 82 154 0 1169550448 82248272 15640944
08:32:44 1323 8192000000 10005950752 10385514496 1060025968 11.457 100 95 179 0 1127879904 49604272 18249664
08:33:47 1317 8192000000 10000169408 10370916352 1026043584 11.130 98 96 182 4 1122978720 78638848 18296288
08:34:49 1235 8192000000 9999660576 10374406144 1046021648 11.323 100 92 179 0 1127708608 62332992 19353968
08:35:51 1073 8192000000 9973277968 10342555648 1019886688 11.071 100 91 169 2 1098153200 59543760 18722752
08:36:53 1119 8192000000 9992769840 10364780544 1030129968 11.170 100 88 166 0 1111436160 65002384 16303808
08:37:57 8229 8192000000 9989893120 10376818688 1064124912 11.496 100 63 117 0 1114443168 38541248 11777008
08:39:16 1152 8192000000 10032282736 10414645248 1074701744 11.597 100 93 179 2 1155014816 60541744 19771328
08:40:24 10073 8192000000 10054552624 10438934528 1093465600 11.776 100 69 134 2 1171494016 63712656 14315760
08:41:37 3629 8192000000 10073193056 10467356672 1110989312 11.942 100 65 124 2 1191644016 67959920 12694784
08:42:44 14006 8192000000 10152781680 10550931456 1125593968 12.080 100 63 120 0 1260793984 122287920 12912096
08:44:03 935 8192000000 10189152992 10582966272 1137451152 12.192 100 99 189 0 1306442224 147551808 21439264
08:45:07 3652 8192000000 10195380992 10605129728 1146996240 12.281 100 61 120 3 1312709280 152977584 12735456
08:46:13 1530 8192000000 10236163264 10641514496 1164247984 12.443 100 88 169 2 1352033088 168894800 18890304
08:47:17 645 8192000000 10246244192 10653163520 1160511792 12.408 100 94 182 3 1359854752 178596112 20746848
08:48:23 906 8192000000 10277692656 10686111744 1172191504 12.517 100 94 175 1 1385585264 193440912 19952848
08:49:27 1437 8192000000 10304608864 10719989760 1178397152 12.575 100 94 183 1 1430115360 230883408 20834800
08:50:35 789 8192000000 10348079840 10748624896 1184230272 12.630 100 96 184 0 1460646592 254762880 21653440
08:51:41 1043 8192000000 10383608096 10785685504 1186020976 12.646 100 94 183 1 1493221392 285988672 21211744
08:52:45 1238 8192000000 10400043152 10823262208 1186235888 12.648 100 95 184 1 1511798432 303263744 22298800
08:53:50 1638 8192000000 10455836928 10884915200 1193550880 12.716 100 92 182 0 1562989120 347306528 22131712
08:54:54 7213 8192000000 10415539104 10869440512 1180784528 12.598 100 65 122 0 1521470720 324902272 15783920
08:56:04 11277 8192000000 10434480160 10874617856 1167991888 12.478 99 57 107 1 1535498208 352837520 14668800
08:57:18 2871 8192000000 10402272912 10888900608 1180273968 12.593 100 84 166 0 1509891488 309363088 20254432
08:58:35 13987 8192000000 10347314352 10818056192 1178005600 12.572 100 33 66 0 1464595200 278054400 8535200
08:59:54 11047 8192000000 10348945840 10793656320 1183784768 12.625 100 37 74 1 1465163376 272360848 9017760
09:01:11 1854 8192000000 10358802448 10785636352 1157767136 12.382 98 64 124 0 1461836848 289047696 15022016
09:02:14 14052 8192000000 10369640960 10843090944 1152721088 12.335 99 45 88 2 1482089440 318083760 11284592
09:03:44 29885 8192000000 10312812352 10785193984 1145623744 12.268 99 30 60 1 1422313600 268638688 8051168
09:05:36 34640 8192000000 10226404384 10691055616 1139132320 12.207 99 39 77 0 1352788352 204369744 9286288
09:07:13 23314 8192000000 10234627488 10665455616 1139346064 12.209 99 35 68 0 1327087920 179908880 7832976
09:08:45 9331 8192000000 10235242944 10679599104 1138160384 12.198 100 58 117 1 1340494352 189933712 12400256
09:10:03 2665 8192000000 10248039920 10673041408 1138980608 12.206 100 74 146 1 1343468128 187487136 17000384
09:11:14 39803 8192000000 10267520768 10723500032 1150268480 12.312 100 51 100 0 1386122720 224341952 11512288
09:12:55 65008 8192000000 10260601600 10737020928 1149022752 12.300 100 22 44 0 1366200368 211209152 5968464
09:15:24 89056 8192000000 10117035776 10642825216 1126126272 12.085 99 12 22 0 1294002624 165177392 2698960
09:17:55 24524 8192000000 10068233088 10500521984 1118380224 12.012 99 51 102 0 1151088176 21274560 11433392
09:19:26 26202 8192000000 10073945056 10501222400 1116942528 11.998 99 39 74 0 1156517040 32501104 7073408
09:20:58 84100 8192000000 10111042560 10625683456 1126837312 12.092 99 17 34 0 1230480224 99625296 4017616
09:23:23 44688 8192000000 10052603248 10541346816 1127553888 12.098 100 7 14 0 1164570864 35321648 1695328
09:25:09 61789 8192000000 10063086480 10515750912 1123705264 12.062 99 16 32 0 1163938224 36578752 3654208
09:27:11 137430 8192000000 10048491696 10527731712 1130547952 12.127 100 19 38 0 1198052784 63292352 4212480
09:30:34 86636 8192000000 10039967040 10499026944 1105452304 11.889 97 20 40 0 1134902160 25574448 3875408
09:33:02 22660 8192000000 10065525856 10510708736 1115633856 11.986 99 51 98 1 1172302064 46571152 10097056
09:34:28 99819 8192000000 10087805392 10553888768 1126949648 12.093 99 14 28 0 1194808080 64752672 3105760
09:37:08 64838 8192000000 10063633232 10545053696 1113532736 11.966 98 13 26 0 1152098144 36024176 2541232
09:39:41 90714 8192000000 10151849680 10588364800 1106223072 11.897 96 29 58 0 1184769088 72804240 5741776
09:42:15 20177 8192000000 10230743296 10666860544 1144681584 12.260 100 37 74 1 1316660592 164031168 7947840
09:43:46 52179 8192000000 10107737040 10635149312 1138741616 12.204 100 31 60 0 1244747456 99474640 6531200
09:45:39 36792 8192000000 10096439248 10530385920 1129979856 12.121 99 36 71 0 1168906032 31868208 7057968
09:47:25 28433 8192000000 10109548512 10577022976 1135515424 12.173 99 49 96 1 1240985456 95324672 10145360
09:48:55 16633 8192000000 10155935840 10584367104 1142467744 12.239 100 63 126 0 1259375520 103843824 13063952
09:50:13 41520 8192000000 10118048736 10564108288 1125622160 12.080 100 37 74 0 1202126624 69221136 7283328
09:51:56 102431 8192000000 10103100256 10595328000 1131679456 12.137 100 41 82 0 1256794064 116444704 8669904
09:54:43 59938 8192000000 10062039040 10524274688 1112467568 11.956 98 24 46 1 1169489392 52792272 4229552
09:56:44 64596 8192000000 10070132256 10552369152 1123529408 12.060 99 10 20 0 1173376656 47489568 2357680
09:59:01 100019 8192000000 10137931936 10580303872 1105047840 11.886 97 19 37 0 1188070224 79320560 3701824
10:01:44 86725 8192000000 10051169424 10523963392 1089961104 11.742 96 20 38 1 1140733904 46526432 4246368
10:04:14 35283 8192000000 10109518336 10569175040 1118104416 12.009 99 39 74 0 1186309392 59996208 8208768
10:06:09 9547 8192000000 10070938928 10497929216 1109418272 11.927 99 40 81 0 1156143392 38383104 8342016
10:07:21 70792 8192000000 10104574016 10575806464 1116870528 11.997 99 15 30 0 1193274896 73698560 2705808
10:09:37 52488 8192000000 10100482016 10556387328 1095658816 11.796 97 24 45 1 1171019248 70802480 4557952
10:12:24 57933 8192000000 10088526272 10533040128 1108141776 11.915 98 31 62 1 1156723792 42499248 6082768
10:14:28 21536 8192000000 10059919520 10480517120 1096852672 11.808 97 51 95 1 1144270160 37763600 9653888
10:15:51 101909 8192000000 10102093424 10568957952 1107237104 11.906 98 44 88 1 1198502640 82199888 9065648
10:18:34 74367 8192000000 10088050912 10564321280 1131577616 12.136 100 12 24 0 1201571040 67291856 2701568
10:20:51 81257 8192000000 10091072592 10563039232 1104634176 11.882 97 20 38 0 1196715520 88155408 3925936
10:23:51 128577 8192000000 10157908464 10609369088 1112106736 11.952 97 21 42 0 1230520784 113758656 4655392
10:27:10 57560 8192000000 10107630512 10568888320 1110919088 11.941 98 24 48 0 1208150704 91804800 5426816
10:29:35 50747 8192000000 10112753488 10572156928 1118147568 12.009 99 37 74 0 1209068656 83376192 7544896
10:31:29 67708 8192000000 10130765488 10588876800 1128726000 12.109 99 38 74 0 1209477472 73345936 7405536
10:33:43 95203 8192000000 10060083536 10520555520 1098952400 11.828 97 43 86 0 1147444976 39847520 8645056
10:36:25 23815 8192000000 10084034640 10545811456 1126482592 12.088 100 13 26 0 1173803392 44398528 2922272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-12-2022 10:40:16 to 11-12-2022 10:40:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 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 11-12-2022 10:40:33 to 11-12-2022 10:40:35.

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          218051
MON$OLDEST_ACTIVE               219091
MON$OLDEST_SNAPSHOT             219091
MON$NEXT_TRANSACTION            219091
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                       2072544
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2884 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2884 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2884 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2884 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 ---
:75: 
:76: mscs-test-161.budzdorov.ru    Sun Dec 11 10:38:03 2022
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:78: 
--- end of diff output ---

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

11.12.22 10:40:36. 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-11 10:40:38 No severe PSQL-related problems occured
11.12.22 10:40:40. End of report.