Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.12.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 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 16580292 kB
MemAvailable 31542308 kB
Buffers 0 kB
Cached 15139588 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-06 07:37:13
DTS_END                         2021-12-06 07:37:13.5140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45790
TRN_ID                          246061
ATT_ID                          636
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: 9076.26 21994 1089151
customer order: creation 181.07 397 21728
customer order: refuse 14.93 312 1791
order to supplier: creation 48.02 1420 5762
order to supplier: removal 3.17 98948 380
invoice (draft): creation 48.39 3723 5807
invoice (draft): removal 50.34 799 6041
invoice accept: apply 42.80 16515 5136
invoice accept: cancel 46.18 786 5542
customer reserve: creation 3831.69 168 459803
customer reserve: removal 4501.04 55 540125
realization accept: apply 85.94 162 10313
realization accept: cancel 87.60 107 10512
payment from customer: creation 62.80 116 7536
payment from customer: removal 5.77 91 692
payment to supplier: creation 51.10 141 6132
payment to supplier: removal 3.59 69 431
service: total inventory turnovers 4.18 3063 502
service: total monetary turnovers 6.66 474 799
service: aggregate perf. data 0.96 15935 115
service: refresh index statistics 0.03 296600 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-12-2021 07:39:13 to 06-12-2021 07:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2211
TEST_TIME 2 3647
TEST_TIME 3 3208
TEST_TIME 4 3888
TEST_TIME 5 4435
TEST_TIME 6 4795
TEST_TIME 7 4910
TEST_TIME 8 5015
TEST_TIME 9 5199
TEST_TIME 10 5448
TEST_TIME 11 5591
TEST_TIME 12 6096
TEST_TIME 13 6149
TEST_TIME 14 6737
TEST_TIME 15 6813
TEST_TIME 16 7512
TEST_TIME 17 8444
TEST_TIME 18 8514
TEST_TIME 19 8662
TEST_TIME 20 8854
TEST_TIME 21 9263
TEST_TIME 22 9917
TEST_TIME 23 9775
TEST_TIME 24 10279
TEST_TIME 25 10638
TEST_TIME 26 10563
TEST_TIME 27 10560
TEST_TIME 28 10362
TEST_TIME 29 10386
TEST_TIME 30 10526
TEST_TIME 31 10563
TEST_TIME 32 10575
TEST_TIME 33 10266
TEST_TIME 34 9998
TEST_TIME 35 10098
TEST_TIME 36 9944
TEST_TIME 37 9907
TEST_TIME 38 9971
TEST_TIME 39 9934
TEST_TIME 40 9903
TEST_TIME 41 9792
TEST_TIME 42 9750
TEST_TIME 43 9660
TEST_TIME 44 9606
TEST_TIME 45 9633
TEST_TIME 46 9668
TEST_TIME 47 9624
TEST_TIME 48 9675
TEST_TIME 49 9615
TEST_TIME 50 9775
TEST_TIME 51 9769
TEST_TIME 52 9754
TEST_TIME 53 9749
TEST_TIME 54 9840
TEST_TIME 55 9834
TEST_TIME 56 9886
TEST_TIME 57 9844
TEST_TIME 58 9705
TEST_TIME 59 9685
TEST_TIME 60 9598
TEST_TIME 61 9543
TEST_TIME 62 9547
TEST_TIME 63 9537
TEST_TIME 64 9473
TEST_TIME 65 9444
TEST_TIME 66 9497
TEST_TIME 67 9477
TEST_TIME 68 9501
TEST_TIME 69 9490
TEST_TIME 70 9494
TEST_TIME 71 9559
TEST_TIME 72 9582
TEST_TIME 73 9601
TEST_TIME 74 9562
TEST_TIME 75 9624
TEST_TIME 76 9631
TEST_TIME 77 9527
TEST_TIME 78 9449
TEST_TIME 79 9469
TEST_TIME 80 9429
TEST_TIME 81 9344
TEST_TIME 82 9311
TEST_TIME 83 9342
TEST_TIME 84 9344
TEST_TIME 85 9312
TEST_TIME 86 9344
TEST_TIME 87 9389
TEST_TIME 88 9374
TEST_TIME 89 9351
TEST_TIME 90 9403
TEST_TIME 91 9420
TEST_TIME 92 9471
TEST_TIME 93 9495
TEST_TIME 94 9492
TEST_TIME 95 9413
TEST_TIME 96 9346
TEST_TIME 97 9298
TEST_TIME 98 9307
TEST_TIME 99 9255
TEST_TIME 100 9265
TEST_TIME 101 9230
TEST_TIME 102 9201
TEST_TIME 103 9188
TEST_TIME 104 9187
TEST_TIME 105 9297
TEST_TIME 106 9306
TEST_TIME 107 9278
TEST_TIME 108 9273
TEST_TIME 109 9268
TEST_TIME 110 9321
TEST_TIME 111 9412
TEST_TIME 112 9376
TEST_TIME 113 9299
TEST_TIME 114 9255
TEST_TIME 115 9259
TEST_TIME 116 9197
TEST_TIME 117 9150
TEST_TIME 118 9142
TEST_TIME 119 9143
TEST_TIME 120 9078

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-12-2021 07:39:17 to 06-12-2021 07:39:20.

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 1515946 1515946 0 0.00 0 36708 6 0 0
doc_list_biud 1515946 1515946 0 0.00 0 38583 25 0 0
sp_add_invoice_to_stock 5270 5136 134 2.54 19 191203 16515 0 134
sp_cancel_adding_invoice 5542 5542 0 0.00 6 63058 786 0 0
sp_cancel_client_order 1791 1791 0 0.00 4 13666 312 0 0
sp_cancel_customer_reserve 540125 540125 0 0.00 1 24445 55 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 8742 92 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 4846 70 0 0
sp_cancel_supplier_invoice 6052 6041 11 0.18 2 43119 799 0 11
sp_cancel_supplier_order 380 380 0 0.00 6 982425 98949 0 0
sp_cancel_write_off 10512 10512 0 0.00 1 11780 108 0 0
sp_client_order 21728 21728 0 0.00 4 22642 398 0 0
sp_customer_reserve 459803 459803 0 0.00 0 130825 168 0 0
sp_fill_shopping_cart 33287 33287 0 0.00 1 22867 47 0 0
sp_fill_shopping_cart_clo_res 459797 459797 0 0.00 0 16094 15 0 0
sp_get_clo_for_invoice 5129 5129 0 0.00 0 22650 329 0 0
sp_kill_cost_storno 17177 17177 0 0.00 0 9416 49 0 0
sp_kill_qstorno_ret_qs2qd 1097183 1097183 0 0.00 0 17097 30 0 0
sp_kill_qty_storno 579833 579833 0 0.00 0 38583 65 0 0
sp_lock_selected_doc 21372 21372 0 0.00 0 6246 4 0 0
sp_make_cost_storno 29110 29110 0 0.00 0 15571 53 0 0
sp_multiply_rows_for_pdistr 29110 29110 0 0.00 0 9589 34 0 0
sp_multiply_rows_for_qdistr 21728 21728 0 0.00 0 14470 220 0 0
sp_pay_from_customer 7536 7536 0 0.00 4 13620 116 0 0
sp_pay_to_supplier 6132 6132 0 0.00 5 11001 141 0 0
sp_payment_common 13668 13668 0 0.00 1 9208 24 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 13658 296 0 0
sp_qd_handle_on_invoice_upd_sts 10671 10671 0 0.00 0 38582 315 0 0
sp_qd_handle_on_reserve_upd_sts 10313 10313 0 0.00 0 17558 46 0 0
sp_reserve_write_off 10313 10313 0 0.00 7 17618 162 0 0
sp_supplier_invoice 5807 5807 0 0.00 7 136567 3724 0 0
sp_supplier_order 5762 5762 0 0.00 5 63744 1420 0 0
srv_aggregate_perf_data 116 115 1 0.86 2928 312190 15936 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1461 142431 49012 0 0
srv_find_qd_qs_mism 1040502 1040502 0 0.00 0 36706 5 0 0
srv_make_invnt_saldo 502 502 0 0.00 57 97901 3063 0 0
srv_make_money_saldo 799 799 0 0.00 1 15756 475 0 0
srv_recalc_idx_stat 4 4 0 0.00 258500 327818 296600 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 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 5136 5136 0 0.00 0 18903 150 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 7697 91 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 6767 11 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 1576 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 2400 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 366 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 809 10 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 18392 1445 0 0
t$perf-norm:sp_cancel_write_off 1061 1061 0 0.00 0 1516 7 0 0
t$perf-norm:sp_client_order 21728 21728 0 0.00 0 14975 17 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 6808 12 0 0
t$perf-norm:sp_pay_from_customer 7536 7536 0 0.00 0 17016 11 0 0
t$perf-norm:sp_pay_to_supplier 6132 6132 0 0.00 0 15806 10 0 0
t$perf-norm:sp_reserve_write_off 10313 10313 0 0.00 0 5398 9 0 0
t$perf-norm:sp_supplier_invoice 5807 5807 0 0.00 0 8677 14 0 0
t$perf-norm:sp_supplier_order 5762 5762 0 0.00 0 8682 18 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 165 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1668 29 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 660 5 0 0
t$perf-norm:srv_make_money_saldo 799 799 0 0.00 0 2189 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10524 10256 268 2.54 0 8688 16 0 268
v_all_customers 39618 39618 0 0.00 0 15 1 0 0
v_all_suppliers 31628 31628 0 0.00 0 16 1 0 0
v_all_wares 43454 43454 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 5515 14 0 0
v_cancel_client_order 3582 3582 0 0.00 0 1358 3 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 2239 10 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 2175 8 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 420 4 0 0
v_cancel_supplier_invoice 856 834 22 2.57 0 834 12 0 22
v_cancel_supplier_order 760 760 0 0.00 0 1568 8 0 0
v_cancel_write_off 2122 2122 0 0.00 0 2050 14 0 0
v_max_id_clo_ord 5761 5761 0 0.00 0 983 1 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 5630 22 0 0
v_max_id_ord_sup 5799 5799 0 0.00 0 6477 7 0 0
v_max_non_paid_invoice 1878 1878 0 0.00 0 3631 5 0 0
v_max_non_paid_realizn 3936 3936 0 0.00 0 3213 4 0 0
v_min_id_clo_ord 5761 5761 0 0.00 0 2843 5 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 6385 18 0 0
v_min_id_ord_sup 5799 5799 0 0.00 0 5260 9 0 0
v_min_non_paid_invoice 6132 6132 0 0.00 0 6846 18 0 0
v_min_non_paid_realizn 7536 7536 0 0.00 0 5686 15 0 0
v_our_firm 11036 11036 0 0.00 0 19 1 0 0
v_reserve_write_off 20626 20626 0 0.00 0 5424 5 0 0
x_lock_depdocs_on_canc_invoice 11166 11166 0 0.00 0 15600 104 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 5062 307 0 0
x_make_qstorno_1000_1200 5762 5762 0 0.00 1 63725 1342 0 0
x_make_qstorno_1200_2000 5807 5807 0 0.00 3 134011 3540 0 0
x_make_qstorno_2100_3300 459803 459803 0 0.00 0 130823 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-12-2021 07:39:21 to 06-12-2021 07:39:25.

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:17 13 8192000000 8802162448 8814649344 5768432 0.070 3 1 2 0 5869600 65392 35776
05:08:18 35117 8192000000 9725628960 10019995648 798441296 8.881 100 88 176 1 827930640 8286656 21202688
05:09:57 27054 8192000000 9761758288 10064072704 830612112 9.205 100 89 178 0 865524016 13346176 21565728
05:11:36 14082 8192000000 9809371424 10128023552 865646112 9.557 100 80 160 1 912772880 28831280 18295488
05:12:55 12289 8192000000 9880343488 10209722368 915988800 10.056 100 86 172 0 984951040 50592464 18369776
05:14:10 7735 8192000000 9932025536 10276323328 961158384 10.500 100 82 165 1 1045287008 66703296 17425328
05:15:21 4247 8192000000 9973169712 10328829952 1000854800 10.887 100 89 175 2 1080139744 61776992 17507952
05:16:29 10310 8192000000 10010107120 10374774784 1023221648 11.103 100 80 154 0 1124195824 85803248 15170928
05:17:40 2368 8192000000 10039467584 10407313408 1053971264 11.399 100 93 186 0 1147414016 73729184 19713568
05:18:43 20170 8192000000 10025350208 10407362560 1066902544 11.522 100 75 149 0 1143063712 60442416 15718752
05:20:04 4775 8192000000 9991041296 10378567680 1074422448 11.594 100 40 78 0 1110564496 26767328 9374720
05:21:09 4681 8192000000 9997432480 10406576128 1085850976 11.703 100 26 52 0 1130272736 38808992 5612768
05:22:15 4681 8192000000 9997800336 10396491776 1092809856 11.769 100 45 93 3 1112693712 10869232 9014624
05:23:22 56646 8192000000 9995933376 10415620096 1092462704 11.766 100 39 78 0 1118611504 17876912 8271888
05:25:19 6023 8192000000 10026745344 10438860800 1099534000 11.833 100 39 77 1 1146155168 38597856 8023312
05:26:26 5417 8192000000 9991144576 10400600064 1080850752 11.656 99 26 52 0 1102137904 15454592 5832560
05:27:36 6001 8192000000 10001375024 10406277120 1054619520 11.405 97 41 83 1 1084943904 21550800 8773584
05:28:46 47600 8192000000 9927938592 10330103808 916794592 10.064 86 39 77 1 955640688 30201840 8644256
05:30:34 4570 8192000000 9945375584 10321723392 994729120 10.827 96 48 93 1 1036947920 31121088 11097712
05:31:39 8294 8192000000 9937334368 10322862080 993581536 10.816 97 30 62 0 1026703584 26327616 6794432
05:32:50 8443 8192000000 9953981168 10355646464 1041744944 11.281 99 35 70 2 1074483200 24427488 8310768
05:34:01 20739 8192000000 9964217408 10366201856 1053012832 11.390 100 24 48 1 1084436032 25766976 5656224
05:35:27 8450 8192000000 10000758400 10393714688 1070152080 11.554 100 51 101 1 1111357424 28705648 12499696
05:36:36 5727 8192000000 9998901584 10406662144 1075714560 11.607 100 49 100 2 1106851520 20304560 10832400
05:37:48 9708 8192000000 10028432592 10417434624 1100602208 11.843 100 43 84 3 1130077696 20061552 9413936
05:39:00 87794 8192000000 10058289632 10467586048 1113545248 11.966 100 35 70 0 1173992496 52408848 8038400
05:41:31 5772 8192000000 10124699840 10529923072 1136199840 12.180 100 44 87 0 1232444848 86889952 9355056
05:42:42 12935 8192000000 10162470464 10563825664 1138404176 12.201 99 59 118 1 1260798704 109824576 12569952
05:44:00 4254 8192000000 10185517872 10588860416 1152305440 12.331 100 59 117 0 1290430928 125890896 12234592
05:45:10 5004 8192000000 10226651856 10630090752 1159687040 12.400 100 59 118 1 1325659424 152582624 13389760
05:46:17 3623 8192000000 10245200800 10650927104 1166879760 12.468 100 64 127 0 1350072944 168622176 14571008
05:47:24 1984 8192000000 10262423216 10673164288 1166321664 12.462 100 71 140 1 1373279664 190812288 16145712
05:48:35 7766 8192000000 10306860784 10717556736 1175416064 12.547 100 66 131 0 1417355696 226789280 15150352
05:49:46 4792 8192000000 10318946944 10719637504 1163666704 12.438 100 81 160 0 1418626112 236333392 18626016
05:50:56 1459 8192000000 10343053520 10743783424 1166185840 12.461 100 92 185 1 1445760448 258343600 21231008
05:52:01 4592 8192000000 10315714576 10717741056 1141404528 12.229 100 74 147 3 1419359840 260184544 17770768
05:53:13 2530 8192000000 10349431088 10743857152 1126109728 12.085 100 90 180 0 1452432000 305167472 21154800
05:54:19 2743 8192000000 10249146800 10678636544 1130109856 12.122 100 65 127 1 1350775184 204932080 15733248
05:55:24 22169 8192000000 10260838736 10682548224 1138316096 12.200 100 35 69 3 1347417472 200911552 8189824
05:56:57 21995 8192000000 10236618768 10687901696 1103846016 11.874 98 32 64 0 1328117952 216802288 7469648
05:58:23 18766 8192000000 10188442800 10607865856 1090865680 11.751 98 31 62 0 1270199136 171644112 7689344
05:59:46 25609 8192000000 10154316208 10581757952 1115679520 11.986 99 20 38 2 1244878704 124511136 4688048
06:01:17 64353 8192000000 10079277776 10524008448 1092309376 11.765 98 12 24 1 1207980048 113019376 2651296
06:03:22 47673 8192000000 10051356400 10515480576 1079180928 11.640 98 7 14 1 1122126688 41315408 1630352
06:05:38 28383 8192000000 10086493200 10505027584 1104340256 11.879 99 33 62 1 1173493952 63117392 6036304
06:07:10 53645 8192000000 10097243744 10556293120 1128021184 12.103 100 21 39 2 1198531600 66144032 4366384
06:09:07 103946 8192000000 10068791792 10492764160 1135034848 12.169 100 18 34 0 1158660848 19692400 3933600
06:11:51 17691 8192000000 10080918944 10506342400 1115315904 11.983 99 59 110 2 1171880800 44730928 11833968
06:13:09 11222 8192000000 10088866672 10507079680 1140821216 12.223 100 72 142 0 1169599760 13416352 15362192
06:14:27 59532 8192000000 10085403472 10545524736 1120421824 12.031 98 14 28 2 1179769984 56249920 3098240
06:16:31 75594 8192000000 10053068704 10520272896 1110178864 11.934 97 15 30 1 1171190080 58009488 3001728
06:18:47 142431 8192000000 10091229296 10560061440 1134094224 12.160 99 15 30 1 1200270080 63130496 3045360
06:22:10 42203 8192000000 10104841712 10572312576 1138800352 12.204 99 21 39 1 1186417200 43259648 4357200
06:23:59 46493 8192000000 10086983696 10542047232 1149920400 12.309 100 21 40 1 1184685632 30755952 4009280
06:25:46 46958 8192000000 10137386352 10591100928 1127637248 12.099 98 13 26 0 1202385568 71917248 2831072
06:27:41 30481 8192000000 10087097952 10543329280 1133092016 12.151 100 23 46 1 1213469648 75501088 4876544
06:29:13 73132 8192000000 10110530208 10592137216 1087378704 11.718 95 12 24 0 1173942064 83913536 2649824
06:31:29 35326 8192000000 10061545968 10499670016 1061066512 11.467 95 19 38 2 1131026720 65689520 4270688
06:33:05 63319 8192000000 10077287552 10532548608 1080365872 11.651 97 19 37 1 1121576688 37107520 4103296
06:35:19 116452 8192000000 10017738048 10447056896 1045930848 11.322 93 24 48 0 1078088416 27502864 4654704
06:38:18 60138 8192000000 10077991232 10522988544 1109215856 11.925 99 14 28 1 1183170192 70774464 3179872
06:40:30 28480 8192000000 10038699872 10562519040 1090419136 11.747 98 6 12 0 1141115728 49493840 1202752
06:42:00 71416 8192000000 10112636160 10541596672 1124090960 12.066 100 26 53 2 1206606576 76278112 6237504
06:44:52 47445 8192000000 10084118912 10548846592 1093883920 11.780 97 40 80 1 1166166384 63487744 8794720
06:46:40 59856 8192000000 10140353440 10572980224 1132999680 12.150 100 25 52 0 1224970112 86854816 5115616
06:48:53 61923 8192000000 10110102448 10583826432 1120693264 12.034 99 27 54 0 1166177632 40279376 5204992
06:51:04 29276 8192000000 10080505024 10507010048 1118895104 12.017 99 38 76 1 1152301024 26291808 7114112
06:52:38 132103 8192000000 10075858800 10515963904 1124221584 12.067 99 26 51 0 1171467552 41954704 5291264
06:55:56 40088 8192000000 10069327136 10510852096 1098120128 11.820 97 32 65 2 1183778608 78647840 7010640
06:57:39 81550 8192000000 10101781808 10558447616 1125900768 12.083 99 12 24 1 1174280384 45828352 2551264
07:00:01 79397 8192000000 10084413952 10585575424 1139550656 12.211 100 20 40 1 1233469184 88886160 5032368
07:02:29 39869 8192000000 10096771952 10572046336 1143521712 12.249 100 22 45 0 1219514464 70844288 5148464
07:04:12 71573 8192000000 10090220576 10538409984 1076878848 11.618 94 12 24 0 1163701280 84294480 2527952
07:06:29 56219 8192000000 10132486432 10595958784 1123758960 12.062 98 27 52 0 1208577936 79479136 5339840
07:08:25 50777 8192000000 10125387648 10585751552 1141656576 12.231 99 26 51 2 1188445792 41969408 4819808
07:10:28 82650 8192000000 10074196800 10531799040 1095856608 11.798 95 11 22 0 1149820928 51945056 2019264
07:12:51 108768 8192000000 10114004496 10580570112 1133171280 12.151 99 28 56 0 1221905888 83076544 5658064
07:16:15 52799 8192000000 10075591104 10560155648 1146835984 12.280 100 11 22 0 1199683520 50620720 2226816
07:18:13 73707 8192000000 10115291168 10574217216 1091276672 11.755 94 24 48 1 1150706544 54332736 5097136
07:20:33 80855 8192000000 10141877072 10622689280 1120367328 12.030 98 16 32 1 1210566320 86742544 3456448
07:22:54 80498 8192000000 10041713472 10560770048 1074586144 11.596 97 5 10 0 1121319264 45887376 845744
07:25:15 45450 8192000000 10084648416 10538987520 1104863616 11.884 99 22 44 0 1154980640 45658384 4458640
07:27:05 60562 8192000000 10131791408 10602516480 1110498736 11.937 99 30 60 1 1191585504 74869968 6216800
07:29:11 117704 8192000000 10048696384 10530811904 1088342304 11.727 97 17 34 0 1138479152 46537600 3599248
07:32:32 47799 8192000000 10051222672 10533191680 1116608912 11.995 100 6 12 0 1154275232 36300720 1365600
07:35:37 75443 8192000000 10065816176 10511319040 1115416640 11.984 100 24 49 0 1208987248 87765488 5805120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-12-2021 07:39:27 to 06-12-2021 07:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 134 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 06-12-2021 07:39:43 to 06-12-2021 07:39:44.

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          244701
MON$OLDEST_ACTIVE               246180
MON$OLDEST_SNAPSHOT             246180
MON$NEXT_TRANSACTION            246180
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                       2055520
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             824
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2675 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2675 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2675 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2675 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 ---
:98: 
:99: mscs-test-161.budzdorov.ru    Mon Dec  6 07:37:21 2021
:100:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:101: 
--- end of diff output ---

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

06.12.21 07:39:45. 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-06 07:39:48 No severe PSQL-related problems occured
06.12.21 07:39:49. End of report.