Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.01.2023 04:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 14969376 kB
MemAvailable 30388516 kB
Buffers 20 kB
Cached 16693948 kB
SwapCached 10260 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 2023-01-01 04:37:22
DTS_END                         2023-01-01 04:37:22.9590
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/49212
TRN_ID                          213212
ATT_ID                          572
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: 8574.98 23985 1028997
customer order: creation 169.16 518 20299
customer order: refuse 14.27 452 1712
order to supplier: creation 44.23 1458 5308
order to supplier: removal 3.44 181743 413
invoice (draft): creation 45.18 3378 5422
invoice (draft): removal 46.24 1192 5549
invoice accept: apply 40.03 27283 4804
invoice accept: cancel 41.37 1523 4964
customer reserve: creation 3791.92 254 455030
customer reserve: removal 4098.85 120 491862
realization accept: apply 82.43 313 9892
realization accept: cancel 72.52 188 8702
payment from customer: creation 57.81 192 6937
payment from customer: removal 5.17 157 620
payment to supplier: creation 46.88 214 5625
payment to supplier: removal 3.68 105 442
service: total inventory turnovers 3.69 4757 443
service: total monetary turnovers 7.13 476 856
service: aggregate perf. data 0.93 17647 112
service: refresh index statistics 0.04 237737 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-01-2023 04:39:56 to 01-01-2023 04:39:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8731
TEST_TIME 2 6891
TEST_TIME 3 6281
TEST_TIME 4 6349
TEST_TIME 5 6302
TEST_TIME 6 6462
TEST_TIME 7 6357
TEST_TIME 8 6006
TEST_TIME 9 5908
TEST_TIME 10 5833
TEST_TIME 11 6096
TEST_TIME 12 6114
TEST_TIME 13 6224
TEST_TIME 14 6512
TEST_TIME 15 6550
TEST_TIME 16 6815
TEST_TIME 17 6959
TEST_TIME 18 7134
TEST_TIME 19 7191
TEST_TIME 20 7375
TEST_TIME 21 7301
TEST_TIME 22 7663
TEST_TIME 23 7607
TEST_TIME 24 7737
TEST_TIME 25 7980
TEST_TIME 26 8231
TEST_TIME 27 8453
TEST_TIME 28 8492
TEST_TIME 29 8699
TEST_TIME 30 8711
TEST_TIME 31 8986
TEST_TIME 32 9023
TEST_TIME 33 9189
TEST_TIME 34 9167
TEST_TIME 35 9650
TEST_TIME 36 10244
TEST_TIME 37 10330
TEST_TIME 38 10108
TEST_TIME 39 9974
TEST_TIME 40 10184
TEST_TIME 41 10118
TEST_TIME 42 9933
TEST_TIME 43 9885
TEST_TIME 44 9713
TEST_TIME 45 9580
TEST_TIME 46 9513
TEST_TIME 47 9383
TEST_TIME 48 9314
TEST_TIME 49 9373
TEST_TIME 50 9290
TEST_TIME 51 9324
TEST_TIME 52 9316
TEST_TIME 53 9277
TEST_TIME 54 9343
TEST_TIME 55 9322
TEST_TIME 56 9351
TEST_TIME 57 9344
TEST_TIME 58 9344
TEST_TIME 59 9234
TEST_TIME 60 9195
TEST_TIME 61 9135
TEST_TIME 62 9088
TEST_TIME 63 9058
TEST_TIME 64 9037
TEST_TIME 65 8997
TEST_TIME 66 8997
TEST_TIME 67 8968
TEST_TIME 68 8935
TEST_TIME 69 8907
TEST_TIME 70 8875
TEST_TIME 71 8875
TEST_TIME 72 8887
TEST_TIME 73 8893
TEST_TIME 74 8909
TEST_TIME 75 8831
TEST_TIME 76 8852
TEST_TIME 77 8861
TEST_TIME 78 8810
TEST_TIME 79 8785
TEST_TIME 80 8804
TEST_TIME 81 8816
TEST_TIME 82 8817
TEST_TIME 83 8894
TEST_TIME 84 8902
TEST_TIME 85 8882
TEST_TIME 86 8878
TEST_TIME 87 8885
TEST_TIME 88 8893
TEST_TIME 89 8887
TEST_TIME 90 8902
TEST_TIME 91 8834
TEST_TIME 92 8840
TEST_TIME 93 8840
TEST_TIME 94 8787
TEST_TIME 95 8729
TEST_TIME 96 8714
TEST_TIME 97 8694
TEST_TIME 98 8704
TEST_TIME 99 8668
TEST_TIME 100 8649
TEST_TIME 101 8662
TEST_TIME 102 8716
TEST_TIME 103 8704
TEST_TIME 104 8691
TEST_TIME 105 8665
TEST_TIME 106 8766
TEST_TIME 107 8793
TEST_TIME 108 8830
TEST_TIME 109 8876
TEST_TIME 110 8872
TEST_TIME 111 8865
TEST_TIME 112 8815
TEST_TIME 113 8757
TEST_TIME 114 8770
TEST_TIME 115 8726
TEST_TIME 116 8753
TEST_TIME 117 8721
TEST_TIME 118 8685
TEST_TIME 119 8648
TEST_TIME 120 8595

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-01-2023 04:40:00 to 01-01-2023 04:40:03.

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 1448226 1448226 0 0.00 0 24730 14 0 0
doc_list_biud 1448208 1448208 0 0.00 0 29738 47 0 0
sp_add_invoice_to_stock 4865 4804 61 1.25 72 207893 27284 0 61
sp_cancel_adding_invoice 4964 4964 0 0.00 7 103008 1524 0 0
sp_cancel_client_order 1712 1712 0 0.00 7 10097 452 0 0
sp_cancel_customer_reserve 491862 491862 0 0.00 1 25136 120 0 0
sp_cancel_pay_from_customer 620 620 0 0.00 3 4138 158 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 4 3858 105 0 0
sp_cancel_supplier_invoice 5555 5549 6 0.10 3 30010 1193 0 6
sp_cancel_supplier_order 413 413 0 0.00 12 1437196 181743 0 0
sp_cancel_write_off 8702 8702 0 0.00 2 22153 188 0 0
sp_client_order 20299 20299 0 0.00 5 26825 519 0 0
sp_customer_reserve 455030 455030 0 0.00 0 114196 254 0 0
sp_fill_shopping_cart 31018 31018 0 0.00 1 19900 84 0 0
sp_fill_shopping_cart_clo_res 455008 455008 0 0.00 0 21635 21 0 0
sp_get_clo_for_invoice 4778 4778 0 0.00 0 25061 317 0 0
sp_kill_cost_storno 14728 14728 0 0.00 0 24721 98 0 0
sp_kill_qstorno_ret_qs2qd 998388 998388 0 0.00 0 29724 52 0 0
sp_kill_qty_storno 527868 527868 0 0.00 0 29731 119 0 0
sp_lock_selected_doc 20304 20304 0 0.00 0 7332 8 0 0
sp_make_cost_storno 27232 27232 0 0.00 0 16466 89 0 0
sp_multiply_rows_for_pdistr 27232 27232 0 0.00 0 10609 44 0 0
sp_multiply_rows_for_qdistr 20299 20299 0 0.00 0 10896 181 0 0
sp_pay_from_customer 6937 6937 0 0.00 5 13151 192 0 0
sp_pay_to_supplier 5625 5625 0 0.00 6 12772 214 0 0
sp_payment_common 12561 12561 0 0.00 2 10467 49 0 0
sp_qd_handle_on_cancel_clo 1712 1712 0 0.00 0 10005 381 0 0
sp_qd_handle_on_invoice_upd_sts 9742 9742 0 0.00 0 23186 582 0 0
sp_qd_handle_on_reserve_upd_sts 9888 9888 0 0.00 0 21890 74 0 0
sp_reserve_write_off 9892 9892 0 0.00 8 22055 314 0 0
sp_supplier_invoice 5422 5422 0 0.00 10 86470 3379 0 0
sp_supplier_order 5308 5308 0 0.00 7 39580 1459 0 0
srv_aggregate_perf_data 112 112 0 0.00 2204 254433 17648 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 927 151772 33489 0 0
srv_find_qd_qs_mism 981240 981240 0 0.00 0 17100 10 0 0
srv_make_invnt_saldo 443 443 0 0.00 55 100551 4758 0 0
srv_make_money_saldo 856 856 0 0.00 1 14883 477 0 0
srv_recalc_idx_stat 5 5 0 0.00 52110 363496 237738 0 0
t$perf-abend:sp_add_invoice_to_stock 61 61 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4807 4807 0 0.00 1 14445 969 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 2 7893 601 0 0
t$perf-norm:sp_cancel_client_order 1712 1712 0 0.00 0 3275 32 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 952 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 620 620 0 0.00 0 7349 31 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 1363 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 4528 48 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 94188 8225 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 1043 17 0 0
t$perf-norm:sp_client_order 20299 20299 0 0.00 0 6882 39 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 6157 23 0 0
t$perf-norm:sp_pay_from_customer 6937 6937 0 0.00 0 5491 20 0 0
t$perf-norm:sp_pay_to_supplier 5625 5625 0 0.00 0 7656 22 0 0
t$perf-norm:sp_reserve_write_off 9892 9892 0 0.00 0 3624 21 0 0
t$perf-norm:sp_supplier_invoice 5422 5422 0 0.00 0 8239 33 0 0
t$perf-norm:sp_supplier_order 5308 5308 0 0.00 0 7572 34 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 5274 62 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 107 6 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 1682 22 0 0
t$perf-norm:srv_make_money_saldo 856 856 0 0.00 0 3026 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 201 56 0 0
v_add_invoice_to_stock 9672 9550 122 1.26 0 7615 26 0 122
v_all_customers 36940 36940 0 0.00 0 102 4 0 0
v_all_suppliers 30048 30048 0 0.00 0 108 4 0 0
v_all_wares 40590 40590 0 0.00 0 57 4 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 6249 22 0 0
v_cancel_client_order 3424 3424 0 0.00 0 3216 12 0 0
v_cancel_customer_prepayment 1240 1240 0 0.00 0 3528 14 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 3351 17 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 657 11 0 0
v_cancel_supplier_invoice 808 796 12 1.48 0 2767 25 0 12
v_cancel_supplier_order 826 826 0 0.00 0 3845 24 0 0
v_cancel_write_off 1972 1972 0 0.00 0 2798 23 0 0
v_max_id_clo_ord 5303 5303 0 0.00 0 703 5 0 0
v_max_id_clo_res 962 962 0 0.00 0 2685 13 0 0
v_max_id_ord_sup 5418 5418 0 0.00 0 3105 8 0 0
v_max_non_paid_invoice 1321 1321 0 0.00 0 3650 10 0 0
v_max_non_paid_realizn 3701 3701 0 0.00 0 2250 8 0 0
v_min_id_clo_ord 5303 5303 0 0.00 0 2312 9 0 0
v_min_id_clo_res 962 962 0 0.00 0 5642 19 0 0
v_min_id_ord_sup 5418 5418 0 0.00 0 7829 15 0 0
v_min_non_paid_invoice 5624 5624 0 0.00 0 8515 20 0 0
v_min_non_paid_realizn 6937 6937 0 0.00 0 8700 18 0 0
v_our_firm 10122 10122 0 0.00 0 147 5 0 0
v_reserve_write_off 19776 19776 0 0.00 0 4841 11 0 0
x_lock_depdocs_on_canc_invoice 10115 10115 0 0.00 0 9825 142 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 3324 411 0 0
x_make_qstorno_1000_1200 5307 5307 0 0.00 2 39540 1289 0 0
x_make_qstorno_1200_2000 5422 5422 0 0.00 5 75660 3149 0 0
x_make_qstorno_2100_3300 455029 455029 0 0.00 0 114192 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-01-2023 04:40:04 to 01-01-2023 04:40:08.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:27 30108 8192000000 9731731152 10032619520 806725424 8.964 100 88 176 0 836069392 7532256 21811712
02:10:01 30761 8192000000 9772631968 10083155968 845343424 9.353 100 89 178 0 880186448 13348368 21494656
02:11:40 14389 8192000000 9821769840 10142941184 873773344 9.638 100 80 157 0 921729616 29674688 18281584
02:13:23 10553 8192000000 9887049968 10223235072 932141584 10.216 100 82 164 0 999226960 49207808 17877568
02:14:37 3272 8192000000 9933224448 10280058880 974643680 10.632 100 89 174 1 1049968368 56898992 18425696
02:15:44 2123 8192000000 9982055440 10334990336 1004794560 10.925 100 88 176 0 1098581184 76148816 17637808
02:16:50 1459 8192000000 10002739312 10365353984 1020202176 11.074 100 93 169 0 1118319760 81856896 16260688
02:17:54 1468 8192000000 10012658992 10381758464 1039029040 11.255 100 92 182 0 1134148208 77537808 17581360
02:18:56 1153 8192000000 10001379472 10373914624 1049953728 11.360 100 92 174 0 1126623232 59344736 17324768
02:20:00 1569 8192000000 10028515520 10402017280 1060678336 11.463 100 96 187 0 1149915776 70194016 19043424
02:21:04 1243 8192000000 10025507904 10404483072 1067728352 11.530 100 98 186 4 1147983072 60777440 19477280
02:22:06 1069 8192000000 10069515536 10451955712 1079648432 11.644 100 98 191 0 1190359312 91745040 18965840
02:23:09 3797 8192000000 10050670800 10442272768 1079580288 11.643 100 72 138 1 1178482800 84764096 14138416
02:24:15 2682 8192000000 10030173296 10418126848 1084344960 11.689 100 79 139 2 1159450592 61175520 13930112
02:25:19 1352 8192000000 10053963664 10443481088 1090846144 11.751 100 88 173 2 1181718960 72156896 18715920
02:26:21 6862 8192000000 10052956400 10442477568 1093803664 11.779 100 70 138 1 1182535952 74613824 14118464
02:27:31 1314 8192000000 10073710768 10466234368 1097972176 11.818 100 95 186 3 1194737216 76591232 20173808
02:28:33 855 8192000000 10062467696 10456510464 1100076416 11.838 100 88 174 1 1183564640 66049744 17438480
02:29:35 1248 8192000000 10069640416 10461589504 1092501312 11.766 100 95 182 0 1192470832 80855056 19114464
02:30:37 1125 8192000000 10029804400 10413490176 1066986576 11.523 99 93 178 2 1152845392 68589184 17269632
02:31:39 2079 8192000000 10013296720 10402095104 1069698832 11.549 100 68 121 1 1136622976 55189136 11735008
02:32:42 1930 8192000000 10025347840 10409193472 1060723312 11.463 100 79 152 3 1142556992 66683216 15150464
02:33:46 1288 8192000000 9996006832 10373320704 1050764432 11.368 100 91 176 1 1120890192 52067408 18058352
02:34:49 1181 8192000000 9996685520 10371899392 1034208176 11.209 100 99 187 0 1117782240 64678256 18895808
02:35:51 1124 8192000000 9988210320 10361470976 1036262832 11.229 100 92 167 1 1109228544 55338944 17626768
02:36:54 1151 8192000000 9993886656 10372812800 1029039280 11.159 100 78 151 1 1119054752 72767264 17248208
02:38:01 6467 8192000000 9994645648 10373881856 1053251280 11.392 100 79 147 2 1109498656 41737408 14509968
02:39:14 26152 8192000000 10037410976 10423287808 1071621200 11.568 100 59 112 2 1157206192 74012032 11572960
02:40:44 1748 8192000000 10081711872 10461540352 1090927536 11.751 100 95 182 1 1201557456 91124864 19505056
02:41:53 1135 8192000000 10094176704 10476777472 1095534912 11.795 100 91 175 0 1212647968 99045664 18067392
02:42:59 1618 8192000000 10126405904 10519642112 1113959008 11.970 100 93 181 0 1250503152 116337216 20206928
02:44:05 3819 8192000000 10159722304 10570473472 1128270208 12.105 100 87 164 0 1276591504 129920832 18400464
02:45:16 9077 8192000000 10178300912 10579898368 1133137264 12.151 100 64 127 0 1292073808 145443680 13492864
02:46:29 1363 8192000000 10224014208 10619047936 1144188016 12.255 100 94 181 3 1339452944 173476032 21788896
02:47:34 2794 8192000000 10262020384 10660732928 1144396080 12.257 100 88 173 1 1381364864 216552608 20416176
02:48:40 1380 8192000000 10296235104 10693574656 1159062688 12.394 100 96 187 0 1412514704 231019824 22432192
02:49:45 1041 8192000000 10335552016 10744057856 1169567888 12.493 100 96 184 1 1445583408 253637504 22378016
02:50:50 1646 8192000000 10351256032 10774446080 1176958256 12.562 100 95 186 1 1465743584 267646560 21138768
02:51:57 1848 8192000000 10349890080 10763997184 1184212160 12.629 100 93 178 0 1460658080 255976304 20469616
02:53:03 1734 8192000000 10377541760 10810560512 1188609280 12.670 100 95 182 2 1492842752 283379664 20853808
02:54:08 1060 8192000000 10423711104 10845003776 1195769376 12.737 100 94 186 1 1534066048 315838336 22458336
02:55:12 1166 8192000000 10436193856 10867417088 1195678048 12.736 100 95 190 3 1540617424 322903456 22035920
02:56:18 2700 8192000000 10479363744 10899369984 1196707792 12.746 100 95 184 0 1588455296 370492976 21254528
02:57:29 1171 8192000000 10495131520 10923995136 1205041632 12.823 100 98 189 3 1600142704 371713600 23387472
02:58:34 2608 8192000000 10532580752 10974724096 1209905856 12.868 100 91 182 0 1638662096 406786528 21969712
02:59:40 1077 8192000000 10526397584 10966650880 1215130672 12.917 100 96 189 1 1632792656 394259968 23402016
03:00:44 913 8192000000 10587458208 11000164352 1209154560 12.861 100 96 187 0 1691410816 459844304 22411952
03:01:50 993 8192000000 10538469520 11010617344 1206487632 12.837 100 99 194 2 1642651328 412540464 23623232
03:02:53 1755 8192000000 10542466224 11039629312 1197784736 12.756 100 78 154 1 1648848288 431358896 19704656
03:04:00 976 8192000000 10549445040 10991624192 1193488880 12.716 100 84 157 0 1654446000 441068096 19889024
03:05:06 7847 8192000000 10512793632 10971181056 1185843376 12.645 100 55 107 1 1606124592 406890192 13391024
03:06:17 5811 8192000000 10481473344 10948972544 1172848048 12.523 99 57 112 1 1573452112 386757120 13846944
03:07:29 10639 8192000000 10479692608 10911891456 1173975424 12.534 100 43 84 0 1580642160 396093968 10572768
03:08:42 25793 8192000000 10419309104 10893840384 1159141568 12.395 98 41 79 1 1506094464 336789280 10163616
03:10:13 12840 8192000000 10459605968 10906148864 1161084448 12.413 100 35 70 1 1569381520 398294000 10003072
03:11:31 9753 8192000000 10434945360 10892906496 1163555712 12.437 100 33 66 0 1559179536 387171904 8451920
03:12:43 13072 8192000000 10297546208 10823540736 1154563792 12.352 100 27 53 1 1402792816 241477616 6751408
03:13:59 15961 8192000000 10177100640 10632626176 1138413504 12.201 99 43 79 2 1277207504 129092320 9701680
03:15:29 9630 8192000000 10145536800 10577108992 1136743680 12.185 99 59 105 3 1241958864 92839104 12376080
03:16:43 51563 8192000000 10164417200 10639712256 1140115104 12.217 99 30 58 0 1273764928 127360560 6289264
03:19:50 100694 8192000000 10079117456 10546212864 1119470976 12.022 97 31 56 0 1148464688 23239952 5753760
03:22:33 83498 8192000000 10079651536 10539282432 1132090592 12.141 99 29 59 1 1180295280 42143392 6061296
03:25:06 60627 8192000000 10122931376 10577059840 1115809712 11.987 97 19 38 0 1187401664 67312736 4279216
03:27:15 31092 8192000000 10096391280 10530283520 1133295200 12.152 99 30 45 2 1203857968 66054896 4507872
03:28:50 39947 8192000000 10079934528 10542559232 1120219408 12.029 98 10 20 0 1161179728 39173648 1786672
03:30:31 73834 8192000000 10068215616 10542485504 1097399584 11.813 96 14 28 0 1137997536 37480400 3117552
03:32:51 151771 8192000000 10100045408 10585677824 1087940496 11.723 95 12 24 0 1158447792 67744368 2762928
03:36:24 87642 8192000000 10064861120 10496315392 1104221344 11.878 97 21 40 0 1128478688 20576096 3681248
03:38:52 127016 8192000000 10062207728 10507202560 1088909104 11.732 96 43 82 2 1141190144 43029280 9251760
03:42:01 22591 8192000000 10092166464 10525913088 1133921104 12.158 100 63 126 0 1197104736 49662304 13521328
03:43:26 94904 8192000000 10118677552 10571296768 1122866208 12.054 98 35 70 0 1221514720 90407328 8241184
03:47:14 42344 8192000000 10127273392 10605465600 1127828512 12.101 99 24 47 0 1258823664 125537360 5457792
03:49:01 88644 8192000000 10099318096 10588086272 1131547264 12.136 99 25 48 1 1244023712 106605920 5870528
03:51:34 37856 8192000000 10107496416 10586431488 1127246912 12.095 99 13 25 0 1208068032 78545440 2275680
03:54:09 70841 8192000000 10056861760 10503606272 1134400384 12.163 100 14 26 0 1159701456 22326624 2974448
03:56:23 66222 8192000000 10095751792 10577272832 1110794368 11.940 97 33 66 1 1195777152 78343632 6639152
03:58:29 33022 8192000000 10117254352 10586861568 1137150624 12.189 100 28 54 0 1208151936 65600416 5400896
04:00:03 73329 8192000000 10084654608 10568069120 1115793680 11.987 98 9 18 0 1158824416 41497968 1532768
04:02:17 16792 8192000000 10090961088 10522939392 1109529328 11.928 98 64 124 1 1158536176 36600176 12406672
04:03:36 6578 8192000000 10106663744 10521493504 1116232624 11.991 100 63 127 0 1187406016 58399920 12773472
04:04:48 15675 8192000000 10092140640 10521804800 1120250640 12.029 100 50 100 0 1189196368 58927328 10018400
04:06:29 3943 8192000000 10097493632 10513113088 1115128768 11.981 100 77 142 2 1183757904 54711808 13917328
04:07:34 115715 8192000000 10098063824 10594336768 1111532416 11.947 98 26 52 0 1206799440 89714624 5552400
04:11:27 67432 8192000000 10061494752 10528755712 1122788288 12.053 99 5 10 0 1160710224 36271536 1650400
04:13:35 72324 8192000000 10083249904 10529767424 1118961264 12.017 98 11 22 0 1164718288 43524144 2232880
04:15:56 53631 8192000000 10127733392 10567651328 1138254608 12.199 99 27 51 0 1212230896 68480400 5495888
04:17:52 24123 8192000000 10155636928 10598875136 1139541008 12.211 99 33 66 0 1238933904 91911232 7481664
04:19:25 53042 8192000000 10131356800 10578743296 1120558288 12.032 97 21 42 1 1225261504 100188624 4514592
04:21:23 27944 8192000000 10174263568 10603024384 1139108656 12.207 100 50 100 0 1265303760 115517776 10677328
04:22:52 40264 8192000000 10232802304 10701873152 1129285152 12.115 99 38 72 0 1319111520 181306336 8520032
04:25:23 20392 8192000000 10081268960 10512052224 1133623856 12.156 100 47 88 1 1174575568 30217808 10733904
04:26:49 46909 8192000000 10092521984 10554789888 1119223744 12.020 99 21 40 0 1168305728 45289136 3792848
04:28:39 18348 8192000000 10083523760 10521137152 1124944704 12.074 99 51 95 1 1175707072 41476288 9286080
04:30:00 65761 8192000000 10109809088 10549092352 1130921360 12.130 100 56 108 1 1199396176 56958432 11516384
04:32:09 33503 8192000000 10082422944 10536943616 1117008432 11.999 98 17 32 0 1181218848 61305056 2905360
04:33:46 108166 8192000000 10110335936 10555559936 1123407600 12.059 98 12 25 0 1167003008 40985616 2609792
04:36:34 61814 8192000000 9572719760 10000658432 675097088 7.613 60 9 18 0 755936288 78497168 2342032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 01-01-2023 04:40:10 to 01-01-2023 04:40:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 61 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          213211
MON$OLDEST_ACTIVE               213862
MON$OLDEST_SNAPSHOT             213862
MON$NEXT_TRANSACTION            213862
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                       2073376
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             759
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 ---
:781: 
:782: mscs-test-161.budzdorov.ru    Sun Jan  1 04:37:58 2023
:783:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:784: 
:785: 
:786: mscs-test-161.budzdorov.ru    Sun Jan  1 04:38:20 2023
:787:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:788: 
--- end of diff output ---

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

01.01.23 04:40:31. 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
2023-01-01 04:40:33 No severe PSQL-related problems occured
01.01.23 04:40:34. End of report.