Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.2021 07: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15833108 kB
MemAvailable 30918288 kB
Buffers 0 kB
Cached 15901768 kB
SwapCached 4628 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-10-26 07:37:11
DTS_END                         2021-10-26 07:37:11.0230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(270:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58344
TRN_ID                          240644
ATT_ID                          677
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: 9343.48 19739 1121217
customer order: creation 173.84 362 20861
customer order: refuse 14.57 313 1748
order to supplier: creation 46.95 1291 5634
order to supplier: removal 3.49 102847 419
invoice (draft): creation 45.49 3379 5459
invoice (draft): removal 53.68 839 6442
invoice accept: apply 41.93 17239 5032
invoice accept: cancel 48.29 773 5795
customer reserve: creation 3879.90 169 465588
customer reserve: removal 4734.45 59 568134
realization accept: apply 84.18 193 10101
realization accept: cancel 87.35 115 10482
payment from customer: creation 59.04 127 7085
payment from customer: removal 5.38 65 645
payment to supplier: creation 50.18 137 6022
payment to supplier: removal 3.53 69 423
service: total inventory turnovers 3.91 3567 469
service: total monetary turnovers 6.40 457 768
service: aggregate perf. data 0.88 14566 106
service: refresh index statistics 0.03 248207 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2021 07:38:52 to 26-10-2021 07:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3861
TEST_TIME 2 2163
TEST_TIME 3 3791
TEST_TIME 4 3899
TEST_TIME 5 4353
TEST_TIME 6 4119
TEST_TIME 7 3953
TEST_TIME 8 4662
TEST_TIME 9 4951
TEST_TIME 10 5283
TEST_TIME 11 5650
TEST_TIME 12 5660
TEST_TIME 13 6027
TEST_TIME 14 6230
TEST_TIME 15 6476
TEST_TIME 16 6666
TEST_TIME 17 7693
TEST_TIME 18 7774
TEST_TIME 19 7987
TEST_TIME 20 8581
TEST_TIME 21 8788
TEST_TIME 22 9160
TEST_TIME 23 9605
TEST_TIME 24 9808
TEST_TIME 25 9711
TEST_TIME 26 10289
TEST_TIME 27 10333
TEST_TIME 28 10302
TEST_TIME 29 10285
TEST_TIME 30 10350
TEST_TIME 31 10237
TEST_TIME 32 10349
TEST_TIME 33 10346
TEST_TIME 34 10240
TEST_TIME 35 10223
TEST_TIME 36 10413
TEST_TIME 37 10432
TEST_TIME 38 10517
TEST_TIME 39 10408
TEST_TIME 40 10406
TEST_TIME 41 10177
TEST_TIME 42 10038
TEST_TIME 43 10030
TEST_TIME 44 10003
TEST_TIME 45 9973
TEST_TIME 46 9888
TEST_TIME 47 9840
TEST_TIME 48 9956
TEST_TIME 49 9860
TEST_TIME 50 9833
TEST_TIME 51 9778
TEST_TIME 52 9676
TEST_TIME 53 9617
TEST_TIME 54 9517
TEST_TIME 55 9630
TEST_TIME 56 9533
TEST_TIME 57 9547
TEST_TIME 58 9639
TEST_TIME 59 9619
TEST_TIME 60 9654
TEST_TIME 61 9698
TEST_TIME 62 9651
TEST_TIME 63 9640
TEST_TIME 64 9718
TEST_TIME 65 9752
TEST_TIME 66 9840
TEST_TIME 67 9828
TEST_TIME 68 9879
TEST_TIME 69 9880
TEST_TIME 70 9899
TEST_TIME 71 9879
TEST_TIME 72 9921
TEST_TIME 73 9807
TEST_TIME 74 9803
TEST_TIME 75 9779
TEST_TIME 76 9733
TEST_TIME 77 9691
TEST_TIME 78 9633
TEST_TIME 79 9604
TEST_TIME 80 9529
TEST_TIME 81 9438
TEST_TIME 82 9482
TEST_TIME 83 9591
TEST_TIME 84 9586
TEST_TIME 85 9586
TEST_TIME 86 9574
TEST_TIME 87 9625
TEST_TIME 88 9606
TEST_TIME 89 9580
TEST_TIME 90 9651
TEST_TIME 91 9668
TEST_TIME 92 9649
TEST_TIME 93 9729
TEST_TIME 94 9714
TEST_TIME 95 9713
TEST_TIME 96 9623
TEST_TIME 97 9561
TEST_TIME 98 9554
TEST_TIME 99 9497
TEST_TIME 100 9453
TEST_TIME 101 9424
TEST_TIME 102 9412
TEST_TIME 103 9394
TEST_TIME 104 9393
TEST_TIME 105 9381
TEST_TIME 106 9389
TEST_TIME 107 9425
TEST_TIME 108 9457
TEST_TIME 109 9450
TEST_TIME 110 9454
TEST_TIME 111 9506
TEST_TIME 112 9489
TEST_TIME 113 9525
TEST_TIME 114 9591
TEST_TIME 115 9515
TEST_TIME 116 9441
TEST_TIME 117 9464
TEST_TIME 118 9445
TEST_TIME 119 9417
TEST_TIME 120 9345

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-10-2021 07:38:56 to 26-10-2021 07:38:59.

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 1552154 1552154 0 0.00 0 19005 7 0 0
doc_list_biud 1552151 1552151 0 0.00 0 41454 28 0 0
sp_add_invoice_to_stock 5194 5032 162 3.11 47 165870 17239 0 162
sp_cancel_adding_invoice 5795 5795 0 0.00 4 66789 774 0 0
sp_cancel_client_order 1748 1748 0 0.00 5 9387 313 0 0
sp_cancel_customer_reserve 568134 568134 0 0.00 1 30245 60 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 2596 66 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 2691 69 0 0
sp_cancel_supplier_invoice 6461 6442 19 0.29 2 41457 840 0 19
sp_cancel_supplier_order 419 419 0 0.00 8 895142 102848 0 0
sp_cancel_write_off 10482 10482 0 0.00 1 43616 116 0 0
sp_client_order 20861 20861 0 0.00 4 29792 362 0 0
sp_customer_reserve 465588 465588 0 0.00 0 39734 170 0 0
sp_fill_shopping_cart 31941 31941 0 0.00 1 18097 40 0 0
sp_fill_shopping_cart_clo_res 465585 465585 0 0.00 0 15046 14 0 0
sp_get_clo_for_invoice 5029 5029 0 0.00 0 23520 362 0 0
sp_kill_cost_storno 17345 17345 0 0.00 0 7603 55 0 0
sp_kill_qstorno_ret_qs2qd 1153611 1153611 0 0.00 0 41453 32 0 0
sp_kill_qty_storno 608150 608150 0 0.00 0 41454 70 0 0
sp_lock_selected_doc 20791 20791 0 0.00 0 7395 4 0 0
sp_make_cost_storno 28237 28237 0 0.00 0 15167 59 0 0
sp_multiply_rows_for_pdistr 28237 28237 0 0.00 0 8397 31 0 0
sp_multiply_rows_for_qdistr 20861 20861 0 0.00 0 13078 181 0 0
sp_pay_from_customer 7085 7085 0 0.00 4 12686 128 0 0
sp_pay_to_supplier 6022 6022 0 0.00 4 18343 137 0 0
sp_payment_common 13107 13107 0 0.00 1 18330 28 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 0 9381 293 0 0
sp_qd_handle_on_invoice_upd_sts 10824 10824 0 0.00 0 28652 345 0 0
sp_qd_handle_on_reserve_upd_sts 10101 10101 0 0.00 0 36632 68 0 0
sp_reserve_write_off 10101 10101 0 0.00 7 36745 194 0 0
sp_supplier_invoice 5459 5459 0 0.00 7 81686 3380 0 0
sp_supplier_order 5634 5634 0 0.00 6 36732 1291 0 0
srv_aggregate_perf_data 107 106 1 0.93 2689 166612 14566 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1624 166946 45142 0 0
srv_find_qd_qs_mism 1073070 1073070 0 0.00 0 19003 5 0 0
srv_make_invnt_saldo 469 469 0 0.00 39 88942 3567 0 0
srv_make_money_saldo 768 768 0 0.00 1 18419 458 0 0
srv_recalc_idx_stat 4 4 0 0.00 153533 329918 248207 0 0
t$perf-abend:sp_add_invoice_to_stock 162 162 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 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 5032 5032 0 0.00 0 12548 149 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 7044 129 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 8667 16 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 1259 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 5664 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 581 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 9420 41 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 21526 1370 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 1118 8 0 0
t$perf-norm:sp_client_order 20861 20861 0 0.00 0 9214 15 0 0
t$perf-norm:sp_customer_reserve 1037 1037 0 0.00 0 4992 10 0 0
t$perf-norm:sp_pay_from_customer 7085 7085 0 0.00 0 3931 8 0 0
t$perf-norm:sp_pay_to_supplier 6022 6022 0 0.00 0 3813 9 0 0
t$perf-norm:sp_reserve_write_off 10101 10101 0 0.00 0 9698 16 0 0
t$perf-norm:sp_supplier_invoice 5459 5459 0 0.00 0 10678 22 0 0
t$perf-norm:sp_supplier_order 5634 5634 0 0.00 0 8545 18 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 368 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 377 8 0 0
t$perf-norm:srv_make_invnt_saldo 469 469 0 0.00 0 2147 13 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 2059 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10378 10054 324 3.12 0 4385 16 0 324
v_all_customers 37778 37778 0 0.00 0 21 1 0 0
v_all_suppliers 30726 30726 0 0.00 0 174 1 0 0
v_all_wares 41722 41722 0 0.00 0 64 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 3332 11 0 0
v_cancel_client_order 3496 3496 0 0.00 0 635 3 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 1952 7 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 2321 5 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1417 5 0 0
v_cancel_supplier_invoice 814 776 38 4.66 0 7339 26 0 38
v_cancel_supplier_order 838 838 0 0.00 0 859 4 0 0
v_cancel_write_off 1910 1910 0 0.00 0 1939 13 0 0
v_max_id_clo_ord 5634 5634 0 0.00 0 542 1 0 0
v_max_id_clo_res 1037 1037 0 0.00 0 2153 13 0 0
v_max_id_ord_sup 5446 5446 0 0.00 0 6273 5 0 0
v_max_non_paid_invoice 1739 1739 0 0.00 0 635 2 0 0
v_max_non_paid_realizn 3791 3791 0 0.00 0 3701 4 0 0
v_min_id_clo_ord 5634 5634 0 0.00 0 2100 7 0 0
v_min_id_clo_res 1037 1037 0 0.00 0 1971 6 0 0
v_min_id_ord_sup 5446 5446 0 0.00 0 5298 9 0 0
v_min_non_paid_invoice 6022 6022 0 0.00 0 18323 21 0 0
v_min_non_paid_realizn 7085 7085 0 0.00 0 12669 21 0 0
v_our_firm 10532 10532 0 0.00 0 12 1 0 0
v_reserve_write_off 20202 20202 0 0.00 0 5093 5 0 0
x_lock_depdocs_on_canc_invoice 11849 11849 0 0.00 0 20065 99 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 13454 344 0 0
x_make_qstorno_1000_1200 5634 5634 0 0.00 2 36724 1217 0 0
x_make_qstorno_1200_2000 5459 5459 0 0.00 4 81655 3228 0 0
x_make_qstorno_2100_3300 465588 465588 0 0.00 0 39244 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2021 07:39:00 to 26-10-2021 07:39:04.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:14 17 8192000000 8806572560 8820875264 8816656 0.107 4 1 2 0 8924448 72016 35776
05:08:15 28977 8192000000 9733209008 10031796224 804769360 8.945 100 88 176 1 834049680 7942848 21337472
05:09:52 19706 8192000000 9762625904 10070421504 842491952 9.325 100 90 180 0 876476448 12432992 21551504
05:11:18 14781 8192000000 9802026496 10122043392 867488032 9.575 100 80 160 0 905617056 19686736 18442288
05:12:34 14797 8192000000 9866002464 10199531520 914366416 10.040 100 80 160 0 976464880 45058368 17040096
05:13:52 9245 8192000000 9906003536 10248511488 953435776 10.425 100 84 168 0 1026752800 55768064 17548960
05:15:06 6410 8192000000 9965888640 10317012992 994076176 10.821 100 82 163 1 1083401632 72553328 16772128
05:16:15 3669 8192000000 9982985504 10344542208 1018712768 11.060 100 87 174 0 1100407088 63906544 17787776
05:17:20 1916 8192000000 10025099552 10391941120 1043138416 11.295 100 90 179 0 1141001040 79287280 18575344
05:18:24 1925 8192000000 10048655456 10419859456 1064618336 11.501 100 90 180 1 1156953536 73108304 19226896
05:19:28 7117 8192000000 10017078608 10397011968 1064043072 11.495 100 66 132 1 1124804304 47629760 13131472
05:20:35 6718 8192000000 9992463488 10392571904 1076322016 11.612 100 38 76 2 1115689104 30666192 8700896
05:21:43 6072 8192000000 9999330080 10404622336 1083132496 11.677 100 30 61 0 1114712384 24583104 6996784
05:22:52 5802 8192000000 10019570112 10418987008 1090159728 11.744 100 35 70 0 1124084128 26685120 7239280
05:23:59 22434 8192000000 10011438656 10415759360 1093061360 11.772 100 32 64 2 1123953520 23471760 7420400
05:25:27 7105 8192000000 10019087456 10423529472 1094304304 11.784 100 39 78 1 1120239344 17752576 8182464
05:26:37 6678 8192000000 10006127328 10407596032 1079634064 11.644 99 50 99 2 1116264096 26092064 10537968
05:27:47 6623 8192000000 9971640640 10363498496 1056468304 11.423 99 30 58 0 1084003072 20558976 6975792
05:28:53 29778 8192000000 9954368336 10358362112 1024709664 11.117 96 44 88 0 1049012832 14938496 9364672
05:30:25 10401 8192000000 9944050480 10335346688 985824672 10.741 95 34 68 0 1010619712 17245520 7549520
05:31:38 6494 8192000000 9945398384 10330685440 999305152 10.872 97 29 57 1 1026070400 20678784 6086464
05:32:46 69673 8192000000 9945805488 10338447360 1017639952 11.049 98 33 67 0 1041698080 16917584 7140544
05:34:57 8599 8192000000 9975697744 10383941632 1060007920 11.457 100 34 68 3 1084871312 16377520 8485872
05:36:09 6617 8192000000 9988802496 10377527296 1069987696 11.552 100 32 63 1 1092694192 15646720 7059776
05:37:17 26717 8192000000 10009384752 10404204544 1091285920 11.755 100 48 94 1 1117126032 14959776 10880336
05:39:07 74745 8192000000 10080235088 10490753024 1114089520 11.971 100 43 82 2 1181966688 59774736 8102432
05:41:34 47438 8192000000 10140301776 10550919168 1136607008 12.184 100 47 93 2 1230972704 84369024 9996672
05:43:25 11359 8192000000 10151092704 10561368064 1148080128 12.291 100 38 75 1 1257773392 100943088 8750176
05:44:40 19411 8192000000 10238174992 10645176320 1160396800 12.407 100 66 132 0 1337632016 162172352 15062864
05:46:07 1690 8192000000 10248262736 10653831168 1155980176 12.366 99 79 155 0 1346019152 173039936 16999040
05:47:15 3026 8192000000 10272137376 10678415360 1172663184 12.522 100 64 127 0 1377263776 190431472 14169120
05:48:22 1622 8192000000 10273592144 10681090048 1162474496 12.426 99 69 138 0 1370881936 193478896 14928544
05:49:29 5556 8192000000 10310021696 10723708928 1177193408 12.564 100 60 119 1 1415235744 224439696 13602640
05:50:41 2395 8192000000 10342257648 10737836032 1170904224 12.505 100 82 165 0 1438405456 248083440 19417792
05:51:48 1808 8192000000 10395936592 10793492480 1165300928 12.453 99 92 183 0 1487525440 301289696 20934816
05:52:54 6997 8192000000 10397598288 10804645888 1153722864 12.344 99 60 120 1 1496085696 327055040 15307792
05:54:12 3316 8192000000 10407340896 10848907264 1167539472 12.474 100 78 156 0 1499263264 313134208 18589584
05:55:18 6177 8192000000 10330871136 10743054336 1153487584 12.342 99 51 101 0 1427195632 261534992 12173056
05:56:29 14143 8192000000 10336795776 10783219712 1148270160 12.293 100 34 68 0 1450748960 293501424 8977376
05:57:47 10410 8192000000 10296988112 10732548096 1146256064 12.274 100 33 65 1 1406387584 252029296 8102224
05:59:01 23166 8192000000 10334358624 10772475904 1121471424 12.041 99 28 56 0 1421037040 292110816 7454800
06:00:46 23646 8192000000 10203934272 10679894016 1112069264 11.952 98 20 40 0 1329547744 212699648 4778832
06:02:29 38368 8192000000 10213967184 10657312768 1121813488 12.044 99 18 36 1 1313739984 187350352 4576144
06:04:11 41156 8192000000 10057059776 10483552256 1104311168 11.879 99 10 20 0 1137838800 31800768 1726864
06:05:54 79955 8192000000 10075605648 10511196160 1081778480 11.664 96 30 60 0 1129737296 41831376 6127440
06:08:20 84884 8192000000 10093922608 10548994048 1122230976 12.048 100 17 30 3 1186174704 60879296 3064432
06:11:17 94418 8192000000 10113608512 10553507840 1095101920 11.791 96 10 20 0 1155473040 58317680 2053440
06:13:53 54580 8192000000 10091963968 10561474560 1118042624 12.008 98 15 30 0 1172473152 51880272 2550256
06:15:48 84075 8192000000 10081449472 10549309440 1121233184 12.039 99 7 14 0 1155044208 32581536 1229488
06:18:15 58888 8192000000 10079163936 10506629120 1122867920 12.054 99 32 63 3 1166877008 36351600 7657488
06:20:22 59638 8192000000 10135084336 10597957632 1141158256 12.226 100 29 58 1 1257607696 110498304 5951136
06:22:27 46039 8192000000 10108741760 10548195328 1146826608 12.280 100 25 49 2 1204269872 52033568 5409696
06:24:15 20815 8192000000 10161919472 10588241920 1157118736 12.376 100 65 131 3 1240541968 69607552 13815680
06:25:45 40877 8192000000 10089607056 10529079296 1157364656 12.379 100 24 50 0 1209596384 47430640 4801088
06:27:33 36158 8192000000 10094722544 10570477568 1133148064 12.151 98 10 20 0 1177885168 42619152 2117952
06:29:12 71718 8192000000 10103578544 10560933888 1118357472 12.011 97 24 48 0 1173076576 48583536 6135568
06:31:50 50768 8192000000 10061650384 10563940352 1106914224 11.903 97 16 32 1 1185472480 75122768 3435488
06:33:42 60155 8192000000 10113587776 10601353216 1128051296 12.103 99 8 16 0 1203006944 73194624 1761024
06:35:46 24410 8192000000 10080995872 10515861504 1110406960 11.936 97 26 51 2 1148651824 31507248 6737616
06:37:15 46877 8192000000 10064876720 10496274432 1058067440 11.438 93 24 48 1 1097452896 35030512 4354944
06:39:03 16496 8192000000 10078976016 10514726912 1117276880 12.001 99 30 60 0 1163698656 40777888 5643888
06:40:28 32331 8192000000 10113671328 10596679680 1106686464 11.901 98 22 44 0 1204501728 93386080 4429184
06:42:04 41476 8192000000 10088340768 10537066496 1113192160 11.963 98 24 48 0 1171651728 53595840 4863728
06:43:59 60651 8192000000 10109364896 10541760512 1087406112 11.718 96 21 42 0 1133369712 41269408 4694192
06:46:05 64348 8192000000 10111488352 10582839296 1084882608 11.694 96 24 48 1 1167085872 77599776 4603488
06:48:15 48542 8192000000 10139122880 10592276480 1106175248 11.896 98 29 58 0 1219024224 107149440 5699536
06:51:03 56288 8192000000 10041053920 10503520256 1098926688 11.827 98 20 40 0 1143840608 41093344 3820576
06:53:00 63532 8192000000 10036725728 10501980160 1091186576 11.754 97 8 16 1 1128807888 36332928 1288384
06:55:04 61782 8192000000 10086658288 10530705408 1127941264 12.102 100 31 61 0 1168034208 33742992 6349952
06:57:14 47861 8192000000 10124136176 10584563712 1125941168 12.083 99 13 26 2 1218138880 89044096 3153616
06:59:35 55319 8192000000 10123323120 10598793216 1118804848 12.016 99 11 22 0 1234855504 113513360 2537296
07:01:32 25729 8192000000 10054871728 10493710336 1116941568 11.998 99 8 16 1 1145313664 26805056 1567040
07:03:03 41731 8192000000 10101024704 10550689792 1129454928 12.116 99 33 67 1 1198996144 62241616 7299600
07:04:46 33960 8192000000 10063997712 10502873088 1101672320 11.854 97 34 68 1 1142647440 34106464 6868656
07:06:24 36198 8192000000 10115365072 10555207680 1130471344 12.126 100 34 68 0 1196117456 58933536 6712576
07:08:07 76344 8192000000 10080949536 10533150720 1118220720 12.010 98 19 38 0 1160660576 38549168 3890688
07:10:25 166946 8192000000 10063382160 10564046848 1073564288 11.586 94 7 14 0 1114972336 39684640 1723408
07:15:05 35534 8192000000 10047572912 10510667776 1120224352 12.029 99 12 24 1 1148513248 25981584 2307312
07:16:41 52501 8192000000 10069743104 10536345600 1136133984 12.179 100 11 22 0 1175287408 36933472 2219952
07:18:35 58095 8192000000 10112227552 10587783168 1130040384 12.122 99 21 42 0 1207303472 72297632 4965456
07:20:36 53298 8192000000 10122335008 10570682368 1123767200 12.063 98 20 40 1 1194196624 65993328 4436096
07:22:41 44343 8192000000 10115952432 10591981568 1130108224 12.122 99 32 64 0 1222802992 85965488 6729280
07:24:25 27333 8192000000 10065947600 10502909952 1096688560 11.806 96 18 36 0 1133363616 33065152 3609904
07:25:54 81958 8192000000 10088987584 10547167232 1067669296 11.530 94 25 50 2 1134699360 61973760 5056304
07:28:18 61518 8192000000 10069154608 10490101760 1069172720 11.544 95 33 66 0 1130661872 55272688 6216464
07:30:27 64788 8192000000 10068171872 10600488960 1082066288 11.667 97 9 18 2 1200710976 116506128 2138560
07:32:38 124646 8192000000 10068486896 10569961472 1089436384 11.737 98 25 50 1 1183339760 88410816 5492560
07:35:55 78099 8192000000 9774187712 10187685888 848872544 9.389 77 6 12 0 873650816 23702624 1075648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-10-2021 07:39:06 to 26-10-2021 07:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 162 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2021 07:39:22 to 26-10-2021 07:39:24.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240969
MON$OLDEST_ACTIVE               240970
MON$OLDEST_SNAPSHOT             240970
MON$NEXT_TRANSACTION            240970
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                       2069920
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             813
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2642 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2642 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2642 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2642 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 ---
--- end of diff output ---

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

26.10.21 07:39:25. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-10-26 07:39:27 No severe PSQL-related problems occured
26.10.21 07:39:28. End of report.