Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.07.2022 07:38
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 15871556 kB
MemAvailable 31059492 kB
Buffers 0 kB
Cached 15796740 kB
SwapCached 2524 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-07-14 07:37:18
DTS_END                         2022-07-14 07:37:18.6540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36962
TRN_ID                          236730
ATT_ID                          582
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: 8848.64 22667 1061837
customer order: creation 189.62 458 22754
customer order: refuse 16.16 379 1939
order to supplier: creation 49.88 1312 5986
order to supplier: removal 3.18 158944 382
invoice (draft): creation 49.95 2934 5994
invoice (draft): removal 45.53 1012 5464
invoice accept: apply 45.79 26181 5495
invoice accept: cancel 40.40 1464 4848
customer reserve: creation 4141.43 257 496972
customer reserve: removal 3958.15 102 474978
realization accept: apply 91.64 270 10997
realization accept: cancel 75.28 177 9034
payment from customer: creation 65.08 175 7810
payment from customer: removal 6.14 131 737
payment to supplier: creation 52.49 179 6299
payment to supplier: removal 3.54 110 425
service: total inventory turnovers 4.62 3613 554
service: total monetary turnovers 8.66 301 1039
service: aggregate perf. data 1.03 12844 124
service: refresh index statistics 0.05 242489 6

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6315
TEST_TIME 2 6470
TEST_TIME 3 5977
TEST_TIME 4 5934
TEST_TIME 5 5474
TEST_TIME 6 5542
TEST_TIME 7 5552
TEST_TIME 8 5972
TEST_TIME 9 6098
TEST_TIME 10 6368
TEST_TIME 11 6655
TEST_TIME 12 7019
TEST_TIME 13 7128
TEST_TIME 14 7131
TEST_TIME 15 7135
TEST_TIME 16 7419
TEST_TIME 17 7757
TEST_TIME 18 7886
TEST_TIME 19 8252
TEST_TIME 20 8371
TEST_TIME 21 8326
TEST_TIME 22 8262
TEST_TIME 23 8376
TEST_TIME 24 8645
TEST_TIME 25 8786
TEST_TIME 26 9193
TEST_TIME 27 9867
TEST_TIME 28 10254
TEST_TIME 29 10425
TEST_TIME 30 10965
TEST_TIME 31 10880
TEST_TIME 32 11028
TEST_TIME 33 11223
TEST_TIME 34 11030
TEST_TIME 35 11074
TEST_TIME 36 11182
TEST_TIME 37 10978
TEST_TIME 38 10784
TEST_TIME 39 10586
TEST_TIME 40 10442
TEST_TIME 41 10311
TEST_TIME 42 10284
TEST_TIME 43 10138
TEST_TIME 44 10094
TEST_TIME 45 9986
TEST_TIME 46 9902
TEST_TIME 47 9767
TEST_TIME 48 9677
TEST_TIME 49 9697
TEST_TIME 50 9665
TEST_TIME 51 9612
TEST_TIME 52 9811
TEST_TIME 53 9924
TEST_TIME 54 9837
TEST_TIME 55 9922
TEST_TIME 56 9883
TEST_TIME 57 9822
TEST_TIME 58 9876
TEST_TIME 59 9801
TEST_TIME 60 9814
TEST_TIME 61 9814
TEST_TIME 62 9823
TEST_TIME 63 9781
TEST_TIME 64 9664
TEST_TIME 65 9615
TEST_TIME 66 9557
TEST_TIME 67 9507
TEST_TIME 68 9474
TEST_TIME 69 9430
TEST_TIME 70 9363
TEST_TIME 71 9355
TEST_TIME 72 9314
TEST_TIME 73 9294
TEST_TIME 74 9268
TEST_TIME 75 9316
TEST_TIME 76 9295
TEST_TIME 77 9331
TEST_TIME 78 9330
TEST_TIME 79 9301
TEST_TIME 80 9266
TEST_TIME 81 9255
TEST_TIME 82 9307
TEST_TIME 83 9385
TEST_TIME 84 9426
TEST_TIME 85 9497
TEST_TIME 86 9417
TEST_TIME 87 9343
TEST_TIME 88 9316
TEST_TIME 89 9276
TEST_TIME 90 9224
TEST_TIME 91 9192
TEST_TIME 92 9143
TEST_TIME 93 9155
TEST_TIME 94 9126
TEST_TIME 95 9080
TEST_TIME 96 9074
TEST_TIME 97 9035
TEST_TIME 98 9017
TEST_TIME 99 9013
TEST_TIME 100 9022
TEST_TIME 101 9094
TEST_TIME 102 9049
TEST_TIME 103 9077
TEST_TIME 104 9117
TEST_TIME 105 9130
TEST_TIME 106 9137
TEST_TIME 107 9155
TEST_TIME 108 9174
TEST_TIME 109 9129
TEST_TIME 110 9059
TEST_TIME 111 9083
TEST_TIME 112 9054
TEST_TIME 113 9012
TEST_TIME 114 9022
TEST_TIME 115 8996
TEST_TIME 116 8971
TEST_TIME 117 8943
TEST_TIME 118 8936
TEST_TIME 119 8935
TEST_TIME 120 8873

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-07-2022 07:38:39 to 14-07-2022 07:38:43.

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 1518901 1518901 0 0.00 0 28921 14 0 0
doc_list_biud 1518897 1518897 0 0.00 0 35742 38 0 0
sp_add_invoice_to_stock 5624 5495 129 2.29 52 213797 26182 0 129
sp_cancel_adding_invoice 4848 4848 0 0.00 6 87943 1465 0 0
sp_cancel_client_order 1939 1939 0 0.00 6 11561 379 0 0
sp_cancel_customer_reserve 474978 474978 0 0.00 1 35746 102 0 0
sp_cancel_pay_from_customer 737 737 0 0.00 3 9873 131 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 4 5739 111 0 0
sp_cancel_supplier_invoice 5472 5464 8 0.14 2 17462 1012 0 8
sp_cancel_supplier_order 382 382 0 0.00 10 1418607 158944 0 0
sp_cancel_write_off 9034 9034 0 0.00 2 23502 178 0 0
sp_client_order 22754 22754 0 0.00 5 22175 459 0 0
sp_customer_reserve 496972 496972 0 0.00 0 50965 257 0 0
sp_fill_shopping_cart 34733 34733 0 0.00 1 33436 82 0 0
sp_fill_shopping_cart_clo_res 496947 496947 0 0.00 0 19110 20 0 0
sp_get_clo_for_invoice 5448 5448 0 0.00 0 27004 287 0 0
sp_kill_cost_storno 15044 15044 0 0.00 0 26932 70 0 0
sp_kill_qstorno_ret_qs2qd 964836 964836 0 0.00 0 35569 45 0 0
sp_kill_qty_storno 513090 513090 0 0.00 0 35741 103 0 0
sp_lock_selected_doc 22662 22662 0 0.00 0 8508 7 0 0
sp_make_cost_storno 30554 30554 0 0.00 0 26243 72 0 0
sp_multiply_rows_for_pdistr 30554 30554 0 0.00 0 5960 34 0 0
sp_multiply_rows_for_qdistr 22754 22754 0 0.00 0 8351 186 0 0
sp_pay_from_customer 7810 7810 0 0.00 5 13393 175 0 0
sp_pay_to_supplier 6299 6299 0 0.00 6 12409 179 0 0
sp_payment_common 14109 14109 0 0.00 2 5231 42 0 0
sp_qd_handle_on_cancel_clo 1939 1939 0 0.00 1 11552 299 0 0
sp_qd_handle_on_invoice_upd_sts 10296 10296 0 0.00 0 30457 523 0 0
sp_qd_handle_on_reserve_upd_sts 10997 10997 0 0.00 0 15602 60 0 0
sp_reserve_write_off 10997 10997 0 0.00 8 21886 271 0 0
sp_supplier_invoice 5994 5994 0 0.00 8 84424 2935 0 0
sp_supplier_order 5986 5986 0 0.00 7 30676 1313 0 0
srv_aggregate_perf_data 124 124 0 0.00 2414 116304 12844 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 740 108952 32868 0 0
srv_find_qd_qs_mism 1008368 1008368 0 0.00 0 28919 12 0 0
srv_make_invnt_saldo 554 554 0 0.00 47 104489 3613 0 0
srv_make_money_saldo 1039 1039 0 0.00 1 12897 302 0 0
srv_recalc_idx_stat 6 6 0 0.00 28627 415752 242490 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5496 5496 0 0.00 0 11854 873 0 0
t$perf-norm:sp_cancel_adding_invoice 453 453 0 0.00 2 5891 423 0 0
t$perf-norm:sp_cancel_client_order 1939 1939 0 0.00 0 1066 23 0 0
t$perf-norm:sp_cancel_customer_reserve 769 769 0 0.00 0 1406 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 737 737 0 0.00 0 6711 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 305 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 461 461 0 0.00 0 1014 37 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 89537 6806 0 0
t$perf-norm:sp_cancel_write_off 1051 1051 0 0.00 0 1661 19 0 0
t$perf-norm:sp_client_order 22754 22754 0 0.00 0 9409 33 0 0
t$perf-norm:sp_customer_reserve 1097 1097 0 0.00 0 1693 13 0 0
t$perf-norm:sp_pay_from_customer 7810 7810 0 0.00 0 4546 17 0 0
t$perf-norm:sp_pay_to_supplier 6299 6299 0 0.00 0 4201 18 0 0
t$perf-norm:sp_reserve_write_off 10997 10997 0 0.00 0 8086 22 0 0
t$perf-norm:sp_supplier_invoice 5994 5994 0 0.00 0 3843 29 0 0
t$perf-norm:sp_supplier_order 5986 5986 0 0.00 0 3069 29 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 447 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 72 4 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 729 10 0 0
t$perf-norm:srv_make_money_saldo 1039 1039 0 0.00 0 1426 5 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 2 335 97 0 0
v_add_invoice_to_stock 11154 10896 258 2.31 0 11460 27 0 258
v_all_customers 41286 41286 0 0.00 0 81 4 0 0
v_all_suppliers 33072 33072 0 0.00 0 95 4 0 0
v_all_wares 45508 45508 0 0.00 0 85 4 0 0
v_cancel_adding_invoice 906 906 0 0.00 0 1756 17 0 0
v_cancel_client_order 3878 3878 0 0.00 0 10356 16 0 0
v_cancel_customer_prepayment 1474 1474 0 0.00 0 9832 21 0 0
v_cancel_customer_reserve 1538 1538 0 0.00 1 10330 20 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 2715 14 0 0
v_cancel_supplier_invoice 938 922 16 1.70 0 931 28 0 16
v_cancel_supplier_order 764 764 0 0.00 0 1069 14 0 0
v_cancel_write_off 2102 2102 0 0.00 0 23330 45 0 0
v_max_id_clo_ord 5986 5986 0 0.00 0 1296 5 0 0
v_max_id_clo_res 1097 1097 0 0.00 0 914 10 0 0
v_max_id_ord_sup 5993 5993 0 0.00 0 2562 5 0 0
v_max_non_paid_invoice 1742 1742 0 0.00 0 366 7 0 0
v_max_non_paid_realizn 4206 4206 0 0.00 0 1116 8 0 0
v_min_id_clo_ord 5986 5986 0 0.00 0 3161 8 0 0
v_min_id_clo_res 1097 1097 0 0.00 0 903 9 0 0
v_min_id_ord_sup 5993 5993 0 0.00 0 8729 12 0 0
v_min_non_paid_invoice 6299 6299 0 0.00 0 2695 12 0 0
v_min_non_paid_realizn 7810 7810 0 0.00 0 4498 17 0 0
v_our_firm 11430 11430 0 0.00 0 321 5 0 0
v_reserve_write_off 21994 21994 0 0.00 0 21664 19 0 0
x_lock_depdocs_on_canc_invoice 9851 9851 0 0.00 0 17016 130 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 8582 365 0 0
x_make_qstorno_1000_1200 5986 5986 0 0.00 2 30666 1148 0 0
x_make_qstorno_1200_2000 5994 5994 0 0.00 3 80849 2718 0 0
x_make_qstorno_2100_3300 496971 496971 0 0.00 0 48566 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-07-2022 07:38:44 to 14-07-2022 07:38:48.

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:22 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:23 26848 8192000000 9722366608 10021044224 794435840 8.840 100 83 166 0 822757040 7612016 20709184
05:09:55 22420 8192000000 9766101488 10076205056 838972576 9.289 100 84 168 0 870946576 11881584 20092416
05:11:20 12765 8192000000 9815939296 10135949312 879182928 9.692 100 85 170 0 920274976 21707872 19384176
05:12:38 7325 8192000000 9877568656 10211647488 926214784 10.157 100 85 168 0 982725376 38484144 18026448
05:13:51 3889 8192000000 9930870800 10271899648 963020608 10.519 100 89 174 2 1045176272 64430928 17724736
05:14:58 2216 8192000000 9975586416 10330591232 996488288 10.844 100 90 173 2 1091430768 77422080 17520400
05:16:04 896 8192000000 9999117520 10358829056 1019706272 11.069 100 97 187 0 1118060672 79915904 18438496
05:17:06 1197 8192000000 10004752736 10373042176 1048451552 11.346 100 94 179 4 1121877504 56129392 17296560
05:18:09 963 8192000000 10033187312 10408992768 1062234160 11.478 100 96 186 1 1152961856 72561872 18165824
05:19:11 7164 8192000000 10026760416 10405470208 1070146880 11.553 100 75 140 1 1153775712 70940896 12687936
05:20:19 1018 8192000000 10027082640 10409410560 1073981440 11.590 100 88 175 1 1147578896 56252640 17344816
05:21:21 888 8192000000 10043087616 10427420672 1082884992 11.675 100 82 160 1 1161619840 63242912 15491936
05:22:22 1010 8192000000 10046942560 10433613824 1083313696 11.679 100 92 172 1 1169041520 67709024 18018800
05:23:24 3545 8192000000 10034968592 10429341696 1086862640 11.713 100 67 129 1 1159150608 59466144 12821824
05:24:29 2436 8192000000 10041225392 10432503808 1089508432 11.738 100 82 161 1 1169508592 62030496 17969664
05:25:33 1087 8192000000 10067752864 10459041792 1094767728 11.788 100 96 188 1 1191067696 76856976 19442992
05:26:35 954 8192000000 10060054848 10450755584 1094295680 11.783 100 95 184 0 1182634928 69561696 18777552
05:27:37 983 8192000000 10051390432 10444627968 1085108256 11.696 100 83 162 0 1176626032 75357776 16160000
05:28:39 1017 8192000000 10049453264 10438406144 1079736432 11.645 100 94 169 3 1174255328 75943520 18575376
05:29:41 1079 8192000000 10045644000 10443108352 1088022944 11.724 100 65 126 1 1164187872 63751920 12413008
05:30:43 1267 8192000000 10027052384 10418475008 1083475664 11.681 100 77 142 2 1150571040 52215424 14879952
05:31:45 1366 8192000000 10022221440 10404167680 1060098032 11.457 99 95 181 2 1139256864 59755856 19402976
05:32:47 1138 8192000000 10004765552 10376310784 1032664032 11.194 98 93 175 2 1122826576 71472240 18690304
05:33:50 1665 8192000000 9965976848 10339405824 1036761360 11.234 99 85 159 0 1090038304 36010928 17266016
05:34:52 4952 8192000000 10001139008 10381516800 1046410880 11.326 100 75 147 3 1128929264 67830416 14687968
05:36:00 1144 8192000000 9999327264 10370543616 1036342352 11.229 100 93 185 2 1114709568 59547648 18819568
05:37:02 2414 8192000000 9988200336 10366578688 1051975792 11.380 100 82 153 2 1111578896 43177568 16425536
05:38:11 32965 8192000000 10027925984 10412875776 1070453024 11.556 100 62 113 1 1149046672 67784976 10808672
05:39:49 733 8192000000 10056672336 10437279744 1085636304 11.701 100 95 172 2 1176410544 72363648 18410592
05:40:52 6213 8192000000 10099316448 10489491456 1098316752 11.822 100 72 144 0 1211927632 98942064 14668816
05:42:07 10499 8192000000 10136593152 10532397056 1118893536 12.017 100 61 115 0 1249971808 118928128 12150144
05:43:37 5010 8192000000 10187076144 10592272384 1127437312 12.097 100 62 124 1 1295974944 154721248 13816384
05:44:47 6516 8192000000 10242695136 10647478272 1139262976 12.209 100 71 141 0 1361713408 206867504 15582928
05:46:00 1587 8192000000 10259677024 10656661504 1149119120 12.301 100 89 174 2 1371185168 204357456 17708592
05:47:05 5942 8192000000 10291128112 10700296192 1159948432 12.403 100 73 142 0 1418453088 242351552 16153104
05:48:13 1303 8192000000 10292852112 10707968000 1167502400 12.473 100 91 171 0 1406515856 217744272 21269184
05:49:17 773 8192000000 10306736832 10713030656 1169429632 12.491 100 88 170 3 1410898080 223001248 18467200
05:50:19 2440 8192000000 10376118800 10781523968 1175498016 12.548 100 94 183 1 1476923360 281157072 20268272
05:51:27 1719 8192000000 10375542592 10779254784 1174509648 12.539 100 88 173 1 1483832640 288847552 20475440
05:52:34 1287 8192000000 10392822224 10815135744 1172859664 12.524 100 96 188 2 1501924176 305498048 23566464
05:53:38 1112 8192000000 10378546624 10824781824 1164492800 12.445 100 94 179 0 1486729232 301119296 21117136
05:54:42 997 8192000000 10378531360 10793553920 1151383888 12.322 99 81 155 0 1491114960 321218336 18512736
05:55:49 1056 8192000000 10400778384 10863095808 1177934976 12.571 100 91 181 1 1507172096 307728976 21508144
05:56:53 988 8192000000 10424038624 10840489984 1183372880 12.622 100 91 182 2 1529795664 324114128 22308656
05:57:56 2018 8192000000 10423660304 10842816512 1177805920 12.570 99 77 150 1 1526359648 331079152 17474576
05:59:04 1583 8192000000 10498242720 10908667904 1181627552 12.605 100 95 182 1 1601975456 398954352 21393552
06:00:08 1196 8192000000 10521146864 10943340544 1179752832 12.588 100 96 188 2 1624306672 421555808 22998032
06:01:12 836 8192000000 10516061008 10976022528 1177273840 12.565 100 92 182 2 1622441952 422308656 22859456
06:02:17 1635 8192000000 10530545056 10984550400 1182506976 12.614 100 76 148 2 1629310256 428542672 18260608
06:03:21 2411 8192000000 10466568144 10935152640 1155632720 12.362 99 66 130 1 1568697440 396054800 17009920
06:04:31 19268 8192000000 10349625840 10839449600 1132947888 12.149 98 32 64 0 1461580320 319744848 8887584
06:06:02 21585 8192000000 10235221568 10709749760 1116727920 11.996 98 18 34 0 1335690336 214646592 4315824
06:07:25 17693 8192000000 10113338176 10555658240 1126582448 12.089 98 32 62 0 1234148192 100957568 6608176
06:08:55 37409 8192000000 10140110240 10614980608 1121998032 12.046 98 16 32 0 1229892688 104357344 3537312
06:10:45 69502 8192000000 10096933312 10524106752 1126896368 12.092 98 27 41 0 1159987920 29016416 4075136
06:12:58 69671 8192000000 10045125568 10478895104 1104657488 11.882 97 17 26 0 1144807712 37508656 2641568
06:15:08 97283 8192000000 10071881264 10516652032 1105505520 11.890 98 45 86 0 1148544016 34657312 8381184
06:17:47 21584 8192000000 10071212112 10504040448 1136449152 12.182 100 40 77 1 1167701584 23957536 7294896
06:19:10 17332 8192000000 10120412432 10560040960 1143896496 12.252 100 48 92 1 1226065168 72066656 10102016
06:20:34 13958 8192000000 10092392528 10517667840 1111095008 11.943 97 49 95 1 1181444848 58541264 11808576
06:21:52 40063 8192000000 10152711808 10586460160 1140559776 12.221 100 36 70 1 1247383920 99280272 7543872
06:23:33 46523 8192000000 10169729136 10629574656 1141345440 12.228 100 23 45 0 1275150736 128449616 5355680
06:25:20 45666 8192000000 10180557888 10659233792 1124214368 12.067 99 26 52 0 1297783664 166806768 6762528
06:27:49 16100 8192000000 10175068912 10619748352 1135874176 12.177 100 33 66 0 1273440240 131056704 6509360
06:29:09 49398 8192000000 10111564208 10564534272 1098475616 11.823 97 12 24 0 1194355248 93153968 2725664
06:31:00 21054 8192000000 10071469200 10503229440 1134516768 12.164 100 40 75 1 1166228064 24138624 7572672
06:32:23 54027 8192000000 10067273120 10581307392 1128570992 12.108 100 28 54 1 1188467296 54064976 5831328
06:34:20 7401 8192000000 10083524944 10500722688 1124281184 12.067 100 76 142 3 1172286672 33283488 14722000
06:35:30 50135 8192000000 10080069504 10537308160 1133013248 12.150 100 21 42 0 1191500112 53944800 4542064
06:37:20 21289 8192000000 10097660816 10555478016 1128277600 12.105 100 39 78 1 1206943824 70385152 8281072
06:38:44 51705 8192000000 10090421184 10564083712 1119191376 12.019 99 16 31 0 1177990464 55982832 2816256
06:40:45 88831 8192000000 10046967600 10510962688 1117706896 12.005 99 27 54 0 1143047392 20264128 5076368
06:43:26 37418 8192000000 10063152064 10511306752 1115190272 11.982 99 28 52 1 1150780032 30652288 4937472
06:45:04 49886 8192000000 10078227648 10535395328 1122362560 12.049 99 31 62 0 1188476912 60026256 6088096
06:46:55 59514 8192000000 10058685344 10531999744 1099994656 11.838 97 10 20 0 1147932864 46027504 1910704
06:48:58 27866 8192000000 10031495760 10485993472 1099804544 11.836 98 13 19 0 1121599168 19772928 2021696
06:50:38 19085 8192000000 10092123264 10528542720 1119773728 12.025 99 34 67 0 1191648304 64428288 7446288
06:51:57 26034 8192000000 10103692736 10539483136 1110305376 11.935 98 27 53 0 1184906176 69246000 5354800
06:53:24 57692 8192000000 10096331728 10533691392 1104922832 11.884 98 27 54 0 1190143456 79781232 5439392
06:55:22 65804 8192000000 10109354944 10555363328 1096601008 11.805 97 18 36 0 1166846272 66283136 3962128
06:57:42 37067 8192000000 10159384704 10596737024 1116919776 11.998 98 28 56 0 1244909152 121675536 6313840
06:59:20 34990 8192000000 10163529472 10636554240 1131652672 12.137 100 27 54 0 1279984896 142634224 5698000
07:01:06 68774 8192000000 10088763536 10546200576 1093605792 11.777 97 21 42 0 1175428096 77656720 4165584
07:03:16 108948 8192000000 10066045264 10491142144 1132023648 12.140 100 45 85 1 1178717920 38187664 8506608
07:06:05 71289 8192000000 10081659056 10538831872 1121806816 12.044 99 13 26 0 1185614336 60823904 2983616
07:08:17 44346 8192000000 10060914064 10531213312 1121249088 12.039 99 8 16 0 1182704064 59777936 1677040
07:10:01 47894 8192000000 10055242256 10537291776 1118914944 12.017 99 22 44 0 1175032464 51516032 4601488
07:11:51 52168 8192000000 10036712432 10493288448 1102745488 11.864 98 21 42 0 1136107568 29104928 4257152
07:13:46 46179 8192000000 10025029472 10467684352 1081262912 11.660 96 19 36 0 1104271584 19071920 3936752
07:15:32 17819 8192000000 10080083488 10516541440 1124874256 12.073 100 37 70 0 1172310912 40156736 7279920
07:16:54 74849 8192000000 10116163312 10559045632 1095909120 11.799 96 24 43 0 1188932000 88710416 4312464
07:19:09 19840 8192000000 10103725840 10537652224 1123224496 12.057 100 44 86 0 1188360096 55799648 9335952
07:20:30 46814 8192000000 10141615696 10567299072 1077527792 11.624 96 43 86 0 1189139472 102373136 9238544
07:22:17 25076 8192000000 10078477744 10500263936 1076092976 11.610 97 33 66 0 1152307392 69714640 6499776
07:23:44 68818 8192000000 10083390352 10527936512 1080774240 11.655 97 31 62 1 1145998720 58924512 6299968
07:25:53 41894 8192000000 10069287424 10494775296 1094410128 11.785 98 49 90 1 1144011872 39491584 10110160
07:27:38 98545 8192000000 10065502656 10540326912 1080237392 11.650 97 36 72 0 1153119680 65184288 7698000
07:30:18 52394 8192000000 10065869952 10513846272 1100375952 11.841 99 6 12 0 1167108960 65337248 1395760
07:32:17 107838 8192000000 10061337200 10507083776 1093119184 11.772 98 16 26 0 1123795152 28187632 2488336
07:35:16 15917 8192000000 10108118672 10542833664 1118443424 12.012 99 36 72 0 1197670624 71147696 8079504
07:36:37 38896 8192000000 10137219632 10572050432 1137666224 12.194 100 33 63 0 1231419760 86620384 7133152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-07-2022 07:38:50 to 14-07-2022 07:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-07-2022 07:39:07 to 14-07-2022 07:39:09.

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          230063
MON$OLDEST_ACTIVE               237049
MON$OLDEST_SNAPSHOT             237049
MON$NEXT_TRANSACTION            237049
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                       2061664
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             806
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 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 ---
:198: 
:199: mscs-test-161.budzdorov.ru    Thu Jul 14 07:37:31 2022
:200:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:201: 
:202: 
:203: mscs-test-161.budzdorov.ru    Thu Jul 14 07:37:37 2022
:204:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:205: 
:206: 
:207: mscs-test-161.budzdorov.ru    Thu Jul 14 07:37:38 2022
:208:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:209: 
--- end of diff output ---

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

14.07.22 07:39:10. 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-07-14 07:39:12 No severe PSQL-related problems occured
14.07.22 07:39:14. End of report.