Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.11.2021 13:39
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 16742400 kB
MemAvailable 31611296 kB
Buffers 0 kB
Cached 14983312 kB
SwapCached 0 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 2021-11-24 13:37:10
DTS_END                         2021-11-24 13:37:10.2750
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/58896
TRN_ID                          240689
ATT_ID                          605
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: 9193.38 21586 1103206
customer order: creation 177.26 442 21271
customer order: refuse 14.95 329 1794
order to supplier: creation 47.87 1377 5744
order to supplier: removal 3.39 107083 407
invoice (draft): creation 46.89 3407 5627
invoice (draft): removal 51.57 990 6188
invoice accept: apply 43.25 18233 5190
invoice accept: cancel 46.27 780 5552
customer reserve: creation 3923.91 183 470869
customer reserve: removal 4542.96 62 545155
realization accept: apply 86.58 181 10390
realization accept: cancel 75.63 132 9076
payment from customer: creation 60.73 106 7288
payment from customer: removal 5.87 97 704
payment to supplier: creation 50.75 123 6090
payment to supplier: removal 3.78 78 453
service: total inventory turnovers 4.01 3326 481
service: total monetary turnovers 6.69 390 803
service: aggregate perf. data 0.99 12944 119
service: refresh index statistics 0.04 281464 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-11-2021 13:39:48 to 24-11-2021 13:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4672
TEST_TIME 2 4170
TEST_TIME 3 5062
TEST_TIME 4 4873
TEST_TIME 5 5246
TEST_TIME 6 5017
TEST_TIME 7 5863
TEST_TIME 8 6081
TEST_TIME 9 5745
TEST_TIME 10 5841
TEST_TIME 11 5894
TEST_TIME 12 6318
TEST_TIME 13 6490
TEST_TIME 14 7273
TEST_TIME 15 7371
TEST_TIME 16 7473
TEST_TIME 17 7659
TEST_TIME 18 8209
TEST_TIME 19 8814
TEST_TIME 20 9008
TEST_TIME 21 9048
TEST_TIME 22 9351
TEST_TIME 23 10464
TEST_TIME 24 11271
TEST_TIME 25 11058
TEST_TIME 26 11367
TEST_TIME 27 11243
TEST_TIME 28 11120
TEST_TIME 29 10849
TEST_TIME 30 10642
TEST_TIME 31 10583
TEST_TIME 32 10465
TEST_TIME 33 10264
TEST_TIME 34 10163
TEST_TIME 35 10224
TEST_TIME 36 10243
TEST_TIME 37 10514
TEST_TIME 38 10367
TEST_TIME 39 10215
TEST_TIME 40 10128
TEST_TIME 41 10087
TEST_TIME 42 10067
TEST_TIME 43 10270
TEST_TIME 44 10333
TEST_TIME 45 10297
TEST_TIME 46 10268
TEST_TIME 47 10250
TEST_TIME 48 10335
TEST_TIME 49 10272
TEST_TIME 50 10285
TEST_TIME 51 10306
TEST_TIME 52 10288
TEST_TIME 53 10240
TEST_TIME 54 10356
TEST_TIME 55 10214
TEST_TIME 56 10119
TEST_TIME 57 10086
TEST_TIME 58 9990
TEST_TIME 59 9961
TEST_TIME 60 9958
TEST_TIME 61 9859
TEST_TIME 62 9827
TEST_TIME 63 9782
TEST_TIME 64 9714
TEST_TIME 65 9640
TEST_TIME 66 9608
TEST_TIME 67 9591
TEST_TIME 68 9590
TEST_TIME 69 9630
TEST_TIME 70 9679
TEST_TIME 71 9660
TEST_TIME 72 9683
TEST_TIME 73 9709
TEST_TIME 74 9706
TEST_TIME 75 9695
TEST_TIME 76 9751
TEST_TIME 77 9774
TEST_TIME 78 9770
TEST_TIME 79 9801
TEST_TIME 80 9759
TEST_TIME 81 9658
TEST_TIME 82 9722
TEST_TIME 83 9648
TEST_TIME 84 9601
TEST_TIME 85 9531
TEST_TIME 86 9469
TEST_TIME 87 9482
TEST_TIME 88 9445
TEST_TIME 89 9437
TEST_TIME 90 9490
TEST_TIME 91 9492
TEST_TIME 92 9452
TEST_TIME 93 9437
TEST_TIME 94 9466
TEST_TIME 95 9632
TEST_TIME 96 9599
TEST_TIME 97 9566
TEST_TIME 98 9605
TEST_TIME 99 9563
TEST_TIME 100 9498
TEST_TIME 101 9454
TEST_TIME 102 9433
TEST_TIME 103 9365
TEST_TIME 104 9341
TEST_TIME 105 9322
TEST_TIME 106 9324
TEST_TIME 107 9289
TEST_TIME 108 9312
TEST_TIME 109 9300
TEST_TIME 110 9302
TEST_TIME 111 9297
TEST_TIME 112 9300
TEST_TIME 113 9351
TEST_TIME 114 9350
TEST_TIME 115 9375
TEST_TIME 116 9378
TEST_TIME 117 9332
TEST_TIME 118 9258
TEST_TIME 119 9241
TEST_TIME 120 9196

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-11-2021 13:39:51 to 24-11-2021 13:39:55.

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 1539451 1539451 0 0.00 0 34199 7 0 0
doc_list_biud 1539443 1539443 0 0.00 0 65790 29 0 0
sp_add_invoice_to_stock 5315 5190 125 2.35 30 175489 18234 0 125
sp_cancel_adding_invoice 5552 5552 0 0.00 5 56844 781 0 0
sp_cancel_client_order 1794 1794 0 0.00 4 13115 329 0 0
sp_cancel_customer_reserve 545155 545155 0 0.00 1 53499 62 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 3 8747 98 0 0
sp_cancel_pay_to_supplier 453 453 0 0.00 3 3160 79 0 0
sp_cancel_supplier_invoice 6196 6188 8 0.12 2 65800 990 0 8
sp_cancel_supplier_order 407 407 0 0.00 8 1017186 107084 0 0
sp_cancel_write_off 9076 9076 0 0.00 1 18970 133 0 0
sp_client_order 21271 21271 0 0.00 4 34703 443 0 0
sp_customer_reserve 470869 470869 0 0.00 0 83154 183 0 0
sp_fill_shopping_cart 32632 32632 0 0.00 1 10734 42 0 0
sp_fill_shopping_cart_clo_res 470861 470861 0 0.00 0 36694 18 0 0
sp_get_clo_for_invoice 5182 5182 0 0.00 0 24597 394 0 0
sp_kill_cost_storno 15785 15785 0 0.00 0 6037 53 0 0
sp_kill_qstorno_ret_qs2qd 1105981 1105981 0 0.00 0 65788 35 0 0
sp_kill_qty_storno 583741 583741 0 0.00 0 65790 74 0 0
sp_lock_selected_doc 21566 21566 0 0.00 0 8028 7 0 0
sp_make_cost_storno 28950 28950 0 0.00 0 16646 52 0 0
sp_multiply_rows_for_pdistr 28946 28946 0 0.00 0 7648 32 0 0
sp_multiply_rows_for_qdistr 21271 21271 0 0.00 0 16628 225 0 0
sp_pay_from_customer 7288 7288 0 0.00 4 15300 107 0 0
sp_pay_to_supplier 6090 6090 0 0.00 5 10522 124 0 0
sp_payment_common 13373 13373 0 0.00 1 10738 23 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 13105 289 0 0
sp_qd_handle_on_invoice_upd_sts 10732 10732 0 0.00 0 25556 342 0 0
sp_qd_handle_on_reserve_upd_sts 10389 10389 0 0.00 0 21181 58 0 0
sp_reserve_write_off 10390 10390 0 0.00 7 21218 182 0 0
sp_supplier_invoice 5627 5627 0 0.00 7 86905 3408 0 0
sp_supplier_order 5744 5744 0 0.00 6 29169 1378 0 0
srv_aggregate_perf_data 119 119 0 0.00 2439 112060 12945 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1124 124470 49363 0 0
srv_find_qd_qs_mism 1054332 1054332 0 0.00 0 34197 6 0 0
srv_make_invnt_saldo 481 481 0 0.00 68 93709 3326 0 0
srv_make_money_saldo 803 803 0 0.00 1 12846 390 0 0
srv_recalc_idx_stat 5 5 0 0.00 197990 350268 281464 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5190 5190 0 0.00 0 15502 150 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 5984 122 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 4240 14 0 0
t$perf-norm:sp_cancel_customer_reserve 737 737 0 0.00 0 2439 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 518 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 453 453 0 0.00 0 1408 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 5034 26 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 20571 1386 0 0
t$perf-norm:sp_cancel_write_off 1058 1058 0 0.00 0 1043 5 0 0
t$perf-norm:sp_client_order 21271 21271 0 0.00 0 9480 16 0 0
t$perf-norm:sp_customer_reserve 1078 1078 0 0.00 0 2210 7 0 0
t$perf-norm:sp_pay_from_customer 7288 7288 0 0.00 0 9338 14 0 0
t$perf-norm:sp_pay_to_supplier 6090 6090 0 0.00 0 8559 11 0 0
t$perf-norm:sp_reserve_write_off 10390 10390 0 0.00 0 5879 14 0 0
t$perf-norm:sp_supplier_invoice 5627 5627 0 0.00 0 14873 21 0 0
t$perf-norm:sp_supplier_order 5744 5744 0 0.00 0 9173 19 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 902 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 4 1 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 1696 11 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 3100 12 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10606 10356 250 2.35 0 7265 17 0 250
v_all_customers 38364 38364 0 0.00 0 60 1 0 0
v_all_suppliers 31488 31488 0 0.00 0 172 1 0 0
v_all_wares 42542 42542 0 0.00 0 123 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1991 9 0 0
v_cancel_client_order 3588 3588 0 0.00 0 2975 7 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 4785 10 0 0
v_cancel_customer_reserve 1474 1474 0 0.00 0 2903 8 0 0
v_cancel_payment_to_supplier 906 906 0 0.00 0 2521 8 0 0
v_cancel_supplier_invoice 884 868 16 1.80 0 4838 21 0 16
v_cancel_supplier_order 814 814 0 0.00 0 1202 4 0 0
v_cancel_write_off 2116 2116 0 0.00 0 2682 16 0 0
v_max_id_clo_ord 5741 5741 0 0.00 0 443 1 0 0
v_max_id_clo_res 1078 1078 0 0.00 0 7486 23 0 0
v_max_id_ord_sup 5619 5619 0 0.00 0 3582 4 0 0
v_max_non_paid_invoice 1702 1702 0 0.00 0 5281 8 0 0
v_max_non_paid_realizn 4059 4059 0 0.00 0 3965 4 0 0
v_min_id_clo_ord 5741 5741 0 0.00 0 8397 10 0 0
v_min_id_clo_res 1078 1078 0 0.00 0 3184 10 0 0
v_min_id_ord_sup 5619 5619 0 0.00 0 6187 6 0 0
v_min_non_paid_invoice 6086 6086 0 0.00 0 7488 17 0 0
v_min_non_paid_realizn 7287 7287 0 0.00 0 7543 12 0 0
v_our_firm 10634 10634 0 0.00 0 14 1 0 0
v_reserve_write_off 20778 20778 0 0.00 0 3037 6 0 0
x_lock_depdocs_on_canc_invoice 11306 11306 0 0.00 0 12742 103 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 15403 363 0 0
x_make_qstorno_1000_1200 5744 5744 0 0.00 2 29162 1295 0 0
x_make_qstorno_1200_2000 5627 5627 0 0.00 3 86888 3255 0 0
x_make_qstorno_2100_3300 470869 470869 0 0.00 0 83148 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-11-2021 13:39:56 to 24-11-2021 13:40:00.

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
11:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 28280 8192000000 9722716624 10019373056 793038896 8.826 100 86 172 0 821051808 7694944 20317968
11:09:57 20805 8192000000 9767473232 10075840512 841213008 9.312 100 89 178 0 875341216 12952864 21175344
11:11:24 16577 8192000000 9807633792 10126151680 872212752 9.622 100 84 167 0 912757712 21342080 19202880
11:12:45 13546 8192000000 9874177920 10208657408 910940096 10.007 100 69 139 1 985226080 59391696 14894288
11:14:02 6956 8192000000 9931092816 10276048896 963191440 10.520 100 80 160 1 1045730256 66018368 16520448
11:15:11 6373 8192000000 9954666976 10309414912 999274000 10.871 100 76 152 0 1064689936 49845728 15570208
11:16:19 2911 8192000000 10007159232 10371244032 1029009072 11.159 100 85 169 0 1116723728 70238656 17476000
11:17:24 1577 8192000000 10021394496 10386448384 1056276096 11.421 100 96 190 2 1133048352 57283856 19488400
11:18:27 2041 8192000000 10041409968 10414157824 1070091568 11.553 100 89 178 2 1151517248 63082256 18343424
11:19:30 2310 8192000000 10015071376 10397356032 1076403408 11.613 100 69 138 2 1121271776 29594368 15274000
11:20:34 6252 8192000000 10004066304 10397974528 1075886320 11.608 100 37 72 1 1116349248 32959792 7503136
11:21:42 5480 8192000000 9998789648 10401480704 1084265728 11.688 100 30 60 0 1115082160 24352208 6464224
11:22:50 9061 8192000000 10002271648 10416893952 1089148352 11.735 100 22 44 1 1114170944 20042464 4980128
11:24:01 56088 8192000000 10010948960 10402660352 1093872832 11.779 100 44 86 3 1121569744 18539056 9157856
11:26:06 6131 8192000000 10005320128 10403414016 1084276512 11.688 99 41 82 5 1115119216 20968176 9874528
11:27:13 8295 8192000000 9980863952 10386104320 1051078688 11.371 97 34 68 0 1073487680 14957440 7451552
11:28:22 12849 8192000000 9969416928 10370568192 1036343200 11.230 98 27 55 1 1065761808 23955696 5462912
11:29:38 53848 8192000000 9960329856 10362818560 978659856 10.671 93 30 61 2 1003548288 18104688 6783744
11:31:32 10080 8192000000 9950292016 10336235520 1002398720 10.902 97 43 85 0 1040689200 29101600 9188880
11:32:44 4723 8192000000 9957467200 10332286976 1030241120 11.171 99 43 90 2 1065081904 24584336 10256448
11:33:50 9708 8192000000 9960645072 10363805696 1051115536 11.371 100 24 48 1 1074802480 18246032 5440912
11:35:01 33097 8192000000 9967530368 10370424832 1056464032 11.423 100 30 60 2 1083544448 20546896 6533520
11:36:38 6919 8192000000 10000534016 10384060416 1069750368 11.550 100 36 72 0 1099945088 23157040 7037680
11:37:53 36648 8192000000 10028029024 10418810880 1093747216 11.778 100 42 84 1 1116617744 14155312 8715216
11:39:49 26033 8192000000 10117297152 10523840512 1114856192 11.978 100 50 98 1 1224992688 99443856 10692640
11:41:17 19331 8192000000 10106216096 10514743296 1120186880 12.029 100 40 78 0 1219931952 91611312 8133760
11:42:42 19626 8192000000 10123256768 10533695488 1133284960 12.152 100 45 90 2 1242317872 99307792 9725120
11:44:09 28520 8192000000 10170821904 10586226688 1146055456 12.272 100 41 82 1 1277992624 121943328 9993840
11:45:46 13956 8192000000 10181046608 10594222080 1160472544 12.408 100 47 94 2 1288768848 117548480 10747824
11:47:04 8568 8192000000 10226841232 10636103680 1164051648 12.441 100 59 118 0 1327416624 150169328 13195648
11:48:16 1122 8192000000 10301931376 10705334272 1181934464 12.608 100 73 142 0 1407501120 209531056 16035600
11:49:19 6284 8192000000 10347470944 10773176320 1185934512 12.646 100 70 139 2 1441320800 240150624 15235664
11:50:34 1390 8192000000 10336910560 10746028032 1184229696 12.630 100 78 156 1 1441753120 240005216 17518208
11:51:39 5897 8192000000 10384886480 10804748288 1182018416 12.609 100 52 104 0 1483632896 289766928 11847552
11:53:03 2694 8192000000 10395286336 10800852992 1179526352 12.586 100 77 154 1 1488098976 291017712 17554912
11:54:17 1610 8192000000 10394203424 10801213440 1160024112 12.403 100 72 144 2 1495087152 317787856 17275184
11:55:24 8045 8192000000 10443447280 10855342080 1155859520 12.364 100 64 127 0 1543609200 371788048 15961632
11:56:35 13274 8192000000 10347535008 10800402432 1150531232 12.314 100 34 68 1 1464302720 305031984 8739504
11:57:55 24770 8192000000 10340932960 10784841728 1108044288 11.914 97 28 56 0 1422781872 307727712 7009872
11:59:20 20411 8192000000 10379905824 10820227072 1110203808 11.934 100 26 52 0 1478031856 359825312 8002736
12:01:10 8845 8192000000 10124753440 10567245824 1090161216 11.744 98 33 65 1 1217215088 119949264 7104608
12:02:21 27477 8192000000 10057936176 10458066944 1084089872 11.686 99 40 78 4 1127044688 34741760 8213056
12:03:51 104455 8192000000 10051421136 10471727104 1047628992 11.338 94 14 28 0 1081107360 31020624 2457744
12:06:35 100775 8192000000 10056299600 10503016448 1103760976 11.873 99 26 52 0 1157460832 47997280 5702576
12:09:23 114615 8192000000 10141114224 10557247488 1100979872 11.847 97 30 60 0 1182577040 75627968 5969200
12:12:20 58950 8192000000 10167277808 10599448576 1123925360 12.064 98 24 46 2 1252940496 123905744 5109392
12:14:30 110282 8192000000 10124457680 10564866048 1122313648 12.049 98 17 34 1 1256953280 130747776 3891856
12:17:21 41989 8192000000 10178400416 10619207680 1154303296 12.350 100 21 42 1 1277070080 117859824 4906960
12:19:12 68335 8192000000 10189271968 10654793728 1142415136 12.238 99 22 45 0 1280844128 133729616 4699376
12:21:21 45569 8192000000 10098359920 10527866880 1150950880 12.318 100 46 91 0 1207667264 46883216 9833168
12:23:11 38882 8192000000 10073182256 10520043520 1141792496 12.232 99 29 58 1 1184176608 36700784 5683328
12:24:50 61453 8192000000 10106121536 10589081600 1112182384 11.953 96 18 36 0 1178972704 63156576 3633744
12:26:58 76987 8192000000 10113413424 10605789184 1151558752 12.324 99 17 35 0 1217593888 62267632 3767504
12:29:26 50900 8192000000 10144750800 10584846336 1150399328 12.313 99 31 62 0 1215357344 58924000 6034016
12:31:17 107769 8192000000 10075034000 10516131840 1121940544 12.045 97 18 36 1 1165333104 39321344 4071216
12:34:19 81983 8192000000 10061276688 10514026496 1104619312 11.881 96 28 56 1 1153238992 43283056 5336624
12:36:42 49928 8192000000 10053152848 10529652736 1098850368 11.827 98 17 34 0 1167311872 64731680 3729824
12:38:36 23359 8192000000 10043615184 10473041920 1083797520 11.684 97 17 34 1 1128844960 41373760 3673680
12:40:14 48430 8192000000 10037667200 10501160960 1093092944 11.772 98 11 22 1 1140112672 44772752 2246976
12:42:04 59481 8192000000 10013747904 10444095488 1067129504 11.525 96 13 26 0 1091330976 22062000 2139472
12:44:04 64833 8192000000 10073322192 10503512064 1072933824 11.580 96 20 40 0 1119177648 42212640 4031184
12:46:13 59128 8192000000 10042760304 10511978496 1071980736 11.571 96 15 30 0 1133455936 58448528 3026672
12:48:14 20674 8192000000 10071467328 10507362304 1086856000 11.713 98 43 86 0 1147634576 51841824 8936752
12:49:38 35580 8192000000 10092426448 10512805888 1095322336 11.793 98 41 82 2 1165089600 61439984 8327280
12:51:17 35850 8192000000 10077870992 10504986624 1109484448 11.928 99 46 92 2 1170312608 51343104 9485056
12:52:53 9948 8192000000 10119668976 10536857600 1135105808 12.169 100 47 94 1 1203128064 58835056 9187200
12:54:05 87427 8192000000 10114660288 10560978944 1097634512 11.815 96 19 38 0 1154658080 53126304 3897264
12:56:36 107085 8192000000 10109990912 10546978816 1128070352 12.103 99 33 66 0 1186094592 50897024 7127216
12:59:23 33625 8192000000 10064930528 10568335360 1132988832 12.150 100 9 18 0 1172782560 37915120 1878608
13:00:59 73173 8192000000 10078274048 10536026112 1134541232 12.164 99 11 22 0 1177891872 40794176 2556464
13:03:12 62867 8192000000 10120318272 10571042816 1141025616 12.225 100 27 54 1 1212605104 65723296 5856192
13:05:16 54593 8192000000 10107338992 10552573952 1134544048 12.164 99 21 42 0 1206693536 67653984 4495504
13:07:11 63602 8192000000 10062288080 10513932288 1106509984 11.899 97 19 38 1 1149435152 39353440 3571728
13:09:15 69427 8192000000 10092812352 10530779136 1124634256 12.071 99 26 52 4 1173896112 43503760 5758096
13:11:26 84934 8192000000 10109575680 10702360576 1147715904 12.288 100 11 22 0 1279126608 128931648 2479056
13:14:04 82184 8192000000 10075293024 10561875968 1120476544 12.031 97 25 50 2 1163692080 38054448 5161088
13:16:26 12285 8192000000 10083388032 10511618048 1155057472 12.357 100 44 87 2 1174588912 9328432 10203008
13:17:44 106236 8192000000 10149234272 10619699200 1158302416 12.387 100 32 63 1 1251807072 86720832 6783824
13:20:38 71812 8192000000 10125986512 10605760512 1155167728 12.358 100 9 18 0 1253770224 96862416 1740080
13:22:50 57540 8192000000 10081849280 10558414848 1126095088 12.085 98 14 29 2 1191742768 62652320 2995360
13:24:56 54613 8192000000 10096577920 10564853760 1143358992 12.247 99 18 35 1 1219867344 72429712 4078640
13:27:00 28922 8192000000 10118141952 10555949056 1118107376 12.009 97 38 76 0 1178424352 52280720 8036256
13:28:32 98870 8192000000 10092595376 10566430720 1064800064 11.502 94 18 36 0 1145292688 76511264 3981360
13:31:13 43030 8192000000 10094327200 10552754176 1120009248 12.027 99 46 92 0 1189264864 59748912 9506704
13:33:06 48450 8192000000 10096977088 10578403328 1107978624 11.913 98 27 52 1 1161232240 48209264 5044352
13:34:57 124469 8192000000 10096070416 10585157632 1107037872 11.904 98 28 56 1 1168527072 55614144 5875056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-11-2021 13:40:01 to 24-11-2021 13:40:16.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-11-2021 13:40:17 to 24-11-2021 13:40:19.

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          239978
MON$OLDEST_ACTIVE               240895
MON$OLDEST_SNAPSHOT             240895
MON$NEXT_TRANSACTION            240895
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                       2046304
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             812
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 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 ---
:308: 
:309: mscs-test-161.budzdorov.ru    Wed Nov 24 13:37:14 2021
:310:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:311: 
:312: 
:313: mscs-test-161.budzdorov.ru    Wed Nov 24 13:37:25 2021
:314:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:315: 
:316: 
:317: mscs-test-161.budzdorov.ru    Wed Nov 24 13:37:52 2021
:318:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:319: 
--- end of diff output ---

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

24.11.21 13:40:20. 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
2021-11-24 13:40:22 No severe PSQL-related problems occured
24.11.21 13:40:24. End of report.