Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.06.2022 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 16033536 kB
MemAvailable 31294900 kB
Buffers 20 kB
Cached 15649940 kB
SwapCached 2452 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-11 04:37:19
DTS_END                         2022-06-11 04:37:19.3360
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/41422
TRN_ID                          212158
ATT_ID                          576
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: 8791.35 25698 1054962
customer order: creation 165.18 511 19821
customer order: refuse 14.16 498 1699
order to supplier: creation 44.40 1427 5328
order to supplier: removal 3.18 207615 382
invoice (draft): creation 44.04 3220 5285
invoice (draft): removal 49.89 1072 5987
invoice accept: apply 39.88 27870 4786
invoice accept: cancel 45.26 1378 5431
customer reserve: creation 3723.40 264 446808
customer reserve: removal 4370.97 120 524516
realization accept: apply 81.23 289 9748
realization accept: cancel 85.44 200 10253
payment from customer: creation 56.86 190 6823
payment from customer: removal 5.71 98 685
payment to supplier: creation 45.89 190 5507
payment to supplier: removal 3.11 127 373
service: total inventory turnovers 4.34 3772 521
service: total monetary turnovers 7.33 409 880
service: aggregate perf. data 1.03 14567 124
service: refresh index statistics 0.04 250135 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-06-2022 04:39:59 to 11-06-2022 04:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6520
TEST_TIME 2 5214
TEST_TIME 3 4425
TEST_TIME 4 4781
TEST_TIME 5 4502
TEST_TIME 6 4321
TEST_TIME 7 4445
TEST_TIME 8 4625
TEST_TIME 9 4957
TEST_TIME 10 5410
TEST_TIME 11 5698
TEST_TIME 12 6382
TEST_TIME 13 6583
TEST_TIME 14 6573
TEST_TIME 15 6662
TEST_TIME 16 6598
TEST_TIME 17 6825
TEST_TIME 18 7028
TEST_TIME 19 7559
TEST_TIME 20 7543
TEST_TIME 21 7535
TEST_TIME 22 7645
TEST_TIME 23 7604
TEST_TIME 24 7609
TEST_TIME 25 7876
TEST_TIME 26 7882
TEST_TIME 27 8235
TEST_TIME 28 8312
TEST_TIME 29 8438
TEST_TIME 30 8431
TEST_TIME 31 8806
TEST_TIME 32 9054
TEST_TIME 33 9538
TEST_TIME 34 9667
TEST_TIME 35 9796
TEST_TIME 36 10417
TEST_TIME 37 10857
TEST_TIME 38 10680
TEST_TIME 39 10623
TEST_TIME 40 10485
TEST_TIME 41 10241
TEST_TIME 42 10108
TEST_TIME 43 10061
TEST_TIME 44 10021
TEST_TIME 45 9935
TEST_TIME 46 9869
TEST_TIME 47 9726
TEST_TIME 48 9586
TEST_TIME 49 9470
TEST_TIME 50 9425
TEST_TIME 51 9350
TEST_TIME 52 9391
TEST_TIME 53 9230
TEST_TIME 54 9243
TEST_TIME 55 9191
TEST_TIME 56 9120
TEST_TIME 57 9062
TEST_TIME 58 9098
TEST_TIME 59 9167
TEST_TIME 60 9169
TEST_TIME 61 9095
TEST_TIME 62 9140
TEST_TIME 63 9166
TEST_TIME 64 9161
TEST_TIME 65 9156
TEST_TIME 66 9167
TEST_TIME 67 9245
TEST_TIME 68 9255
TEST_TIME 69 9305
TEST_TIME 70 9232
TEST_TIME 71 9224
TEST_TIME 72 9127
TEST_TIME 73 9076
TEST_TIME 74 9044
TEST_TIME 75 9093
TEST_TIME 76 9047
TEST_TIME 77 8995
TEST_TIME 78 8962
TEST_TIME 79 8939
TEST_TIME 80 8893
TEST_TIME 81 8906
TEST_TIME 82 8907
TEST_TIME 83 8874
TEST_TIME 84 8858
TEST_TIME 85 8879
TEST_TIME 86 9049
TEST_TIME 87 8991
TEST_TIME 88 9023
TEST_TIME 89 9104
TEST_TIME 90 9071
TEST_TIME 91 9025
TEST_TIME 92 8957
TEST_TIME 93 8967
TEST_TIME 94 8906
TEST_TIME 95 8903
TEST_TIME 96 8871
TEST_TIME 97 8845
TEST_TIME 98 8830
TEST_TIME 99 8798
TEST_TIME 100 8794
TEST_TIME 101 8762
TEST_TIME 102 8781
TEST_TIME 103 8775
TEST_TIME 104 8767
TEST_TIME 105 8735
TEST_TIME 106 8723
TEST_TIME 107 8787
TEST_TIME 108 8878
TEST_TIME 109 9025
TEST_TIME 110 9037
TEST_TIME 111 9035
TEST_TIME 112 8974
TEST_TIME 113 8925
TEST_TIME 114 8987
TEST_TIME 115 8941
TEST_TIME 116 8934
TEST_TIME 117 8905
TEST_TIME 118 8876
TEST_TIME 119 8862
TEST_TIME 120 8812

