Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.01.2022 10: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 16462396 kB
MemAvailable 31261340 kB
Buffers 0 kB
Cached 15202292 kB
SwapCached 2300 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.1
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-01-13 10:37:12
DTS_END                         2022-01-13 10:37:12.4460
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/45452
TRN_ID                          213678
ATT_ID                          567
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: 8562.67 26669 1027521
customer order: creation 161.88 447 19425
customer order: refuse 13.75 399 1650
order to supplier: creation 42.83 1373 5139
order to supplier: removal 3.17 173516 380
invoice (draft): creation 43.74 3185 5249
invoice (draft): removal 48.02 939 5762
invoice accept: apply 39.79 27177 4775
invoice accept: cancel 43.53 1260 5224
customer reserve: creation 3630.28 264 435634
customer reserve: removal 4252.25 104 510270
realization accept: apply 79.81 256 9577
realization accept: cancel 81.86 163 9823
payment from customer: creation 55.87 166 6704
payment from customer: removal 5.44 124 653
payment to supplier: creation 44.88 173 5386
payment to supplier: removal 3.23 108 387
service: total inventory turnovers 4.11 3604 493
service: total monetary turnovers 7.26 380 871
service: aggregate perf. data 0.95 15217 114
service: refresh index statistics 0.04 304525 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-01-2022 10:38:19 to 13-01-2022 10:38:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8112
TEST_TIME 2 6838
TEST_TIME 3 6969
TEST_TIME 4 6986
TEST_TIME 5 6802
TEST_TIME 6 6563
TEST_TIME 7 6255
TEST_TIME 8 6137
TEST_TIME 9 6277
TEST_TIME 10 6253
TEST_TIME 11 6176
TEST_TIME 12 6523
TEST_TIME 13 6526
TEST_TIME 14 6617
TEST_TIME 15 6758
TEST_TIME 16 6964
TEST_TIME 17 7195
TEST_TIME 18 7432
TEST_TIME 19 7410
TEST_TIME 20 7791
TEST_TIME 21 7658
TEST_TIME 22 8035
TEST_TIME 23 8396
TEST_TIME 24 9087
TEST_TIME 25 9182
TEST_TIME 26 9323
TEST_TIME 27 9497
TEST_TIME 28 9739
TEST_TIME 29 10472
TEST_TIME 30 10818
TEST_TIME 31 10681
TEST_TIME 32 10459
TEST_TIME 33 10229
TEST_TIME 34 10132
TEST_TIME 35 9956
TEST_TIME 36 9799
TEST_TIME 37 9765
TEST_TIME 38 9731
TEST_TIME 39 9615
TEST_TIME 40 9499
TEST_TIME 41 9376
TEST_TIME 42 9202
TEST_TIME 43 9164
TEST_TIME 44 9024
TEST_TIME 45 9008
TEST_TIME 46 8858
TEST_TIME 47 8847
TEST_TIME 48 9065
TEST_TIME 49 8959
TEST_TIME 50 8953
TEST_TIME 51 9007
TEST_TIME 52 8935
TEST_TIME 53 8854
TEST_TIME 54 8864
TEST_TIME 55 8832
TEST_TIME 56 8903
TEST_TIME 57 8828
TEST_TIME 58 8795
TEST_TIME 59 8950
TEST_TIME 60 8926
TEST_TIME 61 8921
TEST_TIME 62 8891
TEST_TIME 63 8985
TEST_TIME 64 8948
TEST_TIME 65 8906
TEST_TIME 66 8792
TEST_TIME 67 8700
TEST_TIME 68 8712
TEST_TIME 69 8790
TEST_TIME 70 8715
TEST_TIME 71 8668
TEST_TIME 72 8633
TEST_TIME 73 8619
TEST_TIME 74 8624
TEST_TIME 75 8595
TEST_TIME 76 8587
TEST_TIME 77 8543
TEST_TIME 78 8625
TEST_TIME 79 8594
TEST_TIME 80 8563
TEST_TIME 81 8531
TEST_TIME 82 8534
TEST_TIME 83 8490
TEST_TIME 84 8513
TEST_TIME 85 8495
TEST_TIME 86 8697
TEST_TIME 87 8638
TEST_TIME 88 8870
TEST_TIME 89 8817
TEST_TIME 90 8800
TEST_TIME 91 8738
TEST_TIME 92 8686
TEST_TIME 93 8792
TEST_TIME 94 8831
TEST_TIME 95 8809
TEST_TIME 96 8793
TEST_TIME 97 8786
TEST_TIME 98 8774
TEST_TIME 99 8852
TEST_TIME 100 8859
TEST_TIME 101 8877
TEST_TIME 102 8852
TEST_TIME 103 8788
TEST_TIME 104 8781
TEST_TIME 105 8720
TEST_TIME 106 8709
TEST_TIME 107 8697
TEST_TIME 108 8710
TEST_TIME 109 8690
TEST_TIME 110 8652
TEST_TIME 111 8600
TEST_TIME 112 8578
TEST_TIME 113 8596
TEST_TIME 114 8654
TEST_TIME 115 8635
TEST_TIME 116 8633
TEST_TIME 117 8646
TEST_TIME 118 8646
TEST_TIME 119 8654
TEST_TIME 120 8595

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-01-2022 10:38:23 to 13-01-2022 10:38:26.

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 1429781 1429781 0 0.00 0 16961 13 0 0
doc_list_biud 1429767 1429767 0 0.00 0 80603 43 0 0
sp_add_invoice_to_stock 4934 4775 159 3.22 61 240812 27177 0 159
sp_cancel_adding_invoice 5224 5224 0 0.00 7 99358 1260 0 0
sp_cancel_client_order 1650 1650 0 0.00 5 11761 399 0 0
sp_cancel_customer_reserve 510270 510270 0 0.00 1 46740 104 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 3 7518 125 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 2207 108 0 0
sp_cancel_supplier_invoice 5775 5762 13 0.22 4 80608 939 0 13
sp_cancel_supplier_order 380 380 0 0.00 22 1418988 173517 0 0
sp_cancel_write_off 9823 9823 0 0.00 2 18409 164 0 0
sp_client_order 19425 19425 0 0.00 5 23001 447 0 0
sp_customer_reserve 435634 435634 0 0.00 1 65073 265 0 0
sp_fill_shopping_cart 29796 29796 0 0.00 1 38765 84 0 0
sp_fill_shopping_cart_clo_res 435594 435594 0 0.00 0 15129 21 0 0
sp_get_clo_for_invoice 4725 4725 0 0.00 0 33648 374 0 0
sp_kill_cost_storno 16087 16087 0 0.00 0 8416 71 0 0
sp_kill_qstorno_ret_qs2qd 1036505 1036505 0 0.00 0 80602 46 0 0
sp_kill_qty_storno 547411 547411 0 0.00 0 80603 104 0 0
sp_lock_selected_doc 19799 19799 0 0.00 0 6967 7 0 0
sp_make_cost_storno 26392 26392 0 0.00 0 13390 74 0 0
sp_multiply_rows_for_pdistr 26392 26392 0 0.00 0 6098 37 0 0
sp_multiply_rows_for_qdistr 19423 19423 0 0.00 0 10505 178 0 0
sp_pay_from_customer 6704 6704 0 0.00 5 10271 167 0 0
sp_pay_to_supplier 5386 5386 0 0.00 6 11705 174 0 0
sp_payment_common 12090 12090 0 0.00 2 7499 43 0 0
sp_qd_handle_on_cancel_clo 1650 1650 0 0.00 0 11747 339 0 0
sp_qd_handle_on_invoice_upd_sts 9949 9949 0 0.00 0 21826 513 0 0
sp_qd_handle_on_reserve_upd_sts 9577 9577 0 0.00 0 19700 53 0 0
sp_reserve_write_off 9577 9577 0 0.00 8 19896 256 0 0
sp_supplier_invoice 5249 5249 0 0.00 9 87202 3185 0 0
sp_supplier_order 5139 5139 0 0.00 7 51582 1373 0 0
srv_aggregate_perf_data 114 114 0 0.00 2419 160607 15218 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 794 117841 41193 0 0
srv_find_qd_qs_mism 982049 982049 0 0.00 0 16960 10 0 0
srv_make_invnt_saldo 493 493 0 0.00 42 85279 3605 0 0
srv_make_money_saldo 871 871 0 0.00 1 16284 380 0 0
srv_recalc_idx_stat 5 5 0 0.00 97192 440751 304526 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4780 4780 0 0.00 1 11285 864 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 6940 409 0 0
t$perf-norm:sp_cancel_client_order 1650 1650 0 0.00 0 4912 31 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 795 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 1343 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 598 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 3697 46 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 67325 5911 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 1887 20 0 0
t$perf-norm:sp_client_order 19425 19425 0 0.00 0 11807 36 0 0
t$perf-norm:sp_customer_reserve 910 910 0 0.00 0 458 12 0 0
t$perf-norm:sp_pay_from_customer 6704 6704 0 0.00 0 13357 21 0 0
t$perf-norm:sp_pay_to_supplier 5386 5386 0 0.00 0 4918 17 0 0
t$perf-norm:sp_reserve_write_off 9577 9577 0 0.00 0 13359 25 0 0
t$perf-norm:sp_supplier_invoice 5249 5249 0 0.00 0 3258 29 0 0
t$perf-norm:sp_supplier_order 5139 5139 0 0.00 0 6294 33 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 668 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 744 17 0 0
t$perf-norm:srv_make_invnt_saldo 493 493 0 0.00 0 534 12 0 0
t$perf-norm:srv_make_money_saldo 871 871 0 0.00 0 549 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 184 40 0 0
v_add_invoice_to_stock 9760 9442 318 3.25 0 3914 24 0 318
v_all_customers 35592 35592 0 0.00 0 64 3 0 0
v_all_suppliers 28560 28560 0 0.00 0 76 4 0 0
v_all_wares 38842 38842 0 0.00 0 58 4 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 1085 12 0 0
v_cancel_client_order 3300 3300 0 0.00 0 1256 8 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 1634 11 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 2376 11 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 2164 13 0 0
v_cancel_supplier_invoice 824 798 26 3.15 0 2942 26 0 26
v_cancel_supplier_order 760 760 0 0.00 0 851 9 0 0
v_cancel_write_off 1940 1940 0 0.00 0 1690 22 0 0
v_max_id_clo_ord 5135 5135 0 0.00 0 6482 6 0 0
v_max_id_clo_res 910 910 0 0.00 0 7333 24 0 0
v_max_id_ord_sup 5236 5236 0 0.00 0 6247 10 0 0
v_max_non_paid_invoice 1477 1477 0 0.00 0 62 7 0 0
v_max_non_paid_realizn 3542 3542 0 0.00 0 4063 8 0 0
v_min_id_clo_ord 5135 5135 0 0.00 0 1474 7 0 0
v_min_id_clo_res 910 910 0 0.00 0 6541 29 0 0
v_min_id_ord_sup 5236 5236 0 0.00 0 5884 11 0 0
v_min_non_paid_invoice 5386 5386 0 0.00 0 4889 13 0 0
v_min_non_paid_realizn 6704 6704 0 0.00 0 5286 19 0 0
v_our_firm 9572 9572 0 0.00 0 54 4 0 0
v_reserve_write_off 19152 19152 0 0.00 0 2954 10 0 0
x_lock_depdocs_on_canc_invoice 10587 10587 0 0.00 0 10509 118 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 3582 364 0 0
x_make_qstorno_1000_1200 5139 5139 0 0.00 3 51568 1216 0 0
x_make_qstorno_1200_2000 5249 5249 0 0.00 3 87188 2941 0 0
x_make_qstorno_2100_3300 435634 435634 0 0.00 0 64972 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2022 10:38:27 to 13-01-2022 10:38:31.

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:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:17 32483 8192000000 9718546096 10018467840 799472400 8.891 100 86 172 0 828194192 7630304 21091488
08:09:54 9787 8192000000 9767055616 10075021312 843260528 9.332 100 88 177 0 875878656 12026176 20591952
08:11:14 18394 8192000000 9811401200 10128244736 875613776 9.656 100 87 175 1 914950896 19616400 19720720
08:12:48 9305 8192000000 9874302416 10209378304 918481472 10.081 100 79 158 0 984444896 48928288 17035136
08:14:00 4685 8192000000 9917740304 10264383488 960841328 10.497 100 78 156 0 1037842752 60561664 16439760
08:15:07 2898 8192000000 9982827488 10336079872 996581264 10.845 100 92 177 0 1097942864 82726448 18635152
08:16:14 1428 8192000000 9989845520 10347978752 1016282432 11.036 100 89 167 2 1103682432 70296176 17103824
08:17:18 1010 8192000000 10008388928 10377461760 1043435056 11.298 100 94 185 0 1134422384 72197888 18789440
08:18:20 1320 8192000000 10027569200 10397319168 1055847696 11.417 100 92 175 1 1150220448 77583632 16789120
08:19:24 9555 8192000000 10036558128 10412126208 1064733616 11.502 100 82 155 2 1156711312 77794272 14183424
08:20:34 790 8192000000 10045464864 10423427072 1068960144 11.542 100 88 168 1 1165133440 79087984 17085312
08:21:36 5888 8192000000 10031502144 10417864704 1071003488 11.562 100 75 144 0 1144597728 60288832 13305408
08:22:43 1325 8192000000 10035372560 10418319360 1074853568 11.598 100 85 163 2 1153476304 63804560 14818176
08:23:45 1216 8192000000 10049523936 10430783488 1082857584 11.675 100 100 195 1 1172842256 69870928 20113744
08:24:47 1559 8192000000 10050768688 10431672320 1084278240 11.688 100 93 173 3 1173348176 72716608 16353328
08:25:49 1302 8192000000 10085513840 10474700800 1091693504 11.759 100 99 183 4 1211103360 100217776 19192080
08:26:52 1360 8192000000 10062847536 10450317312 1082388896 11.670 100 94 180 1 1180520832 79829872 18302064
08:27:54 1174 8192000000 10041711440 10428403712 1087038928 11.714 100 84 161 3 1163345472 61075040 15231504
08:28:56 1128 8192000000 10052464464 10437144576 1081993504 11.666 100 86 162 1 1174334736 76602688 15738544
08:29:57 951 8192000000 10021814080 10409517056 1073729824 11.588 100 81 155 1 1143142272 53917008 15495440
08:30:59 1273 8192000000 10053477760 10434916352 1076390336 11.613 100 97 188 0 1175550448 78928592 20231520
08:32:02 1223 8192000000 9994410144 10367234048 1050437136 11.365 99 91 174 2 1113746976 45248176 18061664
08:33:03 1109 8192000000 10007292480 10385682432 1041906224 11.283 100 85 165 1 1127552416 69236384 16409808
08:34:05 1390 8192000000 9977034688 10346692608 1041100560 11.275 100 88 174 1 1102611120 41956848 19553712
08:35:08 3092 8192000000 9999413072 10378178560 1044652688 11.309 100 79 156 3 1126071952 65878656 15540608
08:36:12 1312 8192000000 9979016720 10346397696 1025808608 11.128 100 96 186 0 1099562384 55819712 17934064
08:37:15 21840 8192000000 10012899488 10389708800 1048941312 11.351 100 68 130 1 1139716672 78154448 12620912
08:38:50 1937 8192000000 10017905440 10397384704 1066701472 11.521 100 77 140 2 1137892880 57253264 13938144
08:39:56 1192 8192000000 10058356528 10438561792 1080886688 11.656 100 98 196 2 1179486992 75744816 22855488
08:40:59 865 8192000000 10075058768 10455212032 1093638608 11.777 100 95 183 1 1192403616 78345216 20419792
08:42:03 6888 8192000000 10128259040 10525089792 1104637072 11.882 100 70 137 1 1247715232 128537056 14541104
08:43:17 10516 8192000000 10106656448 10509877248 1113476448 11.965 100 48 90 0 1229742624 106295328 9970848
08:44:32 1934 8192000000 10159739232 10549256192 1124714336 12.072 100 83 165 1 1276270432 133232144 18323952
08:45:39 1072 8192000000 10198037488 10588303360 1139176400 12.208 100 94 178 2 1316489984 156391760 20921824
08:46:44 1655 8192000000 10243298480 10634895360 1150697520 12.316 100 91 178 2 1353372400 183686544 18988336
08:47:50 1842 8192000000 10276644240 10675097600 1157501904 12.380 100 92 176 0 1392266160 213969008 20795248
08:48:56 5178 8192000000 10316078480 10729762816 1158479376 12.389 100 78 154 3 1430459536 252603632 19376528
08:50:07 1757 8192000000 10307459152 10711306240 1161922240 12.421 100 92 173 3 1415015408 232135216 20957952
08:51:12 1995 8192000000 10357666416 10762801152 1173541984 12.530 100 95 179 2 1467752864 274597120 19613760
08:52:19 873 8192000000 10406706544 10806456320 1180925216 12.599 100 93 184 1 1513376592 310639280 21812096
08:53:23 1970 8192000000 10416035760 10840838144 1181086288 12.600 100 91 181 1 1527326160 323639376 22600496
08:54:28 1866 8192000000 10451920480 10878480384 1190324688 12.686 100 96 193 0 1554411616 342291792 21795136
08:55:32 829 8192000000 10441254784 10863263744 1182075536 12.610 100 94 182 2 1549308720 344905632 22327552
08:56:38 956 8192000000 10453941680 10879660032 1183935584 12.627 100 98 192 0 1557470720 351220672 22314464
08:57:42 1137 8192000000 10452679456 10869612544 1169397712 12.491 100 90 170 0 1559067456 369266128 20403616
08:58:45 792 8192000000 10465234432 10912141312 1177147248 12.564 100 98 193 2 1569944912 369804800 22992864
08:59:48 2532 8192000000 10509723760 10963505152 1185490800 12.641 100 82 163 2 1620596896 415186080 19920016
09:00:54 16819 8192000000 10392819936 10913370112 1176669888 12.559 100 47 94 0 1502112784 312605744 12837152
09:02:25 11006 8192000000 10388258592 10824765440 1166687568 12.466 100 46 88 0 1492228416 314196416 11344432
09:03:37 27499 8192000000 10351503328 10837237760 1160084304 12.404 100 26 52 0 1469164944 302267984 6812656
09:05:08 39128 8192000000 10293146736 10759737344 1143893120 12.252 100 19 38 0 1391916944 242484304 5539520
09:06:47 75726 8192000000 10100542960 10562363392 1114939632 11.979 97 20 40 1 1255848592 136163856 4745104
09:09:09 75076 8192000000 10050488944 10495348736 1091541856 11.757 96 25 50 1 1128890736 31893504 5455376
09:11:27 75699 8192000000 10095893280 10562457600 1130559392 12.127 99 17 34 0 1198661152 64543424 3558336
09:13:43 87239 8192000000 10049547136 10530897920 1108319696 11.917 97 25 50 0 1160388720 46894000 5175024
09:16:15 32705 8192000000 10078497632 10535321600 1143253632 12.246 100 26 47 1 1190185696 42723184 4208880
09:17:48 111763 8192000000 10124809104 10583617536 1129517792 12.117 98 15 30 0 1205066112 72145008 3403312
09:20:41 55458 8192000000 10129997728 10581250048 1127715568 12.100 98 31 62 1 1227755888 93944144 6096176
09:22:38 74786 8192000000 10153638592 10607972352 1107917584 11.913 98 19 38 0 1214489360 102124992 4446784
09:25:18 73833 8192000000 10124325152 10614808576 1130149824 12.123 100 15 30 1 1235414720 102119328 3145568
09:28:16 87980 8192000000 10048655888 10506215424 1122977184 12.055 99 10 18 1 1183345280 58101184 2266912
09:30:51 17451 8192000000 10078379648 10505392128 1126764480 12.091 99 62 119 1 1174273312 35282112 12226720
09:32:12 29720 8192000000 10104659696 10558201856 1142531328 12.239 100 34 67 1 1201872720 52297872 7043520
09:33:45 58774 8192000000 10099044416 10520858624 1121674064 12.043 98 49 95 0 1192744944 62224368 8846512
09:35:52 88040 8192000000 10075248848 10566873088 1081304048 11.660 95 24 47 0 1171209120 85030704 4874368
09:38:20 95088 8192000000 10113367456 10556043264 1102875776 11.865 97 36 72 0 1171532656 61521984 7134896
09:40:56 63554 8192000000 10095305296 10522619904 1116367840 11.993 99 26 52 1 1166202048 44447792 5386416
09:43:10 117841 8192000000 10156243792 10600071168 1139983584 12.215 100 31 60 3 1256431232 110330080 6117568
09:46:10 43527 8192000000 10057086288 10564341760 1124076768 12.065 99 10 20 0 1164344400 38195904 2071728
09:47:54 59360 8192000000 10065740432 10526392320 1133266656 12.152 100 8 16 0 1167811936 33267152 1278128
09:49:54 63589 8192000000 10085230448 10518962176 1110691776 11.939 97 21 42 0 1150756544 36017568 4047200
09:52:21 28543 8192000000 10125238256 10567163904 1139062736 12.207 99 33 64 1 1217418624 70897536 7458352
09:53:56 37218 8192000000 10164492176 10599256064 1124482800 12.069 97 40 78 1 1245066176 111374352 9209024
09:55:33 39227 8192000000 10165485392 10622935040 1135985600 12.178 98 35 71 1 1256345296 111975072 8384624
09:57:14 9410 8192000000 10212421920 10638487552 1143050688 12.244 99 43 84 0 1287497056 135245808 9200560
09:58:23 18451 8192000000 10246926400 10674868224 1149525296 12.305 99 49 97 1 1338672320 178464960 10682064
09:59:44 22249 8192000000 10280503184 10726707200 1163275168 12.434 100 47 92 0 1369758032 196082816 10400048
10:01:08 20247 8192000000 10349861232 10776649728 1150251728 12.312 99 51 102 2 1434775296 273162112 11361456
10:02:40 29194 8192000000 10322052832 10779680768 1142011072 12.234 99 25 50 2 1432688992 284309968 6367952
10:04:14 73259 8192000000 10193359600 10667970560 1118772032 12.015 99 23 46 1 1296030752 171661712 5597008
10:06:44 53321 8192000000 10074270992 10528595968 1112668176 11.958 98 25 50 1 1142379248 24772592 4938480
10:08:38 82286 8192000000 10140662016 10568888320 1107790960 11.911 97 47 93 1 1195403648 77383632 10229056
10:11:13 77606 8192000000 10055900176 10512011264 1052566784 11.385 93 14 28 1 1111029456 55823088 2639584
10:13:31 92147 8192000000 10116665152 10570657792 1060554528 11.462 93 24 48 0 1116248096 51212464 4481104
10:16:03 80263 8192000000 10094724784 10537943040 1125433040 12.078 99 14 28 0 1192114896 63725872 2955984
10:18:30 48376 8192000000 10084452688 10529271808 1111239408 11.944 97 32 64 1 1172376880 54830064 6307408
10:21:00 93017 8192000000 10085778800 10515599360 1101478032 11.852 97 39 78 3 1141384720 32183456 7723232
10:23:57 57711 8192000000 10076583264 10526326784 1104302800 11.878 98 34 66 1 1181886640 70470288 7113552
10:25:57 79521 8192000000 10103938480 10545115136 1115114000 11.981 98 25 50 0 1179496896 58981024 5401872
10:28:21 51274 8192000000 10143800224 10588311552 1136579248 12.183 100 9 18 0 1234385456 95563872 2242336
10:30:30 83454 8192000000 10057397552 10543706112 1104424000 11.880 97 17 34 1 1171368208 63334848 3609360
10:33:13 47477 8192000000 10096948624 10536366080 1131309984 12.134 100 27 54 1 1204079392 67649408 5120000
10:35:01 84171 8192000000 10099298400 10546556928 1094863872 11.789 96 18 34 0 1156725904 58608112 3253920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-01-2022 10:38:33 to 13-01-2022 10:38:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-01-2022 10:38:50 to 13-01-2022 10:38:52.

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          211638
MON$OLDEST_ACTIVE               213831
MON$OLDEST_SNAPSHOT             213831
MON$NEXT_TRANSACTION            213831
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                       2033696
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:419: 
:420: mscs-test-161.budzdorov.ru    Thu Jan 13 10:37:19 2022
:421:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:422: 
:423: 
:424: mscs-test-161.budzdorov.ru    Thu Jan 13 10:37:24 2022
:425:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:426: 
:427: 
:428: mscs-test-161.budzdorov.ru    Thu Jan 13 10:37:24 2022
:429:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:430: 
--- end of diff output ---

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

13.01.22 10:38:53. 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-01-13 10:38:55 No severe PSQL-related problems occured
13.01.22 10:38:57. End of report.