Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.12.2021 13: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 16570500 kB
MemAvailable 31483036 kB
Buffers 0 kB
Cached 15148120 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-12-15 13:37:09
DTS_END                         2021-12-15 13:37:09.2310
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/48512
TRN_ID                          232990
ATT_ID                          634
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: 9262.84 23654 1111541
customer order: creation 164.54 447 19745
customer order: refuse 13.82 335 1658
order to supplier: creation 44.83 1454 5380
order to supplier: removal 3.42 121357 410
invoice (draft): creation 44.48 3559 5337
invoice (draft): removal 52.93 976 6351
invoice accept: apply 40.47 19931 4856
invoice accept: cancel 47.62 820 5714
customer reserve: creation 3847.38 192 461686
customer reserve: removal 4722.19 68 566663
realization accept: apply 79.38 170 9526
realization accept: cancel 78.63 131 9436
payment from customer: creation 57.43 102 6891
payment from customer: removal 5.51 85 661
payment to supplier: creation 46.37 119 5564
payment to supplier: removal 3.39 153 407
service: total inventory turnovers 3.85 3333 462
service: total monetary turnovers 5.59 550 671
service: aggregate perf. data 0.99 13258 119
service: refresh index statistics 0.03 306041 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-12-2021 13:38:45 to 15-12-2021 13:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3633
TEST_TIME 2 3303
TEST_TIME 3 3883
TEST_TIME 4 4010
TEST_TIME 5 4547
TEST_TIME 6 4471
TEST_TIME 7 4852
TEST_TIME 8 4965
TEST_TIME 9 5631
TEST_TIME 10 5893
TEST_TIME 11 5864
TEST_TIME 12 6235
TEST_TIME 13 6671
TEST_TIME 14 6899
TEST_TIME 15 7001
TEST_TIME 16 7085
TEST_TIME 17 7284
TEST_TIME 18 7507
TEST_TIME 19 8205
TEST_TIME 20 8534
TEST_TIME 21 8757
TEST_TIME 22 8925
TEST_TIME 23 8857
TEST_TIME 24 9057
TEST_TIME 25 8951
TEST_TIME 26 9191
TEST_TIME 27 9421
TEST_TIME 28 11235
TEST_TIME 29 11208
TEST_TIME 30 10975
TEST_TIME 31 10816
TEST_TIME 32 10704
TEST_TIME 33 10435
TEST_TIME 34 10303
TEST_TIME 35 10249
TEST_TIME 36 10201
TEST_TIME 37 10282
TEST_TIME 38 10153
TEST_TIME 39 10102
TEST_TIME 40 9941
TEST_TIME 41 9820
TEST_TIME 42 10071
TEST_TIME 43 10040
TEST_TIME 44 10062
TEST_TIME 45 9981
TEST_TIME 46 9974
TEST_TIME 47 9925
TEST_TIME 48 10105
TEST_TIME 49 10069
TEST_TIME 50 10045
TEST_TIME 51 10110
TEST_TIME 52 10127
TEST_TIME 53 9957
TEST_TIME 54 9901
TEST_TIME 55 9818
TEST_TIME 56 9784
TEST_TIME 57 9738
TEST_TIME 58 9689
TEST_TIME 59 9666
TEST_TIME 60 9587
TEST_TIME 61 9497
TEST_TIME 62 9510
TEST_TIME 63 9501
TEST_TIME 64 9560
TEST_TIME 65 9602
TEST_TIME 66 9707
TEST_TIME 67 9663
TEST_TIME 68 9739
TEST_TIME 69 9729
TEST_TIME 70 9701
TEST_TIME 71 9838
TEST_TIME 72 9943
TEST_TIME 73 9956
TEST_TIME 74 9956
TEST_TIME 75 9859
TEST_TIME 76 9803
TEST_TIME 77 9714
TEST_TIME 78 9724
TEST_TIME 79 9692
TEST_TIME 80 9671
TEST_TIME 81 9622
TEST_TIME 82 9627
TEST_TIME 83 9564
TEST_TIME 84 9576
TEST_TIME 85 9595
TEST_TIME 86 9562
TEST_TIME 87 9554
TEST_TIME 88 9552
TEST_TIME 89 9552
TEST_TIME 90 9571
TEST_TIME 91 9622
TEST_TIME 92 9632
TEST_TIME 93 9638
TEST_TIME 94 9654
TEST_TIME 95 9689
TEST_TIME 96 9716
TEST_TIME 97 9647
TEST_TIME 98 9585
TEST_TIME 99 9580
TEST_TIME 100 9533
TEST_TIME 101 9482
TEST_TIME 102 9452
TEST_TIME 103 9420
TEST_TIME 104 9390
TEST_TIME 105 9380
TEST_TIME 106 9384
TEST_TIME 107 9379
TEST_TIME 108 9389
TEST_TIME 109 9400
TEST_TIME 110 9448
TEST_TIME 111 9422
TEST_TIME 112 9453
TEST_TIME 113 9495
TEST_TIME 114 9474
TEST_TIME 115 9462
TEST_TIME 116 9387
TEST_TIME 117 9396
TEST_TIME 118 9362
TEST_TIME 119 9316
TEST_TIME 120 9264

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-12-2021 13:38:49 to 15-12-2021 13:38:52.

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 1535974 1535974 0 0.00 0 26841 7 0 0
doc_list_biud 1535972 1535972 0 0.00 0 47506 32 0 0
sp_add_invoice_to_stock 4982 4856 126 2.52 45 196828 19932 0 126
sp_cancel_adding_invoice 5714 5714 0 0.00 4 99562 821 0 0
sp_cancel_client_order 1658 1658 0 0.00 4 17485 336 0 0
sp_cancel_customer_reserve 566663 566663 0 0.00 1 47507 69 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 5403 85 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 13102 154 0 0
sp_cancel_supplier_invoice 6359 6351 8 0.12 2 33393 977 0 8
sp_cancel_supplier_order 410 410 0 0.00 10 1098347 121358 0 0
sp_cancel_write_off 9436 9436 0 0.00 1 23785 132 0 0
sp_client_order 19745 19745 0 0.00 4 29863 448 0 0
sp_customer_reserve 461686 461686 0 0.00 0 83490 192 0 0
sp_fill_shopping_cart 30454 30454 0 0.00 1 25598 50 0 0
sp_fill_shopping_cart_clo_res 461676 461676 0 0.00 0 17117 16 0 0
sp_get_clo_for_invoice 4846 4846 0 0.00 0 21610 379 0 0
sp_kill_cost_storno 16218 16218 0 0.00 0 13088 59 0 0
sp_kill_qstorno_ret_qs2qd 1149523 1149523 0 0.00 0 47502 37 0 0
sp_kill_qty_storno 604604 604604 0 0.00 0 47506 80 0 0
sp_lock_selected_doc 19941 19941 0 0.00 0 9088 5 0 0
sp_make_cost_storno 26827 26827 0 0.00 0 16548 53 0 0
sp_multiply_rows_for_pdistr 26827 26827 0 0.00 0 7889 28 0 0
sp_multiply_rows_for_qdistr 19745 19745 0 0.00 0 14964 237 0 0
sp_pay_from_customer 6891 6891 0 0.00 4 16554 102 0 0
sp_pay_to_supplier 5564 5564 0 0.00 4 14396 120 0 0
sp_payment_common 12455 12455 0 0.00 1 14350 20 0 0
sp_qd_handle_on_cancel_clo 1658 1658 0 0.00 0 14209 309 0 0
sp_qd_handle_on_invoice_upd_sts 10560 10560 0 0.00 0 31022 354 0 0
sp_qd_handle_on_reserve_upd_sts 9526 9526 0 0.00 0 22738 57 0 0
sp_reserve_write_off 9526 9526 0 0.00 6 22765 171 0 0
sp_supplier_invoice 5337 5337 0 0.00 7 100740 3559 0 0
sp_supplier_order 5380 5380 0 0.00 5 43146 1455 0 0
srv_aggregate_perf_data 120 119 1 0.83 2392 176369 13258 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 941 110676 45484 0 0
srv_find_qd_qs_mism 1063533 1063533 0 0.00 0 26840 7 0 0
srv_make_invnt_saldo 462 462 0 0.00 16 111964 3333 0 0
srv_make_money_saldo 671 671 0 0.00 1 13791 551 0 0
srv_recalc_idx_stat 4 4 0 0.00 182990 425728 306041 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4856 4856 0 0.00 0 9679 159 0 0
t$perf-norm:sp_cancel_adding_invoice 348 348 0 0.00 1 3647 96 0 0
t$perf-norm:sp_cancel_client_order 1658 1658 0 0.00 0 1209 9 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 1559 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 1302 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 28 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 3121 22 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 18499 1447 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 4991 11 0 0
t$perf-norm:sp_client_order 19745 19745 0 0.00 0 8514 18 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 2714 13 0 0
t$perf-norm:sp_pay_from_customer 6891 6891 0 0.00 0 3939 10 0 0
t$perf-norm:sp_pay_to_supplier 5564 5564 0 0.00 0 40638 19 0 0
t$perf-norm:sp_reserve_write_off 9526 9526 0 0.00 0 5236 11 0 0
t$perf-norm:sp_supplier_invoice 5337 5337 0 0.00 0 4526 17 0 0
t$perf-norm:sp_supplier_order 5380 5380 0 0.00 0 5843 19 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 198 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 293 10 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 1544 9 0 0
t$perf-norm:srv_make_money_saldo 671 671 0 0.00 0 1942 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9944 9692 252 2.53 0 9107 16 0 252
v_all_customers 36174 36174 0 0.00 0 22 1 0 0
v_all_suppliers 29716 29716 0 0.00 0 45 1 0 0
v_all_wares 39490 39490 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 696 696 0 0.00 0 231 6 0 0
v_cancel_client_order 3316 3316 0 0.00 0 2920 6 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 1992 7 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 2207 5 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 5720 16 0 0
v_cancel_supplier_invoice 844 828 16 1.89 0 3280 25 0 16
v_cancel_supplier_order 820 820 0 0.00 0 1482 7 0 0
v_cancel_write_off 1936 1936 0 0.00 0 2222 15 0 0
v_max_id_clo_ord 5376 5376 0 0.00 0 2932 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 1954 11 0 0
v_max_id_ord_sup 5333 5333 0 0.00 0 8539 7 0 0
v_max_non_paid_invoice 1413 1413 0 0.00 0 139 2 0 0
v_max_non_paid_realizn 3608 3608 0 0.00 0 1754 3 0 0
v_min_id_clo_ord 5376 5376 0 0.00 0 5326 7 0 0
v_min_id_clo_res 989 989 0 0.00 0 3396 12 0 0
v_min_id_ord_sup 5333 5333 0 0.00 0 5606 10 0 0
v_min_non_paid_invoice 5564 5564 0 0.00 0 14343 14 0 0
v_min_non_paid_realizn 6891 6891 0 0.00 0 6514 15 0 0
v_our_firm 9882 9882 0 0.00 0 14 1 0 0
v_reserve_write_off 19052 19052 0 0.00 0 6921 6 0 0
x_lock_depdocs_on_canc_invoice 11651 11651 0 0.00 0 18739 127 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 12600 358 0 0
x_make_qstorno_1000_1200 5380 5380 0 0.00 2 43136 1372 0 0
x_make_qstorno_1200_2000 5337 5337 0 0.00 3 100668 3364 0 0
x_make_qstorno_2100_3300 461686 461686 0 0.00 0 82282 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2021 13:38:53 to 15-12-2021 13:38:57.

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:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 24217 8192000000 9710528656 10006573056 787390992 8.768 100 85 170 1 816351104 7519520 21440592
11:09:43 10449 8192000000 9759466416 10064855040 835353696 9.253 100 87 172 1 868243456 12180272 20709488
11:10:57 14734 8192000000 9792845136 10105896960 861354128 9.514 100 88 176 0 903364304 21177728 20832448
11:12:20 15982 8192000000 9864443296 10196975616 914314160 10.040 100 83 166 1 973625168 40950896 18360112
11:13:48 8434 8192000000 9914334160 10257223680 954357952 10.434 100 79 159 1 1026677584 55557392 16762240
11:15:00 6450 8192000000 9969956832 10326724608 992735568 10.808 100 76 152 0 1085618400 77400240 15482592
11:16:08 5854 8192000000 9988818752 10356285440 1022346960 11.095 100 73 146 1 1119556544 82747600 14461984
11:17:17 2634 8192000000 10029590000 10396549120 1047264480 11.334 100 90 179 0 1147850320 82917056 17668784
11:18:22 1525 8192000000 10024682448 10396561408 1061931904 11.475 100 91 183 0 1136576080 55569552 19074624
11:19:24 9816 8192000000 9984619328 10371915776 1062686608 11.482 100 29 56 0 1095766544 26895264 6184672
11:20:35 7786 8192000000 9984067936 10388258816 1075374368 11.603 100 34 68 0 1110585728 28219600 6991760
11:21:43 5938 8192000000 9992224768 10388439040 1081194144 11.659 100 26 51 0 1102864288 15624960 6045184
11:22:50 72069 8192000000 9993173520 10401923072 1084683712 11.692 100 29 58 0 1114693984 24113040 5897232
11:25:02 10913 8192000000 10015429376 10424184832 1092264064 11.764 100 29 58 1 1114832576 15798544 6769968
11:26:14 5296 8192000000 10017258832 10420957184 1097604464 11.815 100 50 94 3 1127987472 20224896 10158112
11:27:23 6389 8192000000 9999679312 10403409920 1063166944 11.487 97 34 68 0 1087810848 17237312 7406592
11:28:33 27519 8192000000 9984585680 10366312448 1027813680 11.147 95 36 71 1 1047817216 12330608 7672928
11:30:01 6335 8192000000 9976393152 10371723264 1052568560 11.385 100 44 88 0 1089241072 27318176 9354336
11:31:08 9254 8192000000 9937437376 10318229504 955586272 10.446 93 41 82 0 976417856 12101072 8730512
11:32:22 8919 8192000000 9954761968 10348154880 1043819280 11.301 100 38 77 0 1076848304 23698240 9330784
11:33:37 9674 8192000000 9951113680 10348519424 1015976192 11.033 97 31 62 0 1045787744 23131712 6679840
11:34:47 2968 8192000000 9986350736 10367868928 1058060704 11.438 100 50 106 4 1084669824 14499056 12110064
11:35:56 11995 8192000000 9991733616 10379784192 1067035680 11.524 100 25 49 1 1100619344 27979712 5603952
11:37:09 73872 8192000000 10010447024 10406133760 1089225440 11.735 100 39 77 0 1112952048 15493552 8233056
11:39:30 24392 8192000000 10055961200 10458406912 1112682576 11.958 100 41 83 1 1161428592 40462352 8283664
11:40:59 11667 8192000000 10074058176 10482921472 1125398912 12.078 100 37 70 2 1189003216 55522416 8081888
11:42:15 28429 8192000000 10115905152 10534363136 1138430768 12.201 100 35 70 0 1229305696 82838928 8036000
11:43:48 4143 8192000000 10204531056 10605953024 1152297984 12.331 100 70 140 0 1303967376 137250032 14419360
11:44:57 7999 8192000000 10200252928 10605350912 1154565328 12.352 100 61 122 0 1305175920 137007792 13602800
11:46:19 1658 8192000000 10198875024 10594627584 1139525520 12.211 99 83 164 0 1296727552 138992048 18209984
11:47:31 12750 8192000000 10247454192 10649985024 1154250848 12.349 100 67 135 0 1343866960 174783488 14832624
11:49:03 1155 8192000000 10275755104 10678595584 1155824688 12.364 99 76 151 2 1377201568 203409552 17967328
11:50:09 1043 8192000000 10302567408 10716225536 1173963968 12.534 100 75 151 0 1408985536 217900064 17121504
11:51:15 2854 8192000000 10330327584 10732486656 1180013488 12.590 100 84 165 2 1427078496 228163424 18901584
11:52:25 3832 8192000000 10362139456 10766155776 1174820896 12.542 100 81 162 1 1463858000 270454768 18582336
11:53:35 940 8192000000 10392120640 10804977664 1172149168 12.517 100 90 178 0 1486772544 292933232 21690144
11:54:39 1531 8192000000 10421727584 10824069120 1164455232 12.445 100 84 166 2 1526565776 342173584 19936960
11:55:44 5042 8192000000 10465563552 10876379136 1148060016 12.291 100 74 148 1 1553674768 387277664 18337088
11:56:52 3589 8192000000 10408085456 10814689280 1165925504 12.459 100 91 181 1 1500933920 314005344 21003072
11:58:01 10371 8192000000 10405026384 10825195520 1175329504 12.547 100 52 100 0 1503335856 315772768 12233584
11:59:13 15508 8192000000 10390776864 10860261376 1158982784 12.394 99 37 74 0 1495597696 326687360 9927552
12:00:39 13826 8192000000 10432433664 10873851904 1156024960 12.366 100 32 64 0 1531330336 367100880 8204496
12:01:54 32938 8192000000 10459791088 10914254848 1143697344 12.250 99 26 52 0 1522827584 372090000 7040240
12:03:56 26873 8192000000 10447047360 10938167296 1108338528 11.917 99 18 36 0 1528471248 414503408 5629312
12:05:33 33943 8192000000 10039582000 10467418112 1108238112 11.916 98 17 33 2 1152800544 40849440 3712992
12:07:17 48609 8192000000 10087590496 10516094976 1130906928 12.130 100 31 62 1 1186885536 49934064 6044544
12:09:11 84899 8192000000 10044847056 10481254400 1077539040 11.624 96 29 58 0 1118254560 34276512 6439008
12:11:38 11507 8192000000 10048749936 10465439744 1064474416 11.499 95 42 85 0 1118901248 44907840 9518992
12:12:50 20040 8192000000 10069255264 10502606848 1096686592 11.806 99 29 58 1 1149777168 47317888 5772688
12:14:20 21972 8192000000 10102481152 10535600128 1113981488 11.970 99 30 59 0 1199324672 79430288 5912896
12:15:44 47277 8192000000 10056504096 10517311488 1099188272 11.830 98 21 42 0 1155921904 52010640 4722992
12:17:31 101287 8192000000 10153095216 10597163008 1116394368 11.993 99 21 42 0 1197050128 75742352 4913408
12:20:39 67426 8192000000 10069290688 10540924928 1108938032 11.922 98 22 44 0 1177969184 64295648 4735504
12:22:47 29324 8192000000 10121048736 10549661696 1141645408 12.231 100 30 60 0 1210596368 62695856 6255104
12:24:17 55217 8192000000 10137701888 10592436224 1123227072 12.057 99 28 56 0 1240723712 110985008 6511632
12:26:25 51713 8192000000 10113946304 10547888128 1142138976 12.236 100 17 34 1 1205920080 60500080 3281024
12:28:18 77009 8192000000 10086243472 10567573504 1133954768 12.159 99 22 44 1 1194769952 55621136 5194048
12:30:36 110676 8192000000 10078971616 10543767552 1116135760 11.990 97 29 58 0 1162964512 40486944 6341808
12:34:07 30214 8192000000 10086099136 10531643392 1130722832 12.128 99 19 38 0 1177352624 42958400 3671392
12:35:41 66154 8192000000 10080100784 10527649792 1103019680 11.866 97 13 26 0 1161335808 55520416 2795712
12:37:49 74916 8192000000 10118438560 10567372800 1129239776 12.114 99 17 34 1 1203409904 70444832 3725296
12:40:10 73130 8192000000 10121697136 10579693568 1133439744 12.154 99 16 32 0 1244689152 107956864 3292544
12:42:25 74625 8192000000 10108380432 10580271104 1129962272 12.121 99 18 36 2 1202949376 68995360 3991744
12:44:43 71620 8192000000 10101608000 10581536768 1137140064 12.189 99 15 30 0 1225190304 84694720 3355520
12:46:55 30317 8192000000 10145918432 10570870784 1116011920 11.989 98 29 58 0 1224610704 102392432 6206352
12:48:31 94868 8192000000 10086224592 10564845568 1098728800 11.826 97 8 16 0 1180547184 79975952 1842432
12:51:07 90386 8192000000 10070432832 10546982912 1087593376 11.720 96 21 42 3 1124152704 32081760 4477568
12:53:56 64354 8192000000 10080406096 10515529728 1127073472 12.094 99 34 68 0 1182735696 48889184 6773040
12:56:01 77646 8192000000 10042836576 10508296192 1079563600 11.643 95 26 52 1 1131615312 46495360 5556352
12:58:20 51252 8192000000 10064745408 10504765440 1069913520 11.551 96 16 32 0 1113776880 40479088 3384272
13:00:16 59829 8192000000 10076782272 10545238016 1116772640 11.996 100 17 34 0 1189289344 68670336 3846368
13:02:18 60150 8192000000 10037572560 10484981760 1083459248 11.680 97 10 18 0 1116289440 30939568 1890624
13:04:18 29419 8192000000 10052379760 10483372032 1095866176 11.798 98 31 62 1 1144820640 42801056 6153408
13:06:09 30515 8192000000 10121114672 10573025280 1134358800 12.162 100 30 60 0 1207000112 66726016 5915296
13:07:46 83264 8192000000 10099985744 10539708416 1106536912 11.900 97 17 34 0 1183331984 73464416 3330656
13:10:12 40106 8192000000 10114703232 10558492672 1136111920 12.179 99 33 66 1 1209023008 66453776 6457312
13:11:53 62693 8192000000 10101893792 10568441856 1128215552 12.105 99 29 58 0 1194519120 60908640 5394928
13:14:03 109444 8192000000 10103878512 10554638336 1137041200 12.188 99 23 46 0 1194548448 52663792 4843456
13:17:05 54697 8192000000 10072766352 10524405760 1125495312 12.079 98 9 18 0 1164416368 37349904 1571152
13:19:06 59172 8192000000 10081696528 10547257344 1144568736 12.258 100 9 18 0 1195056736 48583040 1904960
13:21:20 106438 8192000000 10110982528 10574594048 1154568976 12.352 100 16 32 1 1222106400 63711664 3825760
13:24:10 71486 8192000000 10139706208 10607292416 1158026992 12.385 100 21 42 0 1231711200 69428528 4255680
13:26:28 76701 8192000000 10124508528 10614063104 1142145328 12.236 99 16 32 0 1194949200 49470800 3333072
13:28:47 96310 8192000000 10079859904 10577588224 1124456352 12.069 98 9 18 0 1199727776 73635536 1635888
13:31:26 19476 8192000000 10080660400 10513162240 1110638448 11.938 98 34 68 0 1151510288 34321472 6550368
13:32:51 89692 8192000000 10052910704 10509111296 1106630224 11.901 98 35 69 1 1180909072 67114112 7164736
13:37:00 16774 8192000000 9161728192 9452904448 302216544 3.557 27 3 6 0 322000336 19484016 299776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 15-12-2021 13:38:59 to 15-12-2021 13:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 126 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 15-12-2021 13:39:14 to 15-12-2021 13:39:16.

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          232989
MON$OLDEST_ACTIVE               233288
MON$OLDEST_SNAPSHOT             233288
MON$NEXT_TRANSACTION            233288
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                       2047904
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             796
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2689 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2689 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2689 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2689 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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Wed Dec 15 13:37:25 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Wed Dec 15 13:37:25 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Wed Dec 15 13:37:25 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Wed Dec 15 13:37:25 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Wed Dec 15 13:37:25 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Wed Dec 15 13:37:25 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Wed Dec 15 13:37:25 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
--- end of diff output ---

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

15.12.21 13:39:17. 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-12-15 13:39:20 No severe PSQL-related problems occured
15.12.21 13:39:21. End of report.