Performance per minute, chart


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

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 1466288 1466288 0 0.00 0 18126 15 0 0
doc_list_biud 1466285 1466285 0 0.00 0 43559 49 0 0
sp_add_invoice_to_stock 4888 4786 102 2.08 39 209825 27871 0 102
sp_cancel_adding_invoice 5431 5431 0 0.00 7 85912 1379 0 0
sp_cancel_client_order 1699 1699 0 0.00 6 29067 499 0 0
sp_cancel_customer_reserve 524516 524516 0 0.00 1 43563 121 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 4 2163 98 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 4 2202 127 0 0
sp_cancel_supplier_invoice 5998 5987 11 0.18 3 35690 1072 0 11
sp_cancel_supplier_order 382 382 0 0.00 10 1535716 207616 0 0
sp_cancel_write_off 10253 10253 0 0.00 2 37331 201 0 0
sp_client_order 19821 19821 0 0.00 5 34763 512 0 0
sp_customer_reserve 446808 446808 0 0.00 1 157637 265 0 0
sp_fill_shopping_cart 30424 30424 0 0.00 1 18771 84 0 0
sp_fill_shopping_cart_clo_res 446774 446774 0 0.00 0 15900 20 0 0
sp_get_clo_for_invoice 4749 4749 0 0.00 0 31931 280 0 0
sp_kill_cost_storno 16742 16742 0 0.00 0 15130 80 0 0
sp_kill_qstorno_ret_qs2qd 1065654 1065654 0 0.00 0 42018 52 0 0
sp_kill_qty_storno 562765 562765 0 0.00 0 43557 118 0 0
sp_lock_selected_doc 20126 20126 0 0.00 0 6016 6 0 0
sp_make_cost_storno 26827 26827 0 0.00 0 13762 83 0 0
sp_multiply_rows_for_pdistr 26827 26827 0 0.00 0 8801 39 0 0
sp_multiply_rows_for_qdistr 19821 19821 0 0.00 0 16060 190 0 0
sp_pay_from_customer 6823 6823 0 0.00 5 16283 191 0 0
sp_pay_to_supplier 5507 5507 0 0.00 7 14341 190 0 0
sp_payment_common 12330 12330 0 0.00 2 16082 49 0 0
sp_qd_handle_on_cancel_clo 1699 1699 0 0.00 1 29057 418 0 0
sp_qd_handle_on_invoice_upd_sts 10180 10180 0 0.00 0 41653 566 0 0
sp_qd_handle_on_reserve_upd_sts 9748 9748 0 0.00 0 34729 74 0 0
sp_reserve_write_off 9748 9748 0 0.00 9 34824 289 0 0
sp_supplier_invoice 5285 5285 0 0.00 9 108300 3221 0 0
sp_supplier_order 5328 5328 0 0.00 8 76351 1428 0 0
srv_aggregate_perf_data 124 124 0 0.00 2226 207075 14568 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 749 162636 32765 0 0
srv_find_qd_qs_mism 1007838 1007838 0 0.00 0 15486 12 0 0
srv_make_invnt_saldo 521 521 0 0.00 64 95510 3772 0 0
srv_make_money_saldo 880 880 0 0.00 2 25245 410 0 0
srv_recalc_idx_stat 5 5 0 0.00 70396 437179 250135 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4793 4793 0 0.00 0 39445 1015 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 7787 553 0 0
t$perf-norm:sp_cancel_client_order 1699 1699 0 0.00 0 2777 27 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 11318 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 4773 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 525 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1753 43 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 113926 8667 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 4626 31 0 0
t$perf-norm:sp_client_order 19822 19822 0 0.00 0 17637 40 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 3513 24 0 0
t$perf-norm:sp_pay_from_customer 6823 6823 0 0.00 0 4621 20 0 0
t$perf-norm:sp_pay_to_supplier 5507 5507 0 0.00 0 10406 21 0 0
t$perf-norm:sp_reserve_write_off 9748 9748 0 0.00 0 9988 26 0 0
t$perf-norm:sp_supplier_invoice 5286 5286 0 0.00 0 12972 41 0 0
t$perf-norm:sp_supplier_order 5328 5328 0 0.00 0 6637 36 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 453 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 489 13 0 0
t$perf-norm:srv_make_invnt_saldo 521 521 0 0.00 0 1382 12 0 0
t$perf-norm:srv_make_money_saldo 880 880 0 0.00 0 1035 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 169 64 0 0
v_add_invoice_to_stock 9698 9494 204 2.10 0 11655 23 0 204
v_all_customers 36316 36316 0 0.00 0 160 4 0 0
v_all_suppliers 29400 29400 0 0.00 0 141 4 0 0
v_all_wares 39642 39642 0 0.00 0 140 4 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 3975 25 0 0
v_cancel_client_order 3398 3398 0 0.00 0 4704 14 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 1635 11 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 257 9 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 518 10 0 0
v_cancel_supplier_invoice 812 790 22 2.70 0 1215 23 0 22
v_cancel_supplier_order 764 764 0 0.00 0 479 12 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2606 22 0 0
v_max_id_clo_ord 5325 5325 0 0.00 0 2981 5 0 0
v_max_id_clo_res 959 959 0 0.00 0 6690 23 0 0
v_max_id_ord_sup 5277 5277 0 0.00 0 6487 6 0 0
v_max_non_paid_invoice 1408 1408 0 0.00 0 254 7 0 0
v_max_non_paid_realizn 3516 3516 0 0.00 0 3462 9 0 0
v_min_id_clo_ord 5325 5325 0 0.00 0 2302 8 0 0
v_min_id_clo_res 959 959 0 0.00 0 1708 9 0 0
v_min_id_ord_sup 5277 5277 0 0.00 0 3470 7 0 0
v_min_non_paid_invoice 5507 5507 0 0.00 0 8606 16 0 0
v_min_non_paid_realizn 6823 6823 0 0.00 0 15376 21 0 0
v_our_firm 9938 9938 0 0.00 0 63 5 0 0
v_reserve_write_off 19494 19494 0 0.00 0 3179 9 0 0
x_lock_depdocs_on_canc_invoice 11023 11023 0 0.00 0 29361 130 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 12863 424 0 0
x_make_qstorno_1000_1200 5328 5328 0 0.00 2 76330 1252 0 0
x_make_qstorno_1200_2000 5285 5285 0 0.00 4 108289 2994 0 0
x_make_qstorno_2100_3300 446808 446808 0 0.00 0 157635 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-06-2022 04:40:07 to 11-06-2022 04:40:11.

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:23 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:24 32303 8192000000 9718977728 10019450880 796731088 8.863 100 84 168 0 829530928 12128352 20671488
02:10:04 20280 8192000000 9759398896 10067963904 838925440 9.289 100 91 182 0 873806480 13186496 21694544
02:11:29 16652 8192000000 9800041440 10118168576 864078224 9.541 100 83 166 0 906222240 23060112 19083904
02:12:51 11431 8192000000 9855541968 10188890112 906456848 9.962 100 83 164 0 974834080 50319552 18057680
02:14:08 9145 8192000000 9913140576 10257911808 946363088 10.355 100 84 164 0 1026535808 62780624 17392096
02:15:19 2623 8192000000 9948235472 10300170240 987120240 10.753 100 95 177 3 1066388896 61447168 17821488
02:16:24 1680 8192000000 9980011424 10339000320 1014197632 11.016 100 92 172 1 1097246048 65628032 17420384
02:17:28 1287 8192000000 10022338976 10387316736 1031513776 11.183 100 93 180 0 1137403616 88383280 17506560
02:18:31 1048 8192000000 10016668656 10389827584 1047619680 11.338 100 93 180 2 1138612768 72750016 18243072
02:19:34 985 8192000000 10020868496 10399907840 1054822480 11.407 100 90 175 1 1144519024 71821504 17875040
02:20:37 841 8192000000 10038300704 10414694400 1062968544 11.485 100 93 180 1 1155707936 75439472 17299920
02:21:39 1226 8192000000 10043705904 10424332288 1068396384 11.537 100 96 188 0 1160932064 74789600 17746080
02:22:42 1190 8192000000 10068324224 10454913024 1079302688 11.641 100 97 183 1 1190020480 91915280 18802512
02:23:44 1248 8192000000 10056469872 10440073216 1080656656 11.654 100 97 174 1 1176921888 78556736 17708496
02:24:47 1439 8192000000 10047083152 10435407872 1085688336 11.702 100 95 185 0 1168352656 62402576 20261744
02:25:49 1300 8192000000 10067851056 10451869696 1085035760 11.695 100 94 181 0 1185775088 82783216 17956112
02:26:52 1771 8192000000 10080194272 10475532288 1090253136 11.745 100 87 171 1 1199448048 91948512 17246400
02:27:55 961 8192000000 10070461712 10462859264 1095421632 11.794 100 85 162 1 1192118144 79522640 17173872
02:28:58 3458 8192000000 10019081408 10411839488 1076330640 11.612 99 67 128 0 1141674832 51918000 13426192
02:30:02 5080 8192000000 10029055632 10419752960 1083456560 11.680 100 79 152 1 1151896880 54169056 14271264
02:31:09 1228 8192000000 10063412192 10449276928 1075685648 11.606 100 96 189 0 1184210464 90265840 18258976
02:32:11 1289 8192000000 10022895632 10406141952 1061803760 11.474 100 82 155 1 1143154704 66474016 14876928
02:33:15 1092 8192000000 10023881984 10401619968 1060846448 11.465 100 91 178 3 1144362496 64635184 18880864
02:34:17 1805 8192000000 10028304304 10410831872 1052498448 11.385 100 74 143 2 1144801424 76737248 15565728
02:35:20 2123 8192000000 9988190224 10363740160 1047578928 11.337 100 82 154 3 1117243856 53607968 16056960
02:36:23 1495 8192000000 9985948672 10357153792 1034257888 11.209 100 93 179 1 1104603600 52137184 18208528
02:37:28 13263 8192000000 9990433728 10375053312 1027592848 11.145 98 58 108 0 1107252848 69108176 10551824
02:38:58 12651 8192000000 10010986704 10392522752 1072719008 11.578 100 77 151 2 1132858240 43398448 16740784
02:40:18 3961 8192000000 10048129664 10425991168 1073134320 11.582 99 79 155 0 1165199920 75963744 16101856
02:41:28 5280 8192000000 10088763152 10475933696 1091465536 11.757 100 70 129 2 1203774208 99197296 13111376
02:42:40 3463 8192000000 10121771904 10518867968 1119626896 12.023 100 67 128 1 1241701440 108767264 13307280
02:44:04 907 8192000000 10193786704 10580631552 1124759504 12.072 100 85 162 0 1311010176 169355600 16895072
02:45:13 11922 8192000000 10215143200 10620096512 1134348896 12.162 100 59 111 2 1322695936 176185360 12161680
02:46:32 3998 8192000000 10247497712 10647506944 1152652320 12.334 100 72 142 0 1360119040 192127104 15339616
02:47:46 1258 8192000000 10281649824 10680696832 1163930112 12.440 100 90 176 0 1395306512 212549360 18827040
02:48:49 956 8192000000 10319569344 10733826048 1163177744 12.433 100 96 189 4 1431121584 247446784 20497056
02:49:54 2110 8192000000 10293898656 10723942400 1179269904 12.583 100 86 169 1 1408415360 210285728 18859728
02:50:58 1046 8192000000 10343847664 10747682816 1175326640 12.547 100 98 193 1 1454776112 257370288 22079184
02:52:03 1660 8192000000 10382864576 10786897920 1185674320 12.643 100 95 187 0 1496458704 288216208 22568176
02:53:08 891 8192000000 10400510704 10822025216 1191799168 12.700 100 95 186 1 1511388128 298203408 21385552
02:54:14 1762 8192000000 10438376032 10877542400 1200503216 12.781 100 97 185 2 1545288336 323519312 21265808
02:55:21 1091 8192000000 10463402128 10892529664 1204528240 12.818 100 96 186 1 1572745792 345989104 22228448
02:56:24 742 8192000000 10472488640 10924736512 1206087008 12.833 100 93 187 0 1579747136 350710192 22949936
02:57:28 1568 8192000000 10457506096 10891132928 1200824432 12.784 100 91 179 0 1565047888 343150032 21073424
02:58:35 1015 8192000000 10473254832 10885308416 1194186800 12.722 100 81 160 0 1579829040 366033568 19608672
02:59:43 918 8192000000 10527857664 10945888256 1209350592 12.863 100 94 186 1 1632048144 398676528 24021024
03:00:48 777 8192000000 10540713216 10971406336 1196984816 12.748 100 87 174 3 1647074144 428436160 21653168
03:01:52 1070 8192000000 10580387440 11029499904 1214345120 12.909 100 98 191 2 1682748176 444963904 23439152
03:02:57 1778 8192000000 10577637456 11022929920 1210254352 12.871 100 90 180 0 1681117264 449506016 21356896
03:04:01 2014 8192000000 10590696400 11068932096 1214855360 12.914 100 76 148 0 1695970592 461895104 19220128
03:05:04 7584 8192000000 10578754448 11052830720 1208603792 12.856 100 45 90 1 1700371024 479669680 12097552
03:06:16 11091 8192000000 10597429968 11074502656 1187809536 12.663 99 56 112 0 1698339904 495952416 14577952
03:07:35 12002 8192000000 10611096720 11099930624 1174053040 12.535 99 51 102 0 1701857424 514688032 13116352
03:09:13 16745 8192000000 10485950720 10933411840 1166230128 12.462 99 42 84 0 1602438688 425433952 10774608
03:10:51 30941 8192000000 10376670624 10871607296 1158766176 12.392 99 29 58 1 1504856512 339081104 7009232
03:12:22 32626 8192000000 10343277648 10865487872 1155326432 12.359 100 22 44 0 1478334032 317229216 5778384
03:14:02 53858 8192000000 10098991616 10559340544 1121002320 12.036 97 16 30 3 1175639456 51075600 3561536
03:15:57 70399 8192000000 10134843472 10620592128 1147213968 12.283 99 61 118 2 1225418560 65001328 13203264
03:18:58 30678 8192000000 10138157664 10583068672 1151185104 12.321 99 32 64 1 1226148976 68474496 6489376
03:20:30 70946 8192000000 10090207424 10542776320 1128937024 12.111 98 34 64 1 1163038352 26919968 7181360
03:22:45 67431 8192000000 10086585744 10545713152 1125523776 12.079 98 20 41 0 1172313776 42404816 4385184
03:24:53 59273 8192000000 10078285008 10526224384 1107828400 11.912 97 36 70 2 1154320480 38617632 7874448
03:27:02 64244 8192000000 10115250320 10584281088 1127113600 12.094 99 24 48 1 1196260256 64074640 5072016
03:29:06 162636 8192000000 10065373008 10545414144 1094706176 11.787 96 14 28 1 1137391200 39385264 3299760
03:32:49 15601 8192000000 10043823392 10497028096 1122836464 12.054 100 39 63 4 1160850944 28233248 9781232
03:34:05 23899 8192000000 10084428192 10527223808 1129955232 12.121 99 51 101 1 1165842976 25387392 10500352
03:35:34 71237 8192000000 10081675232 10588123136 1120159392 12.028 99 14 28 0 1209563408 86689456 2714560
03:37:46 20365 8192000000 10068138656 10526547968 1140418752 12.219 100 26 51 3 1168401600 21788672 6194176
03:39:12 32711 8192000000 10123748160 10585821184 1139121200 12.207 100 43 86 0 1219119056 71371968 8625888
03:40:54 12806 8192000000 10082338208 10521612288 1132436128 12.144 100 46 82 0 1186055152 46153424 7465600
03:42:10 6019 8192000000 10103009120 10532044800 1125923136 12.083 99 57 109 3 1191227424 54070864 11233424
03:43:17 9951 8192000000 10150478832 10576601088 1130377472 12.125 99 60 120 0 1225732432 83580640 11774320
03:44:27 33481 8192000000 10129804448 10612334592 1131585968 12.136 99 29 58 0 1225888240 88253856 6048416
03:46:05 142062 8192000000 10082917328 10566131712 1119798032 12.025 98 14 29 0 1172214576 49494144 2922400
03:49:30 40334 8192000000 10100289376 10536521728 1134911616 12.168 99 54 101 1 1194330448 47918288 11500544
03:51:12 101760 8192000000 10057996256 10581254144 1114771680 11.978 97 7 14 0 1173264848 57033536 1459632
03:54:25 87922 8192000000 10068236080 10559643648 1101055360 11.848 98 17 34 0 1154470464 48959312 4455792
03:56:53 43749 8192000000 10087748800 10531733504 1109294832 11.926 98 23 44 0 1168535008 53906416 5333760
03:58:39 17448 8192000000 10124538800 10559148032 1135978960 12.178 100 40 79 0 1214659488 70026096 8654432
04:00:00 35894 8192000000 10131546768 10571665408 1105977984 11.894 98 25 50 0 1204148304 92708384 5461936
04:01:41 40989 8192000000 10174870944 10614427648 1119437568 12.022 99 40 80 1 1269069168 141141488 8490112
04:03:26 60300 8192000000 10073537760 10506817536 1129843968 12.120 99 31 58 5 1185992240 50206256 5942016
04:05:30 50195 8192000000 10114270000 10595348480 1095604112 11.796 96 30 60 0 1192486192 90678640 6203440
04:07:27 42032 8192000000 10083125248 10505891840 1137779808 12.195 100 45 85 1 1179334464 33269712 8284944
04:09:11 71330 8192000000 10073255744 10553143296 1142658400 12.241 100 31 60 1 1221096048 71702912 6734736
04:11:27 24843 8192000000 10092215392 10532315136 1139783936 12.213 100 30 56 0 1194429200 48919664 5725600
04:12:52 70035 8192000000 10077779680 10539601920 1126049728 12.084 98 19 38 1 1185225248 54892144 4283376
04:15:03 41648 8192000000 10080099776 10551623680 1119617184 12.023 98 9 17 1 1158033232 36461056 1954992
04:16:50 25159 8192000000 10122459984 10570350592 1134504608 12.164 99 31 62 2 1206661744 64881440 7275696
04:18:16 29644 8192000000 10166723520 10608955392 1139929728 12.215 98 36 71 2 1244655648 96716320 8009600
04:20:05 32369 8192000000 10207881776 10645020672 1151360208 12.322 99 42 84 1 1281927520 121293808 9273504
04:21:44 34258 8192000000 10247315984 10699874304 1143692368 12.250 98 46 92 1 1307693904 153469536 10532000
04:23:21 20169 8192000000 10300047040 10753921024 1179481840 12.585 100 52 104 1 1390560576 198648208 12430528
04:24:55 45844 8192000000 10217660976 10768957440 1166154608 12.461 100 19 38 0 1362696112 191698352 4843152
04:26:45 60670 8192000000 10089539488 10535755776 1136960128 12.187 99 18 36 1 1198233712 57582800 3690784
04:28:48 71842 8192000000 10163719568 10606125056 1143437824 12.248 99 27 52 0 1218517712 69852960 5226928
04:31:48 81631 8192000000 10092189088 10591793152 1129115712 12.113 98 23 46 0 1184500192 50930496 4453984
04:34:17 64645 8192000000 10056712784 10527674368 1130081280 12.122 99 9 19 0 1171808416 39929936 1797200
04:36:25 62229 8192000000 9601324848 9988870144 695508528 7.825 61 9 18 2 733243696 35994480 1740688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 11-06-2022 04:40:13 to 11-06-2022 04:40:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-06-2022 04:40:30 to 11-06-2022 04:40:32.

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          212778
MON$OLDEST_ACTIVE               213213
MON$OLDEST_SNAPSHOT             213213
MON$NEXT_TRANSACTION            213213
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                       2063968
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2776 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2776 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2776 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2776 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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Sat Jun 11 04:37:50 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Sat Jun 11 04:37:50 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sat Jun 11 04:37:58 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
--- end of diff output ---

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

11.06.22 04:40:33. 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-06-11 04:40:36 No severe PSQL-related problems occured
11.06.22 04:40:37. End of report.