Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.05.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 16396216 kB
MemAvailable 31402056 kB
Buffers 0 kB
Cached 15276192 kB
SwapCached 2652 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-05-17 07:37:13
DTS_END                         2022-05-17 07:37:13.4300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54916
TRN_ID                          224533
ATT_ID                          642
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: 8840.84 22214 1060901
customer order: creation 176.57 457 21188
customer order: refuse 15.27 390 1832
order to supplier: creation 47.34 1366 5681
order to supplier: removal 3.14 172164 377
invoice (draft): creation 47.25 3206 5670
invoice (draft): removal 47.95 1026 5754
invoice accept: apply 42.05 26245 5046
invoice accept: cancel 43.30 1344 5196
customer reserve: creation 3884.38 253 466126
customer reserve: removal 4238.55 101 508626
realization accept: apply 86.38 275 10365
realization accept: cancel 76.15 164 9138
payment from customer: creation 60.03 168 7204
payment from customer: removal 6.02 120 722
payment to supplier: creation 50.06 185 6007
payment to supplier: removal 3.48 130 417
service: total inventory turnovers 4.47 3481 537
service: total monetary turnovers 7.43 336 892
service: aggregate perf. data 0.99 14449 119
service: refresh index statistics 0.03 218419 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-05-2022 07:38:30 to 17-05-2022 07:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8708
TEST_TIME 2 8281
TEST_TIME 3 7904
TEST_TIME 4 7896
TEST_TIME 5 7436
TEST_TIME 6 6989
TEST_TIME 7 6889
TEST_TIME 8 6735
TEST_TIME 9 6835
TEST_TIME 10 6723
TEST_TIME 11 6744
TEST_TIME 12 6522
TEST_TIME 13 6736
TEST_TIME 14 7008
TEST_TIME 15 7080
TEST_TIME 16 7223
TEST_TIME 17 7634
TEST_TIME 18 7701
TEST_TIME 19 7839
TEST_TIME 20 7841
TEST_TIME 21 7960
TEST_TIME 22 8135
TEST_TIME 23 8147
TEST_TIME 24 8381
TEST_TIME 25 8263
TEST_TIME 26 8497
TEST_TIME 27 8572
TEST_TIME 28 8946
TEST_TIME 29 9097
TEST_TIME 30 9549
TEST_TIME 31 10144
TEST_TIME 32 10746
TEST_TIME 33 10579
TEST_TIME 34 10569
TEST_TIME 35 10365
TEST_TIME 36 10348
TEST_TIME 37 10096
TEST_TIME 38 10108
TEST_TIME 39 9908
TEST_TIME 40 9893
TEST_TIME 41 9786
TEST_TIME 42 9732
TEST_TIME 43 9771
TEST_TIME 44 9655
TEST_TIME 45 9556
TEST_TIME 46 9557
TEST_TIME 47 9447
TEST_TIME 48 9431
TEST_TIME 49 9489
TEST_TIME 50 9547
TEST_TIME 51 9534
TEST_TIME 52 9488
TEST_TIME 53 9470
TEST_TIME 54 9538
TEST_TIME 55 9651
TEST_TIME 56 9652
TEST_TIME 57 9571
TEST_TIME 58 9556
TEST_TIME 59 9578
TEST_TIME 60 9540
TEST_TIME 61 9574
TEST_TIME 62 9547
TEST_TIME 63 9466
TEST_TIME 64 9435
TEST_TIME 65 9402
TEST_TIME 66 9365
TEST_TIME 67 9294
TEST_TIME 68 9209
TEST_TIME 69 9112
TEST_TIME 70 9107
TEST_TIME 71 9087
TEST_TIME 72 9050
TEST_TIME 73 9009
TEST_TIME 74 9026
TEST_TIME 75 8985
TEST_TIME 76 8951
TEST_TIME 77 8965
TEST_TIME 78 8937
TEST_TIME 79 9000
TEST_TIME 80 9022
TEST_TIME 81 9048
TEST_TIME 82 9046
TEST_TIME 83 9025
TEST_TIME 84 9040
TEST_TIME 85 9111
TEST_TIME 86 9125
TEST_TIME 87 9143
TEST_TIME 88 9195
TEST_TIME 89 9194
TEST_TIME 90 9128
TEST_TIME 91 9139
TEST_TIME 92 9137
TEST_TIME 93 9141
TEST_TIME 94 9115
TEST_TIME 95 9158
TEST_TIME 96 9123
TEST_TIME 97 9083
TEST_TIME 98 9129
TEST_TIME 99 9162
TEST_TIME 100 9161
TEST_TIME 101 9177
TEST_TIME 102 9186
TEST_TIME 103 9137
TEST_TIME 104 9078
TEST_TIME 105 9044
TEST_TIME 106 9008
TEST_TIME 107 9049
TEST_TIME 108 9032
TEST_TIME 109 9001
TEST_TIME 110 8999
TEST_TIME 111 8976
TEST_TIME 112 8941
TEST_TIME 113 8919
TEST_TIME 114 8896
TEST_TIME 115 8899
TEST_TIME 116 8900
TEST_TIME 117 8879
TEST_TIME 118 8887
TEST_TIME 119 8897
TEST_TIME 120 8856

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-05-2022 07:38:34 to 17-05-2022 07:38:37.

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 1491595 1491595 0 0.00 0 26280 14 0 0
doc_list_biud 1491584 1491584 0 0.00 0 108317 40 0 0
sp_add_invoice_to_stock 5228 5046 182 3.48 46 222687 26245 0 182
sp_cancel_adding_invoice 5196 5196 0 0.00 6 96334 1344 0 0
sp_cancel_client_order 1832 1832 0 0.00 5 9711 391 0 0
sp_cancel_customer_reserve 508626 508626 0 0.00 1 25946 101 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 4 4520 120 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 4 2154 131 0 0
sp_cancel_supplier_invoice 5769 5754 15 0.26 3 108325 1026 0 15
sp_cancel_supplier_order 377 377 0 0.00 11 1351747 172165 0 0
sp_cancel_write_off 9138 9138 0 0.00 2 13465 165 0 0
sp_client_order 21188 21188 0 0.00 6 32153 458 0 0
sp_customer_reserve 466126 466126 0 0.00 0 78411 254 0 0
sp_fill_shopping_cart 32523 32523 0 0.00 1 13643 82 0 0
sp_fill_shopping_cart_clo_res 466097 466097 0 0.00 0 18242 20 0 0
sp_get_clo_for_invoice 5012 5012 0 0.00 0 45281 281 0 0
sp_kill_cost_storno 15473 15473 0 0.00 0 26260 74 0 0
sp_kill_qstorno_ret_qs2qd 1032521 1032521 0 0.00 0 108316 45 0 0
sp_kill_qty_storno 546295 546295 0 0.00 0 108316 102 0 0
sp_lock_selected_doc 21207 21207 0 0.00 0 6997 8 0 0
sp_make_cost_storno 28586 28586 0 0.00 0 19528 75 0 0
sp_multiply_rows_for_pdistr 28583 28583 0 0.00 0 7052 36 0 0
sp_multiply_rows_for_qdistr 21188 21188 0 0.00 0 13107 165 0 0
sp_pay_from_customer 7204 7204 0 0.00 6 11409 169 0 0
sp_pay_to_supplier 6007 6007 0 0.00 7 10162 186 0 0
sp_payment_common 13211 13211 0 0.00 2 8845 44 0 0
sp_qd_handle_on_cancel_clo 1832 1832 0 0.00 1 9703 309 0 0
sp_qd_handle_on_invoice_upd_sts 10204 10204 0 0.00 0 22326 500 0 0
sp_qd_handle_on_reserve_upd_sts 10364 10364 0 0.00 0 13672 65 0 0
sp_reserve_write_off 10365 10365 0 0.00 8 15453 276 0 0
sp_supplier_invoice 5670 5670 0 0.00 10 124523 3206 0 0
sp_supplier_order 5681 5681 0 0.00 8 41077 1367 0 0
srv_aggregate_perf_data 119 119 0 0.00 2220 97752 14449 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 779 142760 37532 0 0
srv_find_qd_qs_mism 1011780 1011780 0 0.00 0 9871 11 0 0
srv_make_invnt_saldo 537 537 0 0.00 77 69458 3481 0 0
srv_make_money_saldo 892 892 0 0.00 1 9490 337 0 0
srv_recalc_idx_stat 4 4 0 0.00 66122 408665 218420 0 0
t$perf-abend:sp_add_invoice_to_stock 182 182 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5050 5050 0 0.00 1 13550 903 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 6512 546 0 0
t$perf-norm:sp_cancel_client_order 1832 1832 0 0.00 0 5751 28 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 701 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 1974 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 1541 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 1997 42 0 0
t$perf-norm:sp_cancel_supplier_order 377 377 0 0.00 0 68169 6875 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 1043 19 0 0
t$perf-norm:sp_client_order 21188 21188 0 0.00 0 23278 38 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 1826 21 0 0
t$perf-norm:sp_pay_from_customer 7204 7204 0 0.00 0 10761 23 0 0
t$perf-norm:sp_pay_to_supplier 6007 6007 0 0.00 0 10546 25 0 0
t$perf-norm:sp_reserve_write_off 10365 10365 0 0.00 0 16537 25 0 0
t$perf-norm:sp_supplier_invoice 5670 5670 0 0.00 0 6141 32 0 0
t$perf-norm:sp_supplier_order 5681 5681 0 0.00 0 6847 34 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 1616 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 800 15 0 0
t$perf-norm:srv_make_invnt_saldo 537 537 0 0.00 0 2363 23 0 0
t$perf-norm:srv_make_money_saldo 892 892 0 0.00 0 6716 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 187 55 0 0
v_add_invoice_to_stock 10376 10012 364 3.50 0 5571 27 0 364
v_all_customers 38596 38596 0 0.00 0 108 4 0 0
v_all_suppliers 31550 31550 0 0.00 0 74 4 0 0
v_all_wares 42374 42374 0 0.00 0 86 4 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 571 14 0 0
v_cancel_client_order 3664 3664 0 0.00 0 5138 16 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 4441 16 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 3895 13 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 687 11 0 0
v_cancel_supplier_invoice 880 850 30 3.40 0 378 21 0 30
v_cancel_supplier_order 754 754 0 0.00 0 772 12 0 0
v_cancel_write_off 1962 1962 0 0.00 0 6427 28 0 0
v_max_id_clo_ord 5676 5676 0 0.00 0 378 5 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 2466 15 0 0
v_max_id_ord_sup 5658 5658 0 0.00 0 6929 8 0 0
v_max_non_paid_invoice 1566 1566 0 0.00 0 131 7 0 0
v_max_non_paid_realizn 3780 3780 0 0.00 0 8303 12 0 0
v_min_id_clo_ord 5676 5676 0 0.00 0 1256 7 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 1514 10 0 0
v_min_id_ord_sup 5658 5658 0 0.00 0 7072 10 0 0
v_min_non_paid_invoice 6007 6007 0 0.00 0 8574 15 0 0
v_min_non_paid_realizn 7204 7204 0 0.00 0 7374 15 0 0
v_our_firm 10626 10626 0 0.00 0 83 4 0 0
v_reserve_write_off 20728 20728 0 0.00 0 6421 13 0 0
x_lock_depdocs_on_canc_invoice 10525 10525 0 0.00 0 9284 114 0 0
x_lock_depdocs_on_canc_sup_ord 377 377 0 0.00 0 14925 348 0 0
x_make_qstorno_1000_1200 5681 5681 0 0.00 2 41037 1208 0 0
x_make_qstorno_1200_2000 5670 5670 0 0.00 5 123186 2978 0 0
x_make_qstorno_2100_3300 466122 466122 0 0.00 0 78393 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-05-2022 07:38:38 to 17-05-2022 07:38:42.

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:17 12 8192000000 8801045648 8813072384 5260816 0.064 2 1 2 0 5360240 63648 35776
05:08:18 30965 8192000000 9719292576 10015551488 785183392 8.746 100 87 174 0 814923216 7791040 21948784
05:09:59 26757 8192000000 9768341456 10078339072 838455456 9.284 100 86 172 0 870859120 11816416 20587248
05:11:30 20478 8192000000 9811674992 10138005504 888578656 9.785 100 74 148 0 923234192 18289936 16365600
05:12:54 10841 8192000000 9869764432 10207383552 930477904 10.199 100 81 159 2 974976912 27180496 17318512
05:14:09 3260 8192000000 9913003936 10259841024 963514976 10.523 100 90 165 1 1033346528 52068480 17763072
05:15:16 4214 8192000000 9980261040 10334244864 997210432 10.851 100 85 164 1 1088166144 75008608 15947104
05:16:21 1231 8192000000 9997559728 10361929728 1017927680 11.052 100 92 179 3 1116395536 80345936 18121920
05:17:25 1146 8192000000 10019007376 10392227840 1037828384 11.244 100 86 168 0 1139285456 84329824 17127248
05:18:28 1015 8192000000 10011654096 10384785408 1052377360 11.383 100 93 177 2 1130400656 59581664 18441632
05:19:30 1432 8192000000 10042770320 10422280192 1058543760 11.443 100 95 185 0 1162588592 84755360 19289472
05:20:33 1183 8192000000 10057455824 10437627904 1069575136 11.548 100 98 187 0 1179355792 90549840 19230816
05:21:35 1310 8192000000 10054920704 10436886528 1073005616 11.581 100 91 178 2 1176547664 85924288 17617760
05:22:38 971 8192000000 10063258512 10450305024 1084901072 11.694 100 85 165 1 1185992976 84260016 16831888
05:23:39 1126 8192000000 10059703648 10447343616 1086768688 11.712 100 87 172 0 1183013600 78871616 17373296
05:24:41 1556 8192000000 10071243136 10458251264 1090363104 11.746 100 96 184 3 1192478640 83330432 18785104
05:25:44 1240 8192000000 10043708016 10432692224 1090599184 11.748 100 95 184 0 1168061968 58191760 19271024
05:26:46 1262 8192000000 10078961632 10465382400 1094689616 11.787 100 91 178 0 1203191936 91595072 16907248
05:27:47 1043 8192000000 10051376240 10436718592 1088860016 11.732 100 91 170 1 1171698464 66023216 16815232
05:28:49 3418 8192000000 10071546832 10469429248 1093767008 11.778 100 69 135 0 1193913008 86352336 13793664
05:29:53 1517 8192000000 10031032880 10414538752 1075743984 11.607 100 98 186 0 1156025952 61103776 19178192
05:30:56 1637 8192000000 10031925936 10417360896 1067357744 11.527 100 95 181 0 1154858256 67738352 19762160
05:31:58 1184 8192000000 9998878448 10376110080 1044024208 11.303 100 91 175 1 1120920720 59264176 17632336
05:33:00 1364 8192000000 10005773792 10379862016 1054204672 11.401 100 87 161 1 1122790432 51809776 16775984
05:34:03 1076 8192000000 9993623280 10366279680 1027557136 11.145 99 89 167 1 1116610400 70910432 18142832
05:35:04 1180 8192000000 9992373072 10368593920 1033670176 11.204 100 86 166 1 1113719136 63110448 16938512
05:36:07 1131 8192000000 9982915568 10352136192 1034821472 11.215 100 97 191 3 1108477872 54035568 19620832
05:37:08 2474 8192000000 9978030176 10355027968 1051607536 11.376 100 72 126 1 1107094576 41792640 13694400
05:38:17 1542 8192000000 10038768720 10422427648 1069073200 11.543 100 89 178 2 1164486096 76643008 18769888
05:39:22 6267 8192000000 10060646752 10439720960 1078964176 11.638 100 77 143 3 1169943296 77318160 13660960
05:40:34 5694 8192000000 10070068336 10465959936 1090664384 11.749 100 72 144 1 1183243744 78109360 14470000
05:41:46 12687 8192000000 10071323280 10468614144 1104027952 11.876 100 58 111 0 1188330192 73150944 11151296
05:43:05 1001 8192000000 10110706384 10499895296 1122093680 12.047 100 88 169 1 1221613168 80954256 18565232
05:44:12 4183 8192000000 10127117872 10519408640 1119889808 12.026 100 78 142 3 1242914880 108807184 14217888
05:45:19 900 8192000000 10162036288 10550394880 1138093584 12.198 100 99 186 1 1275705888 115610752 22001552
05:46:26 2303 8192000000 10188663824 10591842304 1140472288 12.220 100 97 192 1 1304334112 141685616 22176208
05:47:34 4687 8192000000 10190089792 10597199872 1153401440 12.341 100 70 136 0 1306968272 137483104 16083728
05:48:41 1432 8192000000 10220156128 10623754240 1153598192 12.343 100 67 130 1 1334492288 166019936 14874160
05:49:47 3327 8192000000 10300799616 10701361152 1167889984 12.477 100 84 162 1 1412473312 226228736 18354592
05:50:57 893 8192000000 10309352912 10716848128 1171811744 12.514 100 95 180 0 1417031360 224918752 20300864
05:51:59 768 8192000000 10335138320 10740576256 1169854880 12.495 100 98 188 1 1444505680 252248752 22402048
05:53:02 928 8192000000 10385160608 10793635840 1177183696 12.564 100 96 187 1 1498017280 297676400 23157184
05:54:06 980 8192000000 10402034016 10834599936 1176986000 12.562 100 97 192 1 1505280896 306034384 22260512
05:55:10 1266 8192000000 10389046224 10802716672 1181080416 12.600 100 97 191 1 1493247136 289868304 22298416
05:56:14 931 8192000000 10395114272 10814492672 1172962624 12.525 100 96 186 0 1499241776 303709440 22569712
05:57:19 822 8192000000 10430563568 10837098496 1176568784 12.558 99 97 185 1 1537307840 339380512 21358544
05:58:23 862 8192000000 10438835760 10850467840 1154447568 12.351 99 97 194 0 1541662912 363264624 23950720
05:59:28 805 8192000000 10464637120 10884509696 1166853856 12.467 100 97 189 1 1567702832 376704608 24144368
06:00:32 774 8192000000 10492807888 10900721664 1166631088 12.465 100 95 187 0 1597279936 407864560 22784288
06:01:35 1447 8192000000 10479012464 10932011008 1168361552 12.482 100 79 157 0 1583907136 395718800 19826784
06:02:42 1847 8192000000 10462321728 10924097536 1167888752 12.477 100 72 139 2 1573574096 388673856 17011488
06:03:55 15671 8192000000 10492778256 10928623616 1167880768 12.477 100 64 128 2 1597825104 413615104 16329232
06:05:13 13645 8192000000 10478935344 10961555456 1160251312 12.406 100 45 90 1 1585917200 413031472 12634416
06:07:24 20610 8192000000 10376115888 10869116928 1147804352 12.289 100 29 58 1 1487503856 331949808 7749696
06:09:38 136612 8192000000 10102035024 10570383360 1046301760 11.325 91 12 24 0 1097354080 48673872 2378448
06:12:55 35049 8192000000 10120489040 10569273344 1130250752 12.124 99 54 104 2 1208611952 67834576 10526624
06:14:37 142760 8192000000 10107373248 10558353408 1115595200 11.985 98 33 65 0 1200940656 78767824 6577632
06:18:08 38696 8192000000 10108994960 10544627712 1136147040 12.179 99 45 83 1 1192221424 46084528 9989856
06:19:51 11888 8192000000 10122220992 10555957248 1134396208 12.163 99 65 127 1 1212488960 64740720 13352032
06:21:11 53048 8192000000 10121177952 10571255808 1133154560 12.151 98 8 16 1 1199237008 64348272 1734176
06:23:05 50722 8192000000 10142752272 10645843968 1158400880 12.388 100 17 34 0 1254693152 92416384 3875888
06:24:59 43652 8192000000 10155871552 10645467136 1142492592 12.239 99 22 44 0 1254578704 106234736 5851376
06:27:06 62331 8192000000 10126706816 10615488512 1156604944 12.371 100 20 39 0 1242893872 81718736 4570192
06:29:09 54179 8192000000 10153471552 10605535232 1155265312 12.359 99 18 36 2 1235423584 76349312 3808960
06:31:16 42652 8192000000 10110558512 10563219456 1128300384 12.105 98 23 46 1 1202972816 69946320 4726112
06:32:59 47961 8192000000 10103813040 10556248064 1115472704 11.984 97 30 60 1 1161665632 40330720 5862208
06:34:55 50973 8192000000 10128069424 10562809856 1125882144 12.083 98 41 82 1 1206326608 72230560 8213904
06:36:50 23486 8192000000 10115495888 10534240256 1114149776 11.972 98 56 108 1 1182778704 57968208 10660720
06:38:17 24599 8192000000 10145935200 10607636480 1134260096 12.162 99 50 100 0 1216445120 72618144 9566880
06:39:44 140079 8192000000 10055961968 10528342016 1107263728 11.907 97 29 58 0 1196163168 82598416 6301024
06:43:04 54963 8192000000 10054762304 10515824640 1126216224 12.086 99 15 28 1 1160030480 31362512 2451744
06:44:59 65537 8192000000 10081019984 10526285824 1093095488 11.772 96 16 29 1 1140837680 44232608 3509584
06:48:33 40128 8192000000 10057955184 10523414528 1133981248 12.159 100 15 28 1 1181055744 44615904 2458592
06:50:17 27569 8192000000 10061640576 10507124736 1103458256 11.870 97 19 38 1 1149108672 41530624 4119792
06:51:47 57005 8192000000 10090456048 10526588928 1088131392 11.725 96 26 51 0 1144819792 51631968 5056432
06:53:53 57627 8192000000 10152169776 10588803072 1123435104 12.059 98 28 56 0 1221350448 92130752 5784592
06:56:09 39341 8192000000 10172684576 10653302784 1106327936 11.898 97 47 92 1 1264550736 148793328 9429472
06:57:50 38142 8192000000 10147670960 10608742400 1108454960 11.918 98 31 60 2 1248488912 133044816 6989136
06:59:37 8891 8192000000 10190696704 10636365824 1140875408 12.224 100 53 104 1 1280546512 128684864 10986240
07:00:48 54625 8192000000 10232324976 10701299712 1119191264 12.019 98 28 56 3 1320534304 194117296 7225744
07:03:01 100939 8192000000 10146071936 10616348672 1131231552 12.133 100 33 65 1 1272036432 132992176 7812704
07:05:45 120806 8192000000 10060192448 10528960512 1137314608 12.190 100 9 16 0 1190964880 51922960 1727312
07:08:47 24545 8192000000 10104545792 10530562048 1132548928 12.145 98 44 85 0 1176646848 35667152 8430768
07:10:30 61403 8192000000 10134892928 10594807808 1127946448 12.102 98 20 40 2 1189034720 56506416 4581856
07:12:31 67228 8192000000 10091582032 10532245504 1115924512 11.988 97 12 24 1 1149917312 31390864 2601936
07:14:40 47763 8192000000 10156347744 10611326976 1142167616 12.236 99 45 88 2 1224701952 73812560 8721776
07:16:30 62450 8192000000 10111823152 10553491456 1126489392 12.088 99 40 80 1 1186260880 51099632 8671856
07:18:50 83653 8192000000 10104059120 10536439808 1096709552 11.806 96 20 38 1 1179516048 78865792 3940704
07:21:15 115525 8192000000 10151685008 10604572672 1145704480 12.269 100 24 48 1 1236296208 84918976 5672752
07:24:10 58138 8192000000 10085368656 10567618560 1137144592 12.189 99 11 21 0 1192619888 52566784 2908512
07:27:06 63606 8192000000 10065464064 10545504256 1113431888 11.965 98 21 42 2 1173740240 55691568 4616784
07:29:13 48084 8192000000 10061782688 10528899072 1123159584 12.057 99 18 36 1 1155049536 27571504 4318448
07:31:03 73349 8192000000 10054393776 10525900800 1114665664 11.977 98 11 22 0 1148297680 31435104 2196912
07:33:17 32497 8192000000 10110227136 10564132864 1118072480 12.009 98 23 46 1 1190267984 67115184 5080320
07:34:53 33121 8192000000 10102990176 10534424576 1117545600 12.004 99 31 62 1 1188999936 64630608 6823728
07:36:30 49933 8192000000 9466481632 9840402432 543114720 6.217 48 15 28 1 605609904 59334624 3160560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-05-2022 07:38:44 to 17-05-2022 07:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 182 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-05-2022 07:39:01 to 17-05-2022 07:39:03.

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          223292
MON$OLDEST_ACTIVE               225231
MON$OLDEST_SNAPSHOT             225231
MON$NEXT_TRANSACTION            225231
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                       2042656
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:250: 
:251: mscs-test-161.budzdorov.ru    Tue May 17 07:37:27 2022
:252:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:253: 
--- end of diff output ---

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

17.05.22 07:39:04. 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-05-17 07:39:06 No severe PSQL-related problems occured
17.05.22 07:39:08. End of report.