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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16238252 kB
MemAvailable 31452828 kB
Buffers 0 kB
Cached 15436668 kB
SwapCached 2556 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-09 07:37:16
DTS_END                         2022-05-09 07:37:16.5010
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/45748
TRN_ID                          215595
ATT_ID                          604
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: 9049.13 21359 1085896
customer order: creation 165.40 469 19848
customer order: refuse 14.58 446 1749
order to supplier: creation 43.13 1379 5176
order to supplier: removal 3.58 166679 429
invoice (draft): creation 43.98 3265 5278
invoice (draft): removal 52.18 1070 6262
invoice accept: apply 40.48 26652 4858
invoice accept: cancel 47.03 1256 5644
customer reserve: creation 3768.78 251 452253
customer reserve: removal 4583.62 100 550034
realization accept: apply 80.39 305 9647
realization accept: cancel 78.93 178 9471
payment from customer: creation 58.19 176 6983
payment from customer: removal 5.72 132 686
payment to supplier: creation 47.59 194 5711
payment to supplier: removal 3.38 149 405
service: total inventory turnovers 3.86 4166 463
service: total monetary turnovers 7.46 375 895
service: aggregate perf. data 0.83 17455 100
service: refresh index statistics 0.03 202480 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-05-2022 07:38:17 to 09-05-2022 07:38:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9998
TEST_TIME 2 5887
TEST_TIME 3 5921
TEST_TIME 4 5830
TEST_TIME 5 5995
TEST_TIME 6 6028
TEST_TIME 7 6077
TEST_TIME 8 5807
TEST_TIME 9 5999
TEST_TIME 10 5972
TEST_TIME 11 6393
TEST_TIME 12 6460
TEST_TIME 13 6449
TEST_TIME 14 6705
TEST_TIME 15 6693
TEST_TIME 16 6979
TEST_TIME 17 7194
TEST_TIME 18 7232
TEST_TIME 19 7243
TEST_TIME 20 7250
TEST_TIME 21 7261
TEST_TIME 22 7353
TEST_TIME 23 7457
TEST_TIME 24 7663
TEST_TIME 25 7780
TEST_TIME 26 7975
TEST_TIME 27 8351
TEST_TIME 28 8742
TEST_TIME 29 9116
TEST_TIME 30 9515
TEST_TIME 31 9889
TEST_TIME 32 10034
TEST_TIME 33 10033
TEST_TIME 34 10126
TEST_TIME 35 10355
TEST_TIME 36 10332
TEST_TIME 37 10359
TEST_TIME 38 10210
TEST_TIME 39 10597
TEST_TIME 40 10449
TEST_TIME 41 10426
TEST_TIME 42 10298
TEST_TIME 43 10116
TEST_TIME 44 9976
TEST_TIME 45 9920
TEST_TIME 46 10046
TEST_TIME 47 10113
TEST_TIME 48 10017
TEST_TIME 49 9869
TEST_TIME 50 9871
TEST_TIME 51 9820
TEST_TIME 52 9710
TEST_TIME 53 9711
TEST_TIME 54 9686
TEST_TIME 55 9748
TEST_TIME 56 9654
TEST_TIME 57 9668
TEST_TIME 58 9594
TEST_TIME 59 9652
TEST_TIME 60 9691
TEST_TIME 61 9633
TEST_TIME 62 9503
TEST_TIME 63 9418
TEST_TIME 64 9335
TEST_TIME 65 9383
TEST_TIME 66 9334
TEST_TIME 67 9315
TEST_TIME 68 9257
TEST_TIME 69 9263
TEST_TIME 70 9247
TEST_TIME 71 9270
TEST_TIME 72 9232
TEST_TIME 73 9240
TEST_TIME 74 9318
TEST_TIME 75 9298
TEST_TIME 76 9368
TEST_TIME 77 9342
TEST_TIME 78 9481
TEST_TIME 79 9446
TEST_TIME 80 9453
TEST_TIME 81 9381
TEST_TIME 82 9342
TEST_TIME 83 9326
TEST_TIME 84 9276
TEST_TIME 85 9263
TEST_TIME 86 9186
TEST_TIME 87 9124
TEST_TIME 88 9117
TEST_TIME 89 9132
TEST_TIME 90 9157
TEST_TIME 91 9166
TEST_TIME 92 9182
TEST_TIME 93 9151
TEST_TIME 94 9136
TEST_TIME 95 9150
TEST_TIME 96 9213
TEST_TIME 97 9262
TEST_TIME 98 9212
TEST_TIME 99 9239
TEST_TIME 100 9304
TEST_TIME 101 9286
TEST_TIME 102 9299
TEST_TIME 103 9317
TEST_TIME 104 9268
TEST_TIME 105 9202
TEST_TIME 106 9227
TEST_TIME 107 9191
TEST_TIME 108 9140
TEST_TIME 109 9116
TEST_TIME 110 9088
TEST_TIME 111 9092
TEST_TIME 112 9086
TEST_TIME 113 9050
TEST_TIME 114 9035
TEST_TIME 115 9117
TEST_TIME 116 9118
TEST_TIME 117 9098
TEST_TIME 118 9093
TEST_TIME 119 9091
TEST_TIME 120 9079

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-05-2022 07:38:21 to 09-05-2022 07:38:24.

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 1504685 1504685 0 0.00 0 32352 14 0 0
doc_list_biud 1504668 1504668 0 0.00 0 48128 43 0 0
sp_add_invoice_to_stock 4943 4858 85 1.71 79 233579 26652 0 85
sp_cancel_adding_invoice 5644 5644 0 0.00 6 89915 1256 0 0
sp_cancel_client_order 1749 1749 0 0.00 7 7643 446 0 0
sp_cancel_customer_reserve 550034 550034 0 0.00 1 48136 101 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 4 6468 133 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 4 5944 149 0 0
sp_cancel_supplier_invoice 6272 6262 10 0.15 3 14963 1071 0 10
sp_cancel_supplier_order 429 429 0 0.00 9 1481186 166680 0 0
sp_cancel_write_off 9471 9471 0 0.00 2 9984 178 0 0
sp_client_order 19848 19848 0 0.00 6 31354 469 0 0
sp_customer_reserve 452253 452253 0 0.00 1 90180 252 0 0
sp_fill_shopping_cart 30295 30295 0 0.00 2 8692 75 0 0
sp_fill_shopping_cart_clo_res 452216 452216 0 0.00 0 23968 19 0 0
sp_get_clo_for_invoice 4804 4804 0 0.00 0 31588 287 0 0
sp_kill_cost_storno 16206 16206 0 0.00 0 10084 87 0 0
sp_kill_qstorno_ret_qs2qd 1116230 1116230 0 0.00 0 48115 44 0 0
sp_kill_qty_storno 588038 588038 0 0.00 0 48125 102 0 0
sp_lock_selected_doc 20188 20188 0 0.00 0 8661 9 0 0
sp_make_cost_storno 27144 27144 0 0.00 0 32333 88 0 0
sp_multiply_rows_for_pdistr 27143 27143 0 0.00 0 8462 40 0 0
sp_multiply_rows_for_qdistr 19848 19848 0 0.00 0 11930 180 0 0
sp_pay_from_customer 6983 6983 0 0.00 6 12179 176 0 0
sp_pay_to_supplier 5711 5711 0 0.00 6 14466 194 0 0
sp_payment_common 12694 12694 0 0.00 2 6269 44 0 0
sp_qd_handle_on_cancel_clo 1749 1749 0 0.00 1 6420 360 0 0
sp_qd_handle_on_invoice_upd_sts 10446 10446 0 0.00 0 21646 517 0 0
sp_qd_handle_on_reserve_upd_sts 9647 9647 0 0.00 0 16467 70 0 0
sp_reserve_write_off 9647 9647 0 0.00 9 32361 305 0 0
sp_supplier_invoice 5278 5278 0 0.00 7 85469 3265 0 0
sp_supplier_order 5176 5176 0 0.00 7 21051 1380 0 0
srv_aggregate_perf_data 100 100 0 0.00 2995 144816 17456 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 735 173903 35115 0 0
srv_find_qd_qs_mism 1039480 1039480 0 0.00 0 16404 10 0 0
srv_make_invnt_saldo 463 463 0 0.00 71 91240 4167 0 0
srv_make_money_saldo 895 895 0 0.00 1 15388 376 0 0
srv_recalc_idx_stat 4 4 0 0.00 71386 315723 202480 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4860 4860 0 0.00 1 42980 934 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 7105 464 0 0
t$perf-norm:sp_cancel_client_order 1749 1749 0 0.00 0 2172 27 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 2269 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 1358 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 977 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1177 37 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 114459 7705 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 2736 19 0 0
t$perf-norm:sp_client_order 19848 19848 0 0.00 0 7467 38 0 0
t$perf-norm:sp_customer_reserve 938 938 0 0.00 0 4647 21 0 0
t$perf-norm:sp_pay_from_customer 6983 6983 0 0.00 0 39250 30 0 0
t$perf-norm:sp_pay_to_supplier 5711 5711 0 0.00 0 8990 21 0 0
t$perf-norm:sp_reserve_write_off 9647 9647 0 0.00 0 19322 26 0 0
t$perf-norm:sp_supplier_invoice 5278 5278 0 0.00 0 8695 37 0 0
t$perf-norm:sp_supplier_order 5176 5176 0 0.00 0 1836 28 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 327 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 870 22 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 824 18 0 0
t$perf-norm:srv_make_money_saldo 895 895 0 0.00 0 1416 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 167 55 0 0
v_add_invoice_to_stock 9774 9604 170 1.73 0 6316 27 0 170
v_all_customers 36238 36238 0 0.00 0 62 3 0 0
v_all_suppliers 29558 29558 0 0.00 0 95 4 0 0
v_all_wares 39696 39696 0 0.00 0 63 4 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 2687 20 0 0
v_cancel_client_order 3498 3498 0 0.00 0 4366 15 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 2469 12 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 4259 22 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 5928 22 0 0
v_cancel_supplier_invoice 868 848 20 2.30 0 580 21 0 20
v_cancel_supplier_order 858 858 0 0.00 0 787 11 0 0
v_cancel_write_off 1880 1880 0 0.00 0 4368 24 0 0
v_max_id_clo_ord 5175 5175 0 0.00 0 837 5 0 0
v_max_id_clo_res 938 938 0 0.00 0 2854 13 0 0
v_max_id_ord_sup 5271 5271 0 0.00 0 6310 6 0 0
v_max_non_paid_invoice 1378 1378 0 0.00 0 6154 12 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 903 7 0 0
v_min_id_clo_ord 5175 5175 0 0.00 0 3385 10 0 0
v_min_id_clo_res 938 938 0 0.00 0 2580 10 0 0
v_min_id_ord_sup 5271 5271 0 0.00 0 3635 7 0 0
v_min_non_paid_invoice 5711 5711 0 0.00 0 6254 18 0 0
v_min_non_paid_realizn 6983 6983 0 0.00 0 4104 16 0 0
v_our_firm 10132 10132 0 0.00 0 45 4 0 0
v_reserve_write_off 19294 19294 0 0.00 0 6850 13 0 0
x_lock_depdocs_on_canc_invoice 11482 11482 0 0.00 0 13257 133 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 19950 455 0 0
x_make_qstorno_1000_1200 5176 5176 0 0.00 3 21007 1213 0 0
x_make_qstorno_1200_2000 5278 5278 0 0.00 3 84638 3067 0 0
x_make_qstorno_2100_3300 452252 452252 0 0.00 0 90176 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-05-2022 07:38:25 to 09-05-2022 07:38:29.

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:20 9 8192000000 8800887584 8812875776 5260816 0.064 2 1 2 0 5360240 63648 35776
05:08:21 29427 8192000000 9734637728 10035748864 813123952 9.029 100 87 174 0 842411632 7783184 21504496
05:09:57 32755 8192000000 9775434832 10086252544 840077760 9.301 100 90 180 0 874144288 13070544 20995984
05:11:36 17958 8192000000 9808033808 10126987264 867600336 9.576 100 82 164 0 913805472 27512448 18692688
05:13:05 6524 8192000000 9869084336 10205196288 915370064 10.050 100 84 168 0 982568624 48482512 18716048
05:14:16 4436 8192000000 9891292704 10237734912 951932976 10.410 100 83 166 1 1019054880 50627248 16494656
05:15:22 2438 8192000000 9981023856 10337476608 988696320 10.769 100 90 175 0 1099417040 93367120 17353600
05:16:27 1550 8192000000 9982493600 10347978752 1016940880 11.042 100 86 167 1 1103807376 70084352 16782144
05:17:30 1071 8192000000 10017033328 10388578304 1047847936 11.340 100 94 183 1 1132569920 66469392 18252592
05:18:32 1399 8192000000 10039025808 10418319360 1060858080 11.465 100 94 183 0 1158075984 79470560 17747344
05:19:35 990 8192000000 10040906608 10420944896 1064415360 11.499 100 96 187 0 1162702064 80278848 18007856
05:20:37 1274 8192000000 10044261200 10427293696 1068944656 11.542 100 87 168 0 1164109472 78081296 17083520
05:21:40 939 8192000000 10022507856 10408435712 1074868352 11.599 100 80 151 1 1143286624 52592832 15825440
05:22:42 919 8192000000 10067358848 10456137728 1081147616 11.658 100 92 180 0 1188789856 89044000 18598240
05:23:44 1368 8192000000 10054997232 10437718016 1087439216 11.718 100 95 181 2 1172069824 66604192 18026416
05:24:46 4767 8192000000 10054157376 10449162240 1094356192 11.784 100 76 148 0 1185070208 75676240 15037776
05:25:53 1332 8192000000 10067576544 10452488192 1094499264 11.785 100 97 183 1 1181913008 69950512 17463232
05:26:55 1166 8192000000 10073614960 10459295744 1096836880 11.808 100 91 177 0 1192913264 77345392 18730992
05:27:57 1231 8192000000 10070184896 10459017216 1097833344 11.817 100 96 188 4 1193447344 76214592 19399408
05:28:59 921 8192000000 10063341056 10454081536 1086719344 11.711 100 86 167 3 1187259792 82539600 18000848
05:30:01 1574 8192000000 10041612512 10430332928 1080915968 11.656 100 77 150 1 1159131136 62861920 15353248
05:31:04 991 8192000000 10012316224 10396585984 1070726368 11.559 100 88 165 3 1136381312 47939360 17715584
05:32:06 841 8192000000 10004185872 10383007744 1052828384 11.388 100 87 169 1 1135689744 65084048 17777312
05:33:07 1609 8192000000 10029523840 10411610112 1061322752 11.469 100 77 150 0 1149628224 72946912 15358560
05:34:10 1325 8192000000 9989852048 10362839040 1029336944 11.162 100 95 174 3 1110447584 62872720 18237920
05:35:12 1129 8192000000 9998442976 10372431872 1044422208 11.307 100 90 176 0 1122917152 60563648 17931296
05:36:14 1156 8192000000 9980121952 10353299456 1039950832 11.264 100 77 144 3 1094773104 41711456 13110816
05:37:16 3010 8192000000 10010665504 10386632704 1055546400 11.414 100 75 142 1 1130214208 60751216 13916592
05:38:24 1843 8192000000 10035857440 10417725440 1074086928 11.591 100 78 148 2 1156676656 67200512 15389216
05:39:34 15613 8192000000 10041212384 10429071360 1079363712 11.641 100 60 113 2 1152652880 62033312 11255856
05:40:55 1260 8192000000 10075772144 10459545600 1102521408 11.862 100 83 161 1 1195951232 75814448 17615376
05:41:59 1096 8192000000 10120164224 10506874880 1104437664 11.880 100 98 189 3 1243090608 116385888 22267056
05:43:05 1858 8192000000 10135712016 10526564352 1115062080 11.980 100 74 146 1 1254261808 123768224 15431504
05:44:15 10741 8192000000 10136722464 10548654080 1117619360 12.004 100 55 103 0 1256992080 127453616 11919104
05:45:30 3482 8192000000 10204806096 10605883392 1135301728 12.171 100 72 144 1 1315408112 163881776 16224608
05:46:43 15192 8192000000 10218406544 10621566976 1143008240 12.244 100 66 131 0 1333224368 175268528 14947600
05:48:08 1507 8192000000 10257545952 10673225728 1162099824 12.423 100 92 177 0 1373640320 190805456 20735040
05:49:14 815 8192000000 10282894240 10682613760 1177670288 12.568 100 95 184 3 1391245952 193527312 20048352
05:50:19 1396 8192000000 10311380128 10711719936 1178354512 12.575 99 93 181 0 1424317024 224926720 21035792
05:51:24 726 8192000000 10352450064 10773426176 1179489328 12.585 100 96 191 1 1463790432 262212688 22088416
05:52:28 1364 8192000000 10371446128 10788585472 1192035200 12.702 100 92 180 0 1483170064 270179168 20955696
05:53:34 1201 8192000000 10398450528 10840760320 1193654224 12.717 100 93 177 0 1510071872 295823088 20594560
05:54:38 1398 8192000000 10415152128 10851708928 1197177376 12.750 100 94 179 0 1516516320 298455392 20883552
05:55:46 1073 8192000000 10451916368 10854322176 1183613392 12.624 100 94 182 0 1556081952 351119584 21348976
05:56:56 832 8192000000 10472433424 10885218304 1194508864 12.725 100 93 181 0 1573500176 357176080 21815232
05:58:01 993 8192000000 10510880544 10923429888 1191845712 12.701 100 96 192 1 1615682928 401079088 22758128
05:59:06 855 8192000000 10546855696 10952241152 1191672016 12.699 100 93 186 0 1651829280 438092928 22064336
06:00:11 1027 8192000000 10561484736 10972684288 1188078176 12.665 100 98 191 0 1662394240 451224912 23091152
06:01:15 4896 8192000000 10530079936 10969513984 1169598160 12.493 99 60 118 0 1633309824 448142000 15569664
06:02:26 2585 8192000000 10523230512 10971709440 1162590976 12.428 99 72 140 0 1625609296 444565712 18452608
06:03:33 10457 8192000000 10518377120 10955317248 1174684848 12.541 100 52 100 1 1629877648 442387040 12805760
06:04:45 14566 8192000000 10456190976 10937954304 1166058928 12.460 100 44 88 0 1573942928 396108224 11775776
06:06:29 25863 8192000000 10341521136 10793586688 1136615488 12.184 99 35 68 1 1437655936 292361808 8678640
06:08:06 17992 8192000000 10239049616 10685976576 1158292672 12.387 100 52 93 1 1336709728 167595456 10821600
06:09:26 18363 8192000000 10176081728 10613927936 1153730432 12.345 99 43 81 1 1278989616 116522720 8736464
06:10:46 35848 8192000000 10183898448 10665086976 1124689872 12.071 98 20 40 4 1286510912 156964464 4856576
06:12:29 75981 8192000000 10118162480 10551508992 1117965104 12.008 98 18 34 1 1194836944 72345808 4526032
06:14:48 68266 8192000000 10176162960 10621984768 1100343520 11.841 96 15 30 0 1208617968 104656624 3617824
06:17:32 16136 8192000000 10091134720 10509373440 1137720704 12.194 100 75 140 5 1186784272 32714096 16349472
06:18:50 9435 8192000000 10087251408 10539266048 1140033344 12.216 100 60 110 1 1192073200 39313200 12726656
06:20:04 120026 8192000000 10162912992 10613116928 1133206592 12.152 99 25 50 1 1216815312 78183024 5425696
06:23:05 173903 8192000000 10078694832 10525192192 1110743184 11.939 97 20 39 0 1158237408 43304704 4189520
06:27:01 972 8192000000 10130021872 10564497408 1144786224 12.261 100 62 118 5 1228914672 70477472 13650976
06:28:04 27808 8192000000 10105688256 10551279616 1130729168 12.128 99 39 78 0 1207888448 68471264 8688016
06:29:35 5450 8192000000 10193423088 10608787456 1146041504 12.272 100 78 153 1 1280711744 118397536 16272704
06:30:46 63263 8192000000 10176996512 10635022336 1139538688 12.211 100 26 52 1 1281035584 135254432 6242464
06:33:35 48739 8192000000 10107361312 10579193856 1082792912 11.674 96 16 30 1 1184597952 98378384 3426656
06:35:24 59202 8192000000 10092998800 10579562496 1117008720 11.999 99 19 38 1 1225898416 104147312 4742384
06:37:27 33769 8192000000 10041099008 10492325888 1096957376 11.809 97 20 40 0 1124279504 22850256 4471872
06:39:10 65922 8192000000 10057975664 10496724992 1116220496 11.991 98 35 67 1 1154876640 31527696 7128448
06:41:48 63223 8192000000 10060612784 10549092352 1122173424 12.048 99 13 26 0 1180920496 56172656 2574416
06:44:02 82171 8192000000 10054351920 10519093248 1134229136 12.161 100 18 30 2 1168616448 31192256 3195056
06:46:24 47801 8192000000 10060798880 10527326208 1116114640 11.990 99 18 36 1 1159759040 39072832 4571568
06:48:15 23177 8192000000 10086889712 10528112640 1112868464 11.960 99 40 80 0 1177161184 56046864 8245856
06:49:48 30035 8192000000 10135603216 10583703552 1117177664 12.000 100 46 90 1 1226388528 100184592 9026272
06:51:22 41895 8192000000 10067365728 10530541568 1099636624 11.834 98 28 56 0 1163024368 57195632 6192112
06:53:16 35297 8192000000 10092212032 10530680832 1103971216 11.875 98 34 68 2 1189457936 78981952 6504768
06:54:54 96077 8192000000 10081770912 10552180736 1061808224 11.474 94 16 32 1 1125588816 60359728 3420864
06:57:30 125135 8192000000 10115245952 10556755968 1090754912 11.750 97 31 62 0 1151740736 53816864 7168960
07:00:59 17797 8192000000 10049061680 10476441600 1090506640 11.747 97 57 104 0 1132621104 30497712 11616752
07:02:20 77964 8192000000 10072549824 10536620032 1093111520 11.772 98 8 16 0 1158750704 63795184 1844000
07:04:40 63211 8192000000 10093768496 10571816960 1106760976 11.902 99 22 42 2 1182760592 71390016 4609600
07:06:57 67993 8192000000 10093755200 10551590912 1126441872 12.088 100 12 24 0 1189579568 60480752 2656944
07:09:35 57633 8192000000 10081746592 10521956352 1096111712 11.801 96 13 26 0 1133693328 35170080 2411536
07:11:35 27075 8192000000 10103253792 10564468736 1134439728 12.163 99 32 64 0 1220349312 78832208 7077376
07:13:10 40721 8192000000 10131106288 10634219520 1140349056 12.219 99 25 50 2 1250697280 104967184 5381040
07:14:56 14333 8192000000 10076363696 10503634944 1127384800 12.097 99 33 66 1 1158535008 24537760 6612448
07:16:16 35685 8192000000 10115937648 10566856704 1123725504 12.062 99 43 84 1 1196550688 64672832 8152352
07:17:52 88424 8192000000 10137071936 10591793152 1110764384 11.940 97 18 36 1 1175874352 61397072 3712896
07:20:21 95862 8192000000 10084344176 10545176576 1127410272 12.097 99 30 57 1 1175074352 41061712 6602368
07:23:02 18740 8192000000 10080616704 10538332160 1129777520 12.119 100 33 62 1 1187298608 50582816 6938272
07:24:22 70552 8192000000 10109591120 10569453568 1119647632 12.024 98 11 22 0 1194964192 72755744 2560816
07:26:33 65807 8192000000 10110823936 10579623936 1128882880 12.111 99 17 33 1 1207710736 74878112 3949744
07:28:41 57162 8192000000 10101732160 10598989824 1128563072 12.108 99 11 22 0 1199632720 68828032 2241616
07:30:38 36178 8192000000 10131919504 10583961600 1115749440 11.987 98 25 46 3 1214934208 93652688 5532080
07:32:18 55765 8192000000 10069889104 10559520768 1123293408 12.058 99 10 20 0 1192340320 66968992 2077920
07:34:43 12766 8192000000 10116809888 10548019200 1137721344 12.194 100 52 104 0 1204292656 55453840 11117472
07:36:03 77649 8192000000 9547765424 9937448960 614270304 6.975 54 14 28 1 697829600 80460592 3098704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 09-05-2022 07:38:31 to 09-05-2022 07:38:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-05-2022 07:38:48 to 09-05-2022 07:38:50.

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          212406
MON$OLDEST_ACTIVE               216796
MON$OLDEST_SNAPSHOT             216796
MON$NEXT_TRANSACTION            216796
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                       2067168
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 ---
:166: 
:167: mscs-test-161.budzdorov.ru    Mon May  9 07:37:28 2022
:168:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:169: 
--- end of diff output ---

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

09.05.22 07:38:51. 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-09 07:38:54 No severe PSQL-related problems occured
09.05.22 07:38:55. End of report.