Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.12.2022 07:37
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 14682040 kB
MemAvailable 30062196 kB
Buffers 0 kB
Cached 16989076 kB
SwapCached 3264 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-12 07:37:21
DTS_END                         2022-12-12 07:37:21.7460
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/58168
TRN_ID                          221119
ATT_ID                          661
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: 8660.52 22089 1039262
customer order: creation 168.97 466 20276
customer order: refuse 14.28 388 1713
order to supplier: creation 45.20 1318 5424
order to supplier: removal 3.56 142913 427
invoice (draft): creation 46.34 3337 5561
invoice (draft): removal 48.63 975 5836
invoice accept: apply 40.68 26618 4882
invoice accept: cancel 43.37 1356 5204
customer reserve: creation 3671.73 258 440607
customer reserve: removal 4287.83 94 514539
realization accept: apply 83.49 284 10019
realization accept: cancel 76.56 168 9187
payment from customer: creation 59.75 186 7170
payment from customer: removal 5.62 126 674
payment to supplier: creation 48.64 192 5837
payment to supplier: removal 3.47 112 416
service: total inventory turnovers 3.71 4221 445
service: total monetary turnovers 7.73 375 928
service: aggregate perf. data 0.93 14625 112
service: refresh index statistics 0.04 243764 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-12-2022 07:38:12 to 12-12-2022 07:38:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6494
TEST_TIME 2 5664
TEST_TIME 3 5859
TEST_TIME 4 6037
TEST_TIME 5 6430
TEST_TIME 6 6772
TEST_TIME 7 6729
TEST_TIME 8 6407
TEST_TIME 9 6453
TEST_TIME 10 6667
TEST_TIME 11 6816
TEST_TIME 12 6989
TEST_TIME 13 7050
TEST_TIME 14 7256
TEST_TIME 15 7288
TEST_TIME 16 7614
TEST_TIME 17 7841
TEST_TIME 18 7773
TEST_TIME 19 8091
TEST_TIME 20 8329
TEST_TIME 21 8324
TEST_TIME 22 8276
TEST_TIME 23 8573
TEST_TIME 24 8698
TEST_TIME 25 8884
TEST_TIME 26 9411
TEST_TIME 27 9505
TEST_TIME 28 9597
TEST_TIME 29 9889
TEST_TIME 30 10094
TEST_TIME 31 10683
TEST_TIME 32 10793
TEST_TIME 33 10725
TEST_TIME 34 10905
TEST_TIME 35 10683
TEST_TIME 36 10453
TEST_TIME 37 10280
TEST_TIME 38 10222
TEST_TIME 39 10276
TEST_TIME 40 10137
TEST_TIME 41 10041
TEST_TIME 42 10214
TEST_TIME 43 10073
TEST_TIME 44 9995
TEST_TIME 45 9909
TEST_TIME 46 9899
TEST_TIME 47 9798
TEST_TIME 48 9677
TEST_TIME 49 9792
TEST_TIME 50 9696
TEST_TIME 51 9643
TEST_TIME 52 9565
TEST_TIME 53 9427
TEST_TIME 54 9404
TEST_TIME 55 9339
TEST_TIME 56 9249
TEST_TIME 57 9370
TEST_TIME 58 9263
TEST_TIME 59 9183
TEST_TIME 60 9093
TEST_TIME 61 9113
TEST_TIME 62 9102
TEST_TIME 63 9047
TEST_TIME 64 8991
TEST_TIME 65 8969
TEST_TIME 66 8955
TEST_TIME 67 8914
TEST_TIME 68 8977
TEST_TIME 69 8984
TEST_TIME 70 9019
TEST_TIME 71 9023
TEST_TIME 72 9155
TEST_TIME 73 9201
TEST_TIME 74 9169
TEST_TIME 75 9143
TEST_TIME 76 9150
TEST_TIME 77 9071
TEST_TIME 78 9008
TEST_TIME 79 8949
TEST_TIME 80 8964
TEST_TIME 81 8926
TEST_TIME 82 8860
TEST_TIME 83 8814
TEST_TIME 84 8825
TEST_TIME 85 8823
TEST_TIME 86 8784
TEST_TIME 87 8814
TEST_TIME 88 8783
TEST_TIME 89 8769
TEST_TIME 90 8794
TEST_TIME 91 8765
TEST_TIME 92 8845
TEST_TIME 93 8842
TEST_TIME 94 8867
TEST_TIME 95 8960
TEST_TIME 96 8930
TEST_TIME 97 8911
TEST_TIME 98 8949
TEST_TIME 99 9023
TEST_TIME 100 8955
TEST_TIME 101 8893
TEST_TIME 102 8866
TEST_TIME 103 8866
TEST_TIME 104 8827
TEST_TIME 105 8780
TEST_TIME 106 8767
TEST_TIME 107 8729
TEST_TIME 108 8702
TEST_TIME 109 8675
TEST_TIME 110 8699
TEST_TIME 111 8685
TEST_TIME 112 8663
TEST_TIME 113 8679
TEST_TIME 114 8702
TEST_TIME 115 8718
TEST_TIME 116 8715
TEST_TIME 117 8685
TEST_TIME 118 8708
TEST_TIME 119 8745
TEST_TIME 120 8690

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-12-2022 07:38:16 to 12-12-2022 07:38:19.

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 1445708 1445708 0 0.00 0 16314 13 0 0
doc_list_biud 1445692 1445692 0 0.00 0 78590 40 0 0
sp_add_invoice_to_stock 5046 4882 164 3.25 63 243037 26618 0 164
sp_cancel_adding_invoice 5204 5204 0 0.00 5 121091 1357 0 0
sp_cancel_client_order 1713 1713 0 0.00 5 11185 389 0 0
sp_cancel_customer_reserve 514539 514539 0 0.00 1 78593 95 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 8729 127 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 4657 113 0 0
sp_cancel_supplier_invoice 5841 5836 5 0.08 2 18452 975 0 5
sp_cancel_supplier_order 427 427 0 0.00 6 1465418 142914 0 0
sp_cancel_write_off 9187 9187 0 0.00 2 14684 169 0 0
sp_client_order 20276 20276 0 0.00 5 14682 466 0 0
sp_customer_reserve 440607 440607 0 0.00 0 33007 259 0 0
sp_fill_shopping_cart 31254 31254 0 0.00 1 40492 96 0 0
sp_fill_shopping_cart_clo_res 440561 440561 0 0.00 0 13156 19 0 0
sp_get_clo_for_invoice 4831 4831 0 0.00 0 24529 336 0 0
sp_kill_cost_storno 15481 15481 0 0.00 0 13092 69 0 0
sp_kill_qstorno_ret_qs2qd 1044528 1044528 0 0.00 0 78586 42 0 0
sp_kill_qty_storno 551755 551755 0 0.00 0 78590 96 0 0
sp_lock_selected_doc 20581 20581 0 0.00 0 8515 8 0 0
sp_make_cost_storno 27856 27856 0 0.00 0 11924 80 0 0
sp_multiply_rows_for_pdistr 27856 27856 0 0.00 0 9434 39 0 0
sp_multiply_rows_for_qdistr 20276 20276 0 0.00 0 13529 190 0 0
sp_pay_from_customer 7170 7170 0 0.00 5 13256 187 0 0
sp_pay_to_supplier 5837 5837 0 0.00 5 15602 193 0 0
sp_payment_common 13003 13003 0 0.00 2 8827 53 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 0 11165 306 0 0
sp_qd_handle_on_invoice_upd_sts 10035 10035 0 0.00 0 20362 535 0 0
sp_qd_handle_on_reserve_upd_sts 10018 10018 0 0.00 0 18135 62 0 0
sp_reserve_write_off 10019 10019 0 0.00 7 18275 284 0 0
sp_supplier_invoice 5561 5561 0 0.00 9 143549 3337 0 0
sp_supplier_order 5424 5424 0 0.00 7 30611 1319 0 0
srv_aggregate_perf_data 112 112 0 0.00 2810 99346 14626 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 726 139079 38245 0 0
srv_find_qd_qs_mism 991690 991690 0 0.00 0 16314 10 0 0
srv_make_invnt_saldo 445 445 0 0.00 50 63552 4221 0 0
srv_make_money_saldo 928 928 0 0.00 1 15473 375 0 0
srv_recalc_idx_stat 5 5 0 0.00 69665 391447 243765 0 0
t$perf-abend:sp_add_invoice_to_stock 164 164 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4886 4886 0 0.00 0 31128 976 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 1 18787 624 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 3531 31 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 10036 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 1298 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 4727 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 827 32 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 96472 6538 0 0
t$perf-norm:sp_cancel_write_off 1034 1034 0 0.00 0 21578 40 0 0
t$perf-norm:sp_client_order 20276 20276 0 0.00 0 19455 37 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 621 14 0 0
t$perf-norm:sp_pay_from_customer 7171 7171 0 0.00 0 6334 23 0 0
t$perf-norm:sp_pay_to_supplier 5837 5837 0 0.00 0 12567 23 0 0
t$perf-norm:sp_reserve_write_off 10019 10019 0 0.00 0 19443 26 0 0
t$perf-norm:sp_supplier_invoice 5561 5561 0 0.00 0 6244 33 0 0
t$perf-norm:sp_supplier_order 5424 5424 0 0.00 0 5277 34 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 742 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 715 17 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 762 11 0 0
t$perf-norm:srv_make_money_saldo 928 928 0 0.00 0 1057 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 156 84 0 0
v_add_invoice_to_stock 9985 9657 328 3.28 0 12472 31 0 328
v_all_customers 37016 37016 0 0.00 0 67 4 0 0
v_all_suppliers 30272 30272 0 0.00 0 153 5 0 0
v_all_wares 40544 40544 0 0.00 0 61 4 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 3152 19 0 0
v_cancel_client_order 3426 3426 0 0.00 0 6808 11 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 5197 17 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 6606 20 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 688 12 0 0
v_cancel_supplier_invoice 768 758 10 1.30 0 6868 36 0 10
v_cancel_supplier_order 854 854 0 0.00 0 3456 15 0 0
v_cancel_write_off 2068 2068 0 0.00 0 3960 24 0 0
v_max_id_clo_ord 5421 5421 0 0.00 0 12612 8 0 0
v_max_id_clo_res 994 994 0 0.00 0 6744 19 0 0
v_max_id_ord_sup 5560 5560 0 0.00 0 8683 9 0 0
v_max_non_paid_invoice 1681 1681 0 0.00 0 269 8 0 0
v_max_non_paid_realizn 3883 3883 0 0.00 0 7840 11 0 0
v_min_id_clo_ord 5421 5421 0 0.00 0 11922 13 0 0
v_min_id_clo_res 994 994 0 0.00 0 8505 19 0 0
v_min_id_ord_sup 5560 5560 0 0.00 0 6571 12 0 0
v_min_non_paid_invoice 5836 5836 0 0.00 0 8724 20 0 0
v_min_non_paid_realizn 7167 7167 0 0.00 0 8800 24 0 0
v_our_firm 10096 10096 0 0.00 0 56 5 0 0
v_reserve_write_off 20032 20032 0 0.00 0 7928 12 0 0
x_lock_depdocs_on_canc_invoice 10661 10661 0 0.00 0 18091 141 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 7298 325 0 0
x_make_qstorno_1000_1200 5424 5424 0 0.00 2 30538 1131 0 0
x_make_qstorno_1200_2000 5561 5561 0 0.00 4 136423 3067 0 0
x_make_qstorno_2100_3300 440607 440607 0 0.00 0 30910 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-12-2022 07:38:20 to 12-12-2022 07:38:24.

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
05:07:25 2 8192000000 8799280608 8810586112 5144976 0.062 2 1 2 0 5244400 63648 35776
05:08:26 36078 8192000000 9735063568 10036133888 824546208 9.144 100 90 180 0 853540912 7360368 21634336
05:10:04 17934 8192000000 9765238368 10071445504 843372096 9.334 100 93 184 0 878247712 12881712 21993904
05:11:30 14180 8192000000 9799715440 10115592192 863808288 9.538 100 86 172 0 905195744 21441904 19945552
05:12:52 8781 8192000000 9855941024 10183766016 904655216 9.944 100 82 162 0 964086368 41568224 17862928
05:14:04 3696 8192000000 9902762992 10241499136 942352384 10.316 100 83 158 1 1021810096 62596352 16861360
05:15:12 1938 8192000000 9948773680 10299727872 991313424 10.794 100 88 165 0 1065424624 57193856 16917344
05:16:15 1706 8192000000 9991149104 10355683328 1017692832 11.050 100 90 176 0 1114729984 78506208 18530944
05:17:19 1512 8192000000 10017614848 10387980288 1042766064 11.291 100 90 175 1 1138037280 77532992 17738224
05:18:22 1041 8192000000 10028408848 10400866304 1056691696 11.425 100 90 179 0 1154346864 79305600 18349568
05:19:25 997 8192000000 10029780720 10405232640 1063527824 11.490 100 94 185 1 1151566336 69086720 18951792
05:20:27 1121 8192000000 10038877536 10417852416 1070287616 11.555 100 99 189 2 1165704560 76434176 18982768
05:21:29 934 8192000000 10024500864 10404409344 1071425744 11.566 100 91 167 1 1145389888 55534496 18429648
05:22:31 1150 8192000000 10057175856 10441826304 1076036864 11.610 100 90 173 1 1178397008 83551248 18808896
05:23:33 1042 8192000000 10039850816 10426540032 1086527600 11.710 100 86 166 2 1152896496 49728208 16640688
05:24:35 1072 8192000000 10031881328 10417025024 1091084400 11.753 100 97 183 4 1152276064 43600752 17590912
05:25:38 1124 8192000000 10050335312 10440282112 1092002672 11.762 100 88 170 2 1173074512 63122048 17949792
05:26:39 1206 8192000000 10079065264 10464059392 1098101616 11.820 100 99 195 0 1198096656 81044592 18950448
05:27:42 1296 8192000000 10095366832 10485022720 1096542144 11.805 100 85 170 2 1215153200 101476160 17134896
05:28:44 1068 8192000000 10042494064 10428530688 1092034912 11.762 100 97 182 0 1162576128 52559360 17981856
05:29:46 987 8192000000 10070367776 10458824704 1083483360 11.681 100 88 170 1 1188813616 87736912 17593344
05:30:48 1024 8192000000 10039119440 10421014528 1073528560 11.586 100 85 163 0 1164139072 73509792 17100720
05:31:50 1668 8192000000 10035252240 10420518912 1070680992 11.559 100 84 158 0 1155757056 68114112 16961952
05:32:52 1141 8192000000 10011537008 10386837504 1039769232 11.262 100 94 176 2 1124747120 66907360 18070528
05:33:55 4859 8192000000 9997689584 10383294464 1028445920 11.153 98 65 128 1 1113685680 72249040 12990720
05:35:00 1258 8192000000 9976356448 10354167808 1030334240 11.172 100 82 155 2 1101322352 54560304 16427808
05:36:02 1038 8192000000 9963966576 10334277632 1021771216 11.089 98 93 176 0 1085072368 45361456 17939696
05:37:04 1185 8192000000 10011408304 10388291584 1051239408 11.373 100 97 182 1 1133975488 63685936 19050144
05:38:13 7126 8192000000 10024412256 10411790336 1049211792 11.353 99 62 123 1 1136064336 74993632 11858912
05:39:26 9825 8192000000 10048254928 10434064384 1079899456 11.647 100 70 138 1 1166407488 72474096 14033936
05:40:41 3470 8192000000 10077844720 10465787904 1099155600 11.830 100 71 135 0 1197866592 84202400 14508592
05:41:48 6703 8192000000 10082131584 10474520576 1111410832 11.946 100 65 123 0 1195699024 71290144 12998048
05:42:57 2629 8192000000 10124206320 10523414528 1123661936 12.062 100 76 145 2 1240266176 100896304 15707936
05:44:02 3121 8192000000 10139754864 10545950720 1129676208 12.118 100 61 113 1 1257843312 115676656 12490448
05:45:12 4321 8192000000 10163857520 10560892928 1139633424 12.212 100 76 144 0 1273978592 118728976 15616192
05:46:19 2689 8192000000 10205721200 10609758208 1149432592 12.304 100 75 144 3 1316004000 150488784 16082624
05:47:28 1468 8192000000 10218875200 10627076096 1158771776 12.392 100 94 177 1 1328592528 150905392 18915360
05:48:33 2842 8192000000 10247808960 10639994880 1158252928 12.387 100 89 168 2 1354535760 176732576 19550256
05:49:41 1009 8192000000 10291123088 10687762432 1175580992 12.549 100 95 185 1 1402000336 205081712 21337632
05:50:46 883 8192000000 10296894896 10713120768 1179746544 12.588 100 92 173 2 1408989952 209166656 20076752
05:51:50 3990 8192000000 10319595200 10732965888 1168102640 12.479 100 71 137 0 1429358944 245375456 15880848
05:52:56 1096 8192000000 10324259696 10762924032 1175460976 12.548 100 89 164 2 1433581248 238943200 19177072
05:54:00 758 8192000000 10365502000 10791002112 1185879376 12.645 100 97 187 1 1473601664 266530480 21191808
05:55:05 910 8192000000 10384519136 10791428096 1190478176 12.688 100 97 187 1 1491350112 277149568 23722368
05:56:07 807 8192000000 10406456256 10826956800 1183071536 12.619 100 95 189 0 1511635728 305407216 23156976
05:57:10 2095 8192000000 10397753056 10824019968 1177233696 12.564 100 66 131 0 1505176752 312072896 15870160
05:58:16 1979 8192000000 10369036992 10802221056 1158003888 12.385 100 80 146 0 1479179808 303769568 17406352
05:59:20 3076 8192000000 10411430304 10837200896 1152126512 12.329 100 65 120 2 1518990992 351804848 15059632
06:00:26 4319 8192000000 10397875120 10856165376 1152478576 12.333 100 67 133 0 1504419584 335469200 16471808
06:01:36 716 8192000000 10428750480 10839724032 1152916560 12.337 100 91 177 1 1532321696 358466880 20938256
06:02:40 762 8192000000 10365764400 10832162816 1152144560 12.330 100 96 185 2 1470026496 295691376 22190560
06:03:42 11616 8192000000 10357996656 10831269888 1161744112 12.420 100 42 84 0 1459701440 287504848 10452480
06:04:54 43308 8192000000 10325988720 10825289728 1149093232 12.301 99 19 38 0 1418547984 264419760 5034992
06:06:40 54104 8192000000 10271853920 10758627328 1101444736 11.851 96 20 39 0 1366794832 260437088 4913008
06:08:35 46760 8192000000 10072819552 10519150592 1052186080 11.382 93 16 31 0 1138231424 82746688 3298656
06:10:47 1478 8192000000 10144005824 10586882048 1144619696 12.259 100 67 133 1 1246036928 87191024 14226208
06:11:50 106155 8192000000 10162289760 10592641024 1134178656 12.161 99 50 94 3 1251313120 107459008 9675456
06:14:39 43964 8192000000 10227553072 10683150336 1143664432 12.250 100 53 104 2 1312018496 157165936 11188128
06:17:42 52847 8192000000 10128870320 10587951104 1123091536 12.056 99 32 59 0 1262637536 132488720 7057280
06:19:38 59934 8192000000 10104852704 10562686976 1137619824 12.193 100 13 26 0 1215881392 75356000 2905568
06:21:42 74941 8192000000 10093830752 10544553984 1117372720 12.002 99 11 18 1 1196846064 77195024 2278320
06:23:59 78896 8192000000 10159585776 10609471488 1134889664 12.167 99 39 74 0 1232690080 89361360 8439056
06:28:12 94556 8192000000 10066359344 10518028288 1114300512 11.973 98 20 36 1 1149227056 31201824 3724720
06:31:04 14993 8192000000 10113621872 10541387776 1137743792 12.194 100 62 123 0 1207350528 56976320 12630416
06:32:21 63500 8192000000 10109087232 10553090048 1111866016 11.950 98 20 40 0 1185439136 69039392 4533728
06:34:41 55144 8192000000 10088131488 10592940032 1108859072 11.922 98 11 22 0 1166091088 54579536 2652480
06:36:36 73600 8192000000 10078995616 10562002944 1096149008 11.801 97 10 20 0 1173526368 75178064 2199296
06:38:53 101286 8192000000 10057913024 10545344512 1120867520 12.035 99 17 35 0 1197427520 73261584 3298416
06:41:35 44598 8192000000 10072384320 10528677888 1132483120 12.145 100 15 30 0 1165736448 30502800 2750528
06:43:53 28010 8192000000 10138510096 10586984448 1108738528 11.920 96 29 58 0 1204051296 88844400 6468368
06:45:32 45389 8192000000 10154169776 10594611200 1105375296 11.889 97 29 56 0 1229037456 117255488 6406672
06:47:20 139077 8192000000 10167148416 10617094144 1072680576 11.578 94 18 33 0 1207227456 130917936 3628944
06:50:39 12232 8192000000 10070338752 10496204800 1129793504 12.119 100 46 89 0 1154899264 14670816 10434944
06:51:54 25484 8192000000 10101714784 10523168768 1100189984 11.839 97 46 90 1 1151111808 42136560 8785264
06:53:25 51965 8192000000 10076250256 10528870400 1094258160 11.783 97 46 90 2 1165423088 61328624 9836304
06:56:03 28976 8192000000 10097877280 10534592512 1133947648 12.159 100 54 106 1 1207283200 62448704 10886848
06:57:33 50063 8192000000 10045070560 10540515328 1115975248 11.989 99 5 10 0 1163728208 46744976 1007984
07:00:34 60027 8192000000 10111839792 10583867392 1134627280 12.165 100 14 26 0 1204348480 67033152 2688048
07:02:47 75268 8192000000 10067030704 10515386368 1112230384 11.954 98 16 31 0 1162440304 47333024 2876896
07:05:02 56650 8192000000 10072037792 10515759104 1094781536 11.788 96 21 41 0 1137194128 38546496 3866096
07:06:59 105452 8192000000 10122564528 10564501504 1091930912 11.761 96 16 33 0 1161988592 66384112 3673568
07:09:45 64830 8192000000 10089454160 10523205632 1117891840 12.007 99 20 38 0 1158109728 37111216 3106672
07:11:51 37873 8192000000 10159746640 10591588352 1112039568 11.952 97 37 74 0 1223914288 104275280 7599440
07:13:30 15668 8192000000 10070530432 10499866624 1114999664 11.980 98 34 68 0 1173654480 52311824 6342992
07:14:48 19616 8192000000 10157662784 10585780224 1131370560 12.134 99 58 116 0 1228635008 85956080 11308368
07:16:08 85062 8192000000 10073403616 10576904192 1127415696 12.097 99 12 24 0 1176656128 46897360 2343072
07:19:11 61775 8192000000 10118661216 10570305536 1136974736 12.187 99 35 64 0 1213889984 69976144 6939104
07:21:59 73143 8192000000 10081547216 10560942080 1140993248 12.225 99 17 34 0 1193334384 48407552 3933584
07:24:14 69576 8192000000 10098700848 10564931584 1125197776 12.076 98 14 28 0 1183903184 55742448 2962960
07:26:54 86601 8192000000 10091609248 10546954240 1109174624 11.925 97 15 30 0 1156536688 44606960 2755104
07:29:21 65204 8192000000 10109603664 10567045120 1128399168 12.106 99 23 44 0 1194774368 60635456 5739744
07:31:26 78840 8192000000 10067660656 10550796288 1103160560 11.868 97 20 40 0 1168700288 61293056 4246672
07:33:45 33811 8192000000 10079681840 10526789632 1088092304 11.725 96 30 60 1 1150466864 56524336 5850224
07:35:19 65003 8192000000 10095672832 10552750080 1118239616 12.010 100 32 64 1 1200904224 76038208 6626400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-12-2022 07:38:26 to 12-12-2022 07:38:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 164 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-12-2022 07:38:43 to 12-12-2022 07:38:45.

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          220722
MON$OLDEST_ACTIVE               221632
MON$OLDEST_SNAPSHOT             221632
MON$NEXT_TRANSACTION            221632
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                       2074208
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             781
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 ---
:118: 
:119: mscs-test-161.budzdorov.ru    Mon Dec 12 07:37:27 2022
:120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:121: 
:122: 
:123: mscs-test-161.budzdorov.ru    Mon Dec 12 07:37:32 2022
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:125: 
:126: 
:127: mscs-test-161.budzdorov.ru    Mon Dec 12 07:37:36 2022
:128:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:129: 
--- end of diff output ---

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

12.12.22 07:38:46. 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-12 07:38:49 No severe PSQL-related problems occured
12.12.22 07:38:50. End of report.