Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.04.2022 10: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 16302884 kB
MemAvailable 31531984 kB
Buffers 0 kB
Cached 15434776 kB
SwapCached 2688 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-04-17 10:37:16
DTS_END                         2022-04-17 10:37:16.2190
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/53698
TRN_ID                          225599
ATT_ID                          670
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: 9022.92 23482 1082751
customer order: creation 174.64 437 20957
customer order: refuse 14.88 390 1786
order to supplier: creation 47.99 1220 5759
order to supplier: removal 3.13 171437 376
invoice (draft): creation 46.66 3179 5599
invoice (draft): removal 48.42 935 5810
invoice accept: apply 42.18 25444 5061
invoice accept: cancel 44.40 1271 5328
customer reserve: creation 3958.28 238 474993
customer reserve: removal 4345.23 97 521427
realization accept: apply 85.35 278 10242
realization accept: cancel 79.10 168 9492
payment from customer: creation 60.49 167 7259
payment from customer: removal 5.77 118 692
payment to supplier: creation 49.69 215 5963
payment to supplier: removal 3.72 134 446
service: total inventory turnovers 3.92 4256 470
service: total monetary turnovers 8.03 363 964
service: aggregate perf. data 1.02 15779 122
service: refresh index statistics 0.04 243509 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-04-2022 10:38:51 to 17-04-2022 10:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9639
TEST_TIME 2 6665
TEST_TIME 3 6520
TEST_TIME 4 7402
TEST_TIME 5 7045
TEST_TIME 6 7144
TEST_TIME 7 7105
TEST_TIME 8 6903
TEST_TIME 9 6849
TEST_TIME 10 6769
TEST_TIME 11 6807
TEST_TIME 12 6855
TEST_TIME 13 7234
TEST_TIME 14 7453
TEST_TIME 15 7620
TEST_TIME 16 7750
TEST_TIME 17 8049
TEST_TIME 18 8101
TEST_TIME 19 8132
TEST_TIME 20 8212
TEST_TIME 21 8150
TEST_TIME 22 8127
TEST_TIME 23 8121
TEST_TIME 24 8604
TEST_TIME 25 8610
TEST_TIME 26 8809
TEST_TIME 27 9145
TEST_TIME 28 9375
TEST_TIME 29 9343
TEST_TIME 30 9977
TEST_TIME 31 10430
TEST_TIME 32 10682
TEST_TIME 33 10852
TEST_TIME 34 11310
TEST_TIME 35 11166
TEST_TIME 36 10941
TEST_TIME 37 11194
TEST_TIME 38 11168
TEST_TIME 39 11025
TEST_TIME 40 10981
TEST_TIME 41 10852
TEST_TIME 42 10684
TEST_TIME 43 10610
TEST_TIME 44 10558
TEST_TIME 45 10477
TEST_TIME 46 10390
TEST_TIME 47 10399
TEST_TIME 48 10458
TEST_TIME 49 10377
TEST_TIME 50 10332
TEST_TIME 51 10343
TEST_TIME 52 10299
TEST_TIME 53 10145
TEST_TIME 54 10017
TEST_TIME 55 9906
TEST_TIME 56 9901
TEST_TIME 57 9781
TEST_TIME 58 9773
TEST_TIME 59 9703
TEST_TIME 60 9752
TEST_TIME 61 9759
TEST_TIME 62 9694
TEST_TIME 63 9634
TEST_TIME 64 9558
TEST_TIME 65 9490
TEST_TIME 66 9608
TEST_TIME 67 9584
TEST_TIME 68 9587
TEST_TIME 69 9521
TEST_TIME 70 9503
TEST_TIME 71 9535
TEST_TIME 72 9526
TEST_TIME 73 9615
TEST_TIME 74 9640
TEST_TIME 75 9737
TEST_TIME 76 9763
TEST_TIME 77 9705
TEST_TIME 78 9692
TEST_TIME 79 9802
TEST_TIME 80 9795
TEST_TIME 81 9707
TEST_TIME 82 9604
TEST_TIME 83 9634
TEST_TIME 84 9556
TEST_TIME 85 9583
TEST_TIME 86 9517
TEST_TIME 87 9487
TEST_TIME 88 9467
TEST_TIME 89 9413
TEST_TIME 90 9381
TEST_TIME 91 9367
TEST_TIME 92 9340
TEST_TIME 93 9335
TEST_TIME 94 9317
TEST_TIME 95 9311
TEST_TIME 96 9303
TEST_TIME 97 9296
TEST_TIME 98 9361
TEST_TIME 99 9305
TEST_TIME 100 9387
TEST_TIME 101 9367
TEST_TIME 102 9389
TEST_TIME 103 9401
TEST_TIME 104 9393
TEST_TIME 105 9434
TEST_TIME 106 9490
TEST_TIME 107 9407
TEST_TIME 108 9354
TEST_TIME 109 9346
TEST_TIME 110 9316
TEST_TIME 111 9283
TEST_TIME 112 9264
TEST_TIME 113 9209
TEST_TIME 114 9165
TEST_TIME 115 9131
TEST_TIME 116 9169
TEST_TIME 117 9133
TEST_TIME 118 9146
TEST_TIME 119 9116
TEST_TIME 120 9052

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-04-2022 10:38:55 to 17-04-2022 10:38:58.

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 1518888 1518888 0 0.00 0 31052 14 0 0
doc_list_biud 1518887 1518887 0 0.00 0 36688 39 0 0
sp_add_invoice_to_stock 5201 5061 140 2.69 89 197263 25445 0 140
sp_cancel_adding_invoice 5328 5328 0 0.00 6 94729 1272 0 0
sp_cancel_client_order 1786 1786 0 0.00 7 9247 390 0 0
sp_cancel_customer_reserve 521427 521427 0 0.00 1 33767 97 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 4 6680 119 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 4 5021 134 0 0
sp_cancel_supplier_invoice 5822 5810 12 0.20 3 36831 936 0 12
sp_cancel_supplier_order 376 376 0 0.00 15 1395318 171438 0 0
sp_cancel_write_off 9492 9492 0 0.00 2 12999 169 0 0
sp_client_order 20957 20957 0 0.00 6 30341 437 0 0
sp_customer_reserve 474993 474993 0 0.00 1 49472 238 0 0
sp_fill_shopping_cart 32306 32306 0 0.00 1 14474 86 0 0
sp_fill_shopping_cart_clo_res 474952 474952 0 0.00 0 15353 18 0 0
sp_get_clo_for_invoice 5019 5019 0 0.00 0 40068 298 0 0
sp_kill_cost_storno 15958 15958 0 0.00 0 31041 76 0 0
sp_kill_qstorno_ret_qs2qd 1058532 1058532 0 0.00 0 36667 41 0 0
sp_kill_qty_storno 559480 559480 0 0.00 0 36677 96 0 0
sp_lock_selected_doc 21194 21194 0 0.00 0 8770 9 0 0
sp_make_cost_storno 28482 28482 0 0.00 0 9431 77 0 0
sp_multiply_rows_for_pdistr 28482 28482 0 0.00 0 8039 39 0 0
sp_multiply_rows_for_qdistr 20957 20957 0 0.00 0 9923 156 0 0
sp_pay_from_customer 7259 7259 0 0.00 6 11183 168 0 0
sp_pay_to_supplier 5963 5963 0 0.00 7 15207 215 0 0
sp_payment_common 13218 13218 0 0.00 2 7963 50 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 1 7350 321 0 0
sp_qd_handle_on_invoice_upd_sts 10347 10347 0 0.00 0 18684 541 0 0
sp_qd_handle_on_reserve_upd_sts 10242 10242 0 0.00 0 11064 62 0 0
sp_reserve_write_off 10242 10242 0 0.00 9 11168 279 0 0
sp_supplier_invoice 5599 5599 0 0.00 9 93003 3179 0 0
sp_supplier_order 5759 5759 0 0.00 8 32241 1221 0 0
srv_aggregate_perf_data 122 122 0 0.00 2904 156564 15779 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 771 154791 36732 0 0
srv_find_qd_qs_mism 1032024 1032024 0 0.00 0 10095 10 0 0
srv_make_invnt_saldo 470 470 0 0.00 73 60841 4257 0 0
srv_make_money_saldo 964 964 0 0.00 1 10172 364 0 0
srv_recalc_idx_stat 5 5 0 0.00 60142 374596 243510 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5064 5064 0 0.00 1 14535 1007 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 3 6419 514 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 4745 30 0 0
t$perf-norm:sp_cancel_customer_reserve 745 745 0 0.00 0 610 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 502 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 950 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1087 33 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 124525 7320 0 0
t$perf-norm:sp_cancel_write_off 1075 1075 0 0.00 0 517 16 0 0
t$perf-norm:sp_client_order 20957 20957 0 0.00 0 11855 36 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 1821 16 0 0
t$perf-norm:sp_pay_from_customer 7259 7259 0 0.00 0 4094 19 0 0
t$perf-norm:sp_pay_to_supplier 5963 5963 0 0.00 0 4161 20 0 0
t$perf-norm:sp_reserve_write_off 10242 10242 0 0.00 0 7391 22 0 0
t$perf-norm:sp_supplier_invoice 5599 5599 0 0.00 0 3481 30 0 0
t$perf-norm:sp_supplier_order 5760 5760 0 0.00 0 4327 32 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 431 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 167 9 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 3338 20 0 0
t$perf-norm:srv_make_money_saldo 964 964 0 0.00 0 1860 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 134 43 0 0
v_add_invoice_to_stock 10318 10038 280 2.71 0 8061 25 0 280
v_all_customers 38332 38332 0 0.00 0 62 4 0 0
v_all_suppliers 31290 31290 0 0.00 0 92 4 0 0
v_all_wares 41908 41908 0 0.00 0 94 4 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 203 13 0 0
v_cancel_client_order 3572 3572 0 0.00 0 3169 9 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 1621 11 0 0
v_cancel_customer_reserve 1490 1490 0 0.00 0 245 10 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 3166 19 0 0
v_cancel_supplier_invoice 844 820 24 2.84 0 7341 44 0 24
v_cancel_supplier_order 752 752 0 0.00 0 1772 16 0 0
v_cancel_write_off 2150 2150 0 0.00 0 8569 29 0 0
v_max_id_clo_ord 5759 5759 0 0.00 0 1719 5 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 2416 11 0 0
v_max_id_ord_sup 5593 5593 0 0.00 0 2337 6 0 0
v_max_non_paid_invoice 1666 1666 0 0.00 0 1531 9 0 0
v_max_non_paid_realizn 3841 3841 0 0.00 0 609 7 0 0
v_min_id_clo_ord 5759 5759 0 0.00 0 2331 9 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 3025 13 0 0
v_min_id_ord_sup 5593 5593 0 0.00 0 4409 9 0 0
v_min_non_paid_invoice 5959 5959 0 0.00 0 7957 27 0 0
v_min_non_paid_realizn 7259 7259 0 0.00 0 4989 17 0 0
v_our_firm 10412 10412 0 0.00 0 56 5 0 0
v_reserve_write_off 20478 20478 0 0.00 0 6744 11 0 0
x_lock_depdocs_on_canc_invoice 10728 10728 0 0.00 0 18422 134 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 11064 423 0 0
x_make_qstorno_1000_1200 5759 5759 0 0.00 2 32216 1041 0 0
x_make_qstorno_1200_2000 5599 5599 0 0.00 5 92984 2956 0 0
x_make_qstorno_2100_3300 474993 474993 0 0.00 0 49465 208 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 17-04-2022 10:38:59 to 17-04-2022 10:39:04.

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
08:07:20 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 14968 8192000000 9705744176 9999994880 787325232 8.768 100 93 187 0 818130752 7179120 23626400
08:09:46 22648 8192000000 9766312528 10074664960 827122944 9.170 100 88 176 1 859904144 11494608 21286592
08:11:26 11968 8192000000 9808131792 10129608704 869160336 9.592 100 77 151 2 912791680 26335600 17295744
08:12:49 9562 8192000000 9883605952 10220941312 930385680 10.198 100 87 172 1 1001004128 51522464 19095984
08:14:06 3480 8192000000 9930949008 10278830080 971544624 10.602 100 83 166 0 1042653936 53640272 17469040
08:15:13 1646 8192000000 9970222448 10326618112 997370416 10.853 100 94 184 2 1090238864 73993968 18874480
08:16:17 7015 8192000000 9981008704 10348941312 1027330336 11.143 100 71 139 1 1103226928 62117024 13779568
08:17:26 1123 8192000000 10013907984 10384818176 1049527408 11.356 100 92 177 1 1138274576 71150384 17596784
08:18:29 1009 8192000000 10042723440 10417418240 1060149168 11.458 100 92 171 1 1164065328 86697856 17218304
08:19:32 8280 8192000000 10038916816 10415243264 1067391904 11.527 100 89 161 5 1162933744 79253952 16287888
08:20:42 1250 8192000000 10031101456 10417131520 1072777984 11.579 100 73 128 2 1156499648 70083760 13637904
08:21:44 2147 8192000000 10029089200 10414776320 1076982992 11.619 100 79 147 0 1155201952 64046000 14172960
08:22:48 865 8192000000 10024416656 10411143168 1077041936 11.619 100 89 172 0 1148250560 53486704 17721920
08:23:50 835 8192000000 10056329520 10440822784 1086391472 11.708 100 87 168 1 1175994304 72932480 16670352
08:24:52 1257 8192000000 10060407024 10451259392 1092706672 11.768 100 95 181 0 1185516288 74138880 18670736
08:25:54 1253 8192000000 10058718400 10448248832 1094866272 11.789 100 94 183 1 1180634384 67386064 18382048
08:26:56 5055 8192000000 10070777088 10469482496 1098430496 11.823 100 81 162 0 1199778048 85629632 15717920
08:28:02 6040 8192000000 10034112656 10421547008 1085551472 11.700 99 76 148 2 1153130640 52113664 15465504
08:29:12 1269 8192000000 10020026320 10409725952 1088490688 11.728 100 86 164 0 1140207024 34406544 17309792
08:30:14 1072 8192000000 10033782208 10418974720 1064947648 11.504 99 95 176 0 1154123392 70915056 18260688
08:31:17 1111 8192000000 10017154000 10393464832 1052059696 11.380 100 96 185 0 1136956624 65844960 19051968
08:32:20 1182 8192000000 10003475920 10379726848 1028908656 11.158 99 92 180 0 1118100144 70055312 19136176
08:33:22 1073 8192000000 9990782992 10369093632 1039376688 11.259 99 80 155 4 1111105616 54440656 17288272
08:34:23 1845 8192000000 10028807344 10409500672 1045522384 11.318 100 77 150 1 1149690752 88687392 15480976
08:35:27 1060 8192000000 9991397216 10364059648 1040937360 11.274 100 98 183 1 1115869952 56079664 18852928
08:36:29 949 8192000000 10011839632 10392489984 1048098032 11.342 100 89 175 2 1135652992 68763936 18791024
08:37:33 3268 8192000000 9999410336 10387079168 1057048352 11.428 100 69 126 0 1122259952 53020752 12190848
08:38:42 3823 8192000000 10007368256 10389106688 1068186928 11.535 100 76 145 1 1125466368 42284752 14994688
08:39:59 3598 8192000000 10055011120 10440605696 1083970992 11.685 100 69 130 3 1175430576 79007296 12452288
08:41:07 1344 8192000000 10094747344 10481537024 1104034176 11.876 100 92 170 0 1208997088 86972928 17989984
08:42:11 1097 8192000000 10107440992 10497851392 1122743696 12.053 100 80 149 0 1226276672 88668736 14864240
08:43:19 5101 8192000000 10151575296 10552864768 1132868656 12.148 100 62 119 0 1268760096 122712448 13178992
08:44:32 2609 8192000000 10188361888 10582351872 1140431808 12.220 100 80 158 0 1296449552 138330032 17687712
08:45:41 983 8192000000 10214429632 10609160192 1148421552 12.295 100 89 167 3 1331420752 164184400 18814800
08:46:49 5953 8192000000 10237748192 10637737984 1155091952 12.357 100 71 134 0 1346182864 176688800 14402112
08:48:00 1702 8192000000 10299933984 10696482816 1172919648 12.524 100 95 182 0 1408934784 215943440 20071696
08:49:04 1264 8192000000 10326087248 10730627072 1172892864 12.524 100 86 166 2 1437445552 246928976 17623712
08:50:08 1169 8192000000 10378185760 10801467392 1178117952 12.573 100 97 186 1 1484363760 286100304 20145504
08:51:12 1221 8192000000 10356203024 10765393920 1184519664 12.632 100 84 164 0 1466760768 264386080 17855024
08:52:15 958 8192000000 10424728144 10837004288 1193305088 12.714 100 98 191 0 1533447872 318711184 21431600
08:53:18 2193 8192000000 10427759968 10857627648 1192240400 12.704 100 94 182 1 1535573072 321148928 22183744
08:54:26 976 8192000000 10416296160 10835714048 1179249872 12.583 100 92 183 0 1527504560 326425168 21829520
08:55:31 1156 8192000000 10445814704 10856333312 1194582496 12.726 100 94 181 2 1547842192 332563680 20696016
08:56:37 1570 8192000000 10501785584 10919579648 1196702288 12.746 100 84 162 2 1606601056 390759920 19138848
08:57:40 763 8192000000 10482435264 10894217216 1162967792 12.431 100 96 191 1 1588640912 402545888 23127232
08:58:42 1144 8192000000 10560056768 10965663744 1189073408 12.675 100 97 189 1 1665478256 454206064 22198784
08:59:46 885 8192000000 10558716672 11009925120 1186624192 12.652 100 97 188 1 1662395824 453281520 22490112
09:00:49 791 8192000000 10612185472 11071045632 1191535248 12.698 100 99 194 1 1720022976 504659280 23828448
09:01:53 775 8192000000 10570038128 11041886208 1185839472 12.645 100 92 183 0 1674794368 465596640 23358256
09:02:55 2068 8192000000 10576923952 11028058112 1157419536 12.379 99 70 138 0 1677131584 501128944 18583104
09:03:59 4024 8192000000 10578711008 11045859328 1173849472 12.533 100 55 110 0 1680527120 491954192 14723456
09:05:09 22421 8192000000 10564874080 11090862080 1174841536 12.542 100 42 82 1 1665463008 479563392 11058080
09:06:52 17769 8192000000 10498610032 11008888832 1142409712 12.238 99 37 74 0 1603244960 450616992 10218256
09:08:12 40316 8192000000 10328680848 10800979968 1156482896 12.370 100 26 52 0 1443116720 280186000 6447824
09:10:25 19226 8192000000 10216929664 10672926720 1138793920 12.204 99 19 38 0 1326280272 182887264 4599088
09:11:46 28469 8192000000 10146933968 10571165696 1130665680 12.128 98 54 98 2 1242257600 101835344 9756576
09:13:15 22361 8192000000 10187751088 10628124672 1149618160 12.306 100 77 139 1 1299268944 133660512 15990272
09:14:53 77226 8192000000 10092682176 10567647232 1135699296 12.175 99 11 22 0 1213288032 75034304 2554432
09:17:11 154791 8192000000 10103875760 10562928640 1110609984 11.938 97 11 22 0 1147714048 34804240 2299824
09:20:48 48095 8192000000 10065057360 10493308928 1127139072 12.094 99 15 28 1 1161396960 31437584 2820304
09:22:36 72674 8192000000 10105952704 10560221184 1142162992 12.236 100 15 28 1 1192835008 47357920 3314096
09:24:56 143466 8192000000 10066053184 10553417728 1092119952 11.763 95 9 18 1 1152566864 58757344 1689568
09:28:20 133377 8192000000 10049339280 10480902144 1080174672 11.649 95 23 43 3 1121882832 36937296 4770864
09:31:35 69668 8192000000 10085179984 10526908416 1116434672 11.993 99 23 45 0 1163310704 42307424 4568608
09:33:49 18746 8192000000 10061913520 10490687488 1130714064 12.128 100 52 97 1 1157701984 15508000 11479920
09:35:18 50727 8192000000 10105405856 10541285376 1130535104 12.126 99 35 68 1 1180798928 43243520 7020304
09:37:11 44704 8192000000 10064875728 10532372480 1109664544 11.929 98 38 72 1 1187239296 70646320 6928432
09:38:57 45550 8192000000 10063789424 10515046400 1123842464 12.063 98 15 30 0 1158727984 31760416 3125104
09:40:44 60797 8192000000 10086519200 10555932672 1117877888 12.007 98 12 24 0 1166434816 46149984 2406944
09:42:55 58178 8192000000 10058788144 10561581056 1098470032 11.823 97 16 32 2 1201142640 99214304 3458304
09:44:54 74966 8192000000 10072869120 10532855808 1101059344 11.848 97 14 28 0 1142684096 38239232 3385520
09:47:30 38144 8192000000 10181296336 10629242880 1123030800 12.056 98 31 62 1 1238779776 109511104 6237872
09:49:09 56069 8192000000 10156939856 10614464512 1085423328 11.699 95 29 58 1 1219098784 127144528 6530928
09:51:10 61786 8192000000 10126127232 10617102336 1121916080 12.045 100 23 46 0 1237466688 109915200 5635408
09:53:18 38941 8192000000 10065577184 10502045696 1093329696 11.774 96 31 62 0 1155011920 55179616 6502608
09:54:58 42306 8192000000 10141547408 10583056384 1118224256 12.010 98 45 90 1 1213034160 85887600 8922304
09:58:22 91246 8192000000 10092146528 10537213952 1103511744 11.871 97 52 103 0 1169795536 55513984 10769808
10:00:56 45807 8192000000 10093102096 10518249472 1120172624 12.029 99 40 79 0 1182500528 53755104 8572800
10:02:43 58694 8192000000 10063439712 10544431104 1113713584 11.968 99 10 20 1 1180997696 64984288 2299824
10:04:42 71143 8192000000 10063561232 10531713024 1098038240 11.819 97 18 36 1 1164247472 61806112 4403120
10:06:59 80267 8192000000 10062740096 10565480448 1095398816 11.794 97 14 29 0 1147774752 49303248 3072688
10:09:24 70623 8192000000 10057951648 10518392832 1115481200 11.984 98 30 60 1 1161968640 40477408 6010032
10:11:36 14310 8192000000 10070261744 10508472320 1127932880 12.102 100 39 78 1 1172158320 36226176 7999264
10:13:04 22799 8192000000 10084140576 10517442560 1113418464 11.965 98 37 74 0 1174151440 53384112 7348864
10:14:28 43731 8192000000 10102081152 10540380160 1075624080 11.606 95 47 94 1 1178700240 93177984 9898176
10:16:14 20166 8192000000 10094705680 10537684992 1130019904 12.122 100 33 66 0 1176626896 39568720 7038272
10:17:43 40374 8192000000 10082590976 10527752192 1098009008 11.819 97 32 62 1 1172757216 68825744 5922464
10:19:25 68100 8192000000 10102593312 10568957952 1129590208 12.117 99 33 65 1 1215239920 78668960 6980752
10:22:01 83205 8192000000 10130057024 10626244608 1117410224 12.003 98 11 22 2 1203817568 84020688 2386656
10:24:28 93171 8192000000 10072864912 10531700736 1133292832 12.152 99 18 36 1 1190608656 53484752 3831072
10:28:08 86371 8192000000 10074936640 10530365440 1108855216 11.922 97 10 20 1 1150632576 39675744 2101616
10:30:35 59788 8192000000 10088297072 10551119872 1128390144 12.106 99 10 20 0 1178309856 47642304 2277408
10:32:44 41242 8192000000 10090852736 10552811520 1107630320 11.910 98 14 26 3 1196306752 85709392 2967040
10:34:26 20652 8192000000 10071248752 10518765568 1106423728 11.899 98 30 59 3 1155416320 42837632 6154960
10:35:58 31380 8192000000 10109871456 10539372544 1098604496 11.824 97 39 76 3 1160809440 54074432 8130512

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-04-2022 10:39:05 to 17-04-2022 10:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-04-2022 10:39:22 to 17-04-2022 10:39:24.

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          222803
MON$OLDEST_ACTIVE               225968
MON$OLDEST_SNAPSHOT             225968
MON$NEXT_TRANSACTION            225968
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                       2074720
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:227: 
:228: mscs-test-161.budzdorov.ru    Sun Apr 17 10:37:18 2022
:229:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:230: 
:231: 
:232: mscs-test-161.budzdorov.ru    Sun Apr 17 10:37:22 2022
:233:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:234: 
:235: 
:236: mscs-test-161.budzdorov.ru    Sun Apr 17 10:37:26 2022
:237:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:238: 
:239: 
:240: mscs-test-161.budzdorov.ru    Sun Apr 17 10:37:26 2022
:241:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:242: 
--- end of diff output ---

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

17.04.22 10:39:25. 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-04-17 10:39:28 No severe PSQL-related problems occured
17.04.22 10:39:29. End of report.