Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.08.2021 16: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 16204876 kB
MemAvailable 30975184 kB
Buffers 0 kB
Cached 15505500 kB
SwapCached 1308 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-08-25 16:37:47
DTS_END                         2021-08-25 16:37:47.6090
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(57:17)
IP                              ::1/47896
TRN_ID                          241836
ATT_ID                          679
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: 9296.38 21938 1115566
customer order: creation 169.26 370 20311
customer order: refuse 14.89 337 1787
order to supplier: creation 45.23 1336 5428
order to supplier: removal 3.53 105606 423
invoice (draft): creation 44.59 3339 5351
invoice (draft): removal 55.08 836 6609
invoice accept: apply 40.03 16500 4803
invoice accept: cancel 49.79 796 5975
customer reserve: creation 3736.68 160 448402
customer reserve: removal 4829.60 60 579552
realization accept: apply 82.74 194 9929
realization accept: cancel 95.96 107 11515
payment from customer: creation 59.41 117 7129
payment from customer: removal 5.98 87 718
payment to supplier: creation 48.93 124 5871
payment to supplier: removal 3.25 83 390
service: total inventory turnovers 4.47 1040 536
service: total monetary turnovers 6.18 43 742
service: aggregate perf. data 0.76 24182 91
service: refresh index statistics 0.03 283439 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-08-2021 16:39:18 to 25-08-2021 16:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4687
TEST_TIME 2 4623
TEST_TIME 3 4990
TEST_TIME 4 4613
TEST_TIME 5 4421
TEST_TIME 6 4368
TEST_TIME 7 4474
TEST_TIME 8 4675
TEST_TIME 9 4824
TEST_TIME 10 4993
TEST_TIME 11 5006
TEST_TIME 12 5334
TEST_TIME 13 5581
TEST_TIME 14 5789
TEST_TIME 15 5898
TEST_TIME 16 6378
TEST_TIME 17 6723
TEST_TIME 18 6996
TEST_TIME 19 7722
TEST_TIME 20 8271
TEST_TIME 21 8209
TEST_TIME 22 8571
TEST_TIME 23 8891
TEST_TIME 24 8766
TEST_TIME 25 8929
TEST_TIME 26 9113
TEST_TIME 27 10122
TEST_TIME 28 10703
TEST_TIME 29 10769
TEST_TIME 30 10531
TEST_TIME 31 10515
TEST_TIME 32 10389
TEST_TIME 33 10226
TEST_TIME 34 10304
TEST_TIME 35 10272
TEST_TIME 36 10095
TEST_TIME 37 9995
TEST_TIME 38 9871
TEST_TIME 39 9780
TEST_TIME 40 10026
TEST_TIME 41 10133
TEST_TIME 42 10100
TEST_TIME 43 9996
TEST_TIME 44 9900
TEST_TIME 45 9806
TEST_TIME 46 9664
TEST_TIME 47 9669
TEST_TIME 48 9616
TEST_TIME 49 9608
TEST_TIME 50 9650
TEST_TIME 51 9537
TEST_TIME 52 9579
TEST_TIME 53 9612
TEST_TIME 54 9533
TEST_TIME 55 9572
TEST_TIME 56 9836
TEST_TIME 57 9856
TEST_TIME 58 9830
TEST_TIME 59 9741
TEST_TIME 60 9886
TEST_TIME 61 9889
TEST_TIME 62 9968
TEST_TIME 63 9876
TEST_TIME 64 9782
TEST_TIME 65 9669
TEST_TIME 66 9661
TEST_TIME 67 9550
TEST_TIME 68 9663
TEST_TIME 69 9596
TEST_TIME 70 9591
TEST_TIME 71 9561
TEST_TIME 72 9562
TEST_TIME 73 9565
TEST_TIME 74 9611
TEST_TIME 75 9564
TEST_TIME 76 9556
TEST_TIME 77 9619
TEST_TIME 78 9625
TEST_TIME 79 9651
TEST_TIME 80 9747
TEST_TIME 81 9748
TEST_TIME 82 9681
TEST_TIME 83 9628
TEST_TIME 84 9534
TEST_TIME 85 9526
TEST_TIME 86 9521
TEST_TIME 87 9457
TEST_TIME 88 9427
TEST_TIME 89 9392
TEST_TIME 90 9316
TEST_TIME 91 9363
TEST_TIME 92 9322
TEST_TIME 93 9322
TEST_TIME 94 9381
TEST_TIME 95 9399
TEST_TIME 96 9435
TEST_TIME 97 9460
TEST_TIME 98 9404
TEST_TIME 99 9469
TEST_TIME 100 9508
TEST_TIME 101 9497
TEST_TIME 102 9488
TEST_TIME 103 9534
TEST_TIME 104 9560
TEST_TIME 105 9544
TEST_TIME 106 9545
TEST_TIME 107 9476
TEST_TIME 108 9496
TEST_TIME 109 9506
TEST_TIME 110 9468
TEST_TIME 111 9471
TEST_TIME 112 9419
TEST_TIME 113 9382
TEST_TIME 114 9378
TEST_TIME 115 9337
TEST_TIME 116 9330
TEST_TIME 117 9342
TEST_TIME 118 9296
TEST_TIME 119 9310
TEST_TIME 120 9298

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-08-2021 16:39:21 to 25-08-2021 16:39:25.

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 1530343 1530343 0 0.00 0 30451 6 0 0
doc_list_biud 1530343 1530343 0 0.00 0 26046 29 0 0
sp_add_invoice_to_stock 4906 4803 103 2.09 16 157962 16500 0 103
sp_cancel_adding_invoice 5975 5975 0 0.00 5 52752 797 0 0
sp_cancel_client_order 1787 1787 0 0.00 4 11619 337 0 0
sp_cancel_customer_reserve 579552 579552 0 0.00 1 26049 60 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 2 5458 88 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 5862 83 0 0
sp_cancel_supplier_invoice 6613 6609 4 0.06 2 14716 837 0 4
sp_cancel_supplier_order 423 423 0 0.00 6 1023727 105606 0 0
sp_cancel_write_off 11515 11515 0 0.00 1 15000 107 0 0
sp_client_order 20311 20311 0 0.00 3 31571 371 0 0
sp_customer_reserve 448402 448402 0 0.00 0 80299 161 0 0
sp_fill_shopping_cart 31088 31088 0 0.00 1 24001 37 0 0
sp_fill_shopping_cart_clo_res 448397 448397 0 0.00 0 17195 13 0 0
sp_get_clo_for_invoice 4798 4798 0 0.00 0 23071 304 0 0
sp_kill_cost_storno 18598 18598 0 0.00 0 23071 59 0 0
sp_kill_qstorno_ret_qs2qd 1177651 1177651 0 0.00 0 26041 33 0 0
sp_kill_qty_storno 620588 620588 0 0.00 0 26045 71 0 0
sp_lock_selected_doc 20502 20502 0 0.00 0 11636 5 0 0
sp_make_cost_storno 27727 27727 0 0.00 0 19441 60 0 0
sp_multiply_rows_for_pdistr 27727 27727 0 0.00 0 9623 31 0 0
sp_multiply_rows_for_qdistr 20311 20311 0 0.00 0 31559 164 0 0
sp_pay_from_customer 7129 7129 0 0.00 4 21730 118 0 0
sp_pay_to_supplier 5871 5871 0 0.00 5 10987 124 0 0
sp_payment_common 13000 13000 0 0.00 1 21726 35 0 0
sp_qd_handle_on_cancel_clo 1787 1787 0 0.00 0 6941 307 0 0
sp_qd_handle_on_invoice_upd_sts 10773 10773 0 0.00 0 19653 332 0 0
sp_qd_handle_on_reserve_upd_sts 9929 9929 0 0.00 0 15929 56 0 0
sp_reserve_write_off 9929 9929 0 0.00 7 15967 194 0 0
sp_supplier_invoice 5351 5351 0 0.00 7 73974 3339 0 0
sp_supplier_order 5428 5428 0 0.00 5 90955 1337 0 0
srv_aggregate_perf_data 91 91 0 0.00 2756 462012 24183 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1294 113819 47058 0 0
srv_find_qd_qs_mism 1068415 1068415 0 0.00 0 30450 4 0 0
srv_make_invnt_saldo 536 536 0 0.00 50 41805 1040 0 0
srv_make_money_saldo 742 742 0 0.00 1 16900 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 61080 384738 283439 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4803 4803 0 0.00 0 15240 171 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 4483 92 0 0
t$perf-norm:sp_cancel_client_order 1787 1787 0 0.00 0 5766 22 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 4623 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 3066 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 2679 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 3544 29 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 23438 1735 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 687 5 0 0
t$perf-norm:sp_client_order 20311 20311 0 0.00 0 9650 19 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 641 3 0 0
t$perf-norm:sp_pay_from_customer 7129 7129 0 0.00 0 5155 18 0 0
t$perf-norm:sp_pay_to_supplier 5871 5871 0 0.00 0 8572 14 0 0
t$perf-norm:sp_reserve_write_off 9929 9929 0 0.00 0 11923 17 0 0
t$perf-norm:sp_supplier_invoice 5351 5351 0 0.00 0 8124 24 0 0
t$perf-norm:sp_supplier_order 5428 5428 0 0.00 0 5790 17 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 839 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3256 57 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 5141 38 0 0
t$perf-norm:srv_make_money_saldo 742 742 0 0.00 0 4710 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 9 6 0 0
v_add_invoice_to_stock 9800 9594 206 2.10 0 7424 19 0 206
v_all_customers 37234 37234 0 0.00 0 15 1 0 0
v_all_suppliers 30504 30504 0 0.00 0 19 1 0 0
v_all_wares 40622 40622 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 2080 13 0 0
v_cancel_client_order 3574 3574 0 0.00 0 2587 7 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 4111 9 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 1760 6 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 1746 5 0 0
v_cancel_supplier_invoice 798 790 8 1.00 0 966 13 0 8
v_cancel_supplier_order 846 846 0 0.00 0 2087 6 0 0
v_cancel_write_off 2046 2046 0 0.00 0 2545 18 0 0
v_max_id_clo_ord 5427 5427 0 0.00 0 2842 2 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 2896 16 0 0
v_max_id_ord_sup 5350 5350 0 0.00 0 3334 2 0 0
v_max_non_paid_invoice 1396 1396 0 0.00 0 521 3 0 0
v_max_non_paid_realizn 3708 3708 0 0.00 0 1339 3 0 0
v_min_id_clo_ord 5427 5427 0 0.00 0 2379 6 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 693 3 0 0
v_min_id_ord_sup 5350 5350 0 0.00 0 6083 11 0 0
v_min_non_paid_invoice 5871 5871 0 0.00 0 9561 26 0 0
v_min_non_paid_realizn 7129 7129 0 0.00 0 9553 25 0 0
v_our_firm 10230 10230 0 0.00 0 20 1 0 0
v_reserve_write_off 19858 19858 0 0.00 0 5176 7 0 0
x_lock_depdocs_on_canc_invoice 12189 12189 0 0.00 0 9862 104 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 22924 413 0 0
x_make_qstorno_1000_1200 5428 5428 0 0.00 2 90895 1260 0 0
x_make_qstorno_1200_2000 5351 5351 0 0.00 3 66566 3206 0 0
x_make_qstorno_2100_3300 448402 448402 0 0.00 0 80298 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-08-2021 16:39:26 to 25-08-2021 16:39:30.

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
14:07:51 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:52 28109 8192000000 10419276864 10745233408 1459153744 15.118 100 89 178 0 1493846880 6595536 28097600
14:10:26 16603 8192000000 10464861024 10802372608 1522233904 15.670 100 88 176 1 1559922480 9705664 27982912
14:11:49 19291 8192000000 10506064832 10850930688 1559941344 15.996 100 83 166 0 1596648320 11278592 25428384
14:13:11 15707 8192000000 10554864672 10911485952 1604613904 16.379 100 83 166 1 1641673728 11873136 25186688
14:14:33 11022 8192000000 10638644320 11008606208 1703893184 17.218 100 84 169 2 1741143424 12693600 24556640
14:15:47 3393 8192000000 10693641072 11075211264 1762404752 17.704 100 80 156 2 1797550144 12171040 22974352
14:16:54 3388 8192000000 10752814768 11136192512 1806370000 18.066 100 86 171 2 1844318336 14327904 23620432
14:18:00 1365 8192000000 10803274512 11190771712 1859477968 18.499 100 93 189 0 1898288048 12966208 25843872
14:19:02 7278 8192000000 10827242032 11222044672 1883089184 18.690 100 74 148 1 1917208592 12499344 21620064
14:20:11 3016 8192000000 10807004352 11222970368 1883650640 18.695 100 46 92 2 1903897648 6628032 13618976
14:21:15 7241 8192000000 10804875040 11231285248 1896731104 18.800 100 35 70 2 1913202304 6735904 9735296
14:22:24 7018 8192000000 10818516672 11250372608 1909266688 18.901 100 30 60 4 1924479088 5737264 9475136
14:23:32 7318 8192000000 10824294144 11258347520 1917295568 18.965 100 22 43 2 1930008784 6581744 6131472
14:24:39 67092 8192000000 10853741408 11266584576 1903830368 18.857 99 39 80 2 1922638880 4793216 14015296
14:26:47 5011 8192000000 10827656496 11260710912 1910810864 18.913 100 34 67 2 1927230400 6738240 9681296
14:27:57 7623 8192000000 10817403872 11256041472 1892562608 18.766 99 19 38 1 1902955584 5106896 5286080
14:29:06 8235 8192000000 10723326592 11140816896 1707478336 17.248 91 27 54 2 1720295232 5411440 7405456
14:30:15 10135 8192000000 10673872832 11082510336 1692220048 17.120 94 43 86 0 1711295232 6421008 12654176
14:31:27 7106 8192000000 10699318304 11118809088 1789018112 17.924 100 28 55 1 1803080576 6057264 8005200
14:32:37 7754 8192000000 10729109296 11148275712 1814808320 18.135 100 27 54 1 1829432448 6913712 7710416
14:33:50 16576 8192000000 10794916976 11207897088 1858997776 18.495 100 27 54 1 1871673776 5655632 7020368
14:35:07 23541 8192000000 10780808752 11208511488 1848470000 18.410 99 15 30 0 1857895872 4630320 4795552
14:36:31 8282 8192000000 10810494768 11237625856 1886467856 18.717 100 26 51 1 1900126176 6336048 7322272
14:37:41 10893 8192000000 10839303168 11259240448 1913777088 18.937 100 22 43 1 1924412512 5178448 5456976
14:38:58 23078 8192000000 10864440672 11281670144 1925650176 19.032 100 58 116 0 1952055456 9668480 16736800
14:40:56 47897 8192000000 10872432992 11309281280 1930179168 19.068 99 23 46 1 1949951360 13152192 6620000
14:42:47 7717 8192000000 10918942464 11349553152 1975618336 19.430 100 39 78 2 2005366000 18163920 11583744
14:43:57 25460 8192000000 10922001440 11356549120 1976625504 19.438 100 45 89 0 2015216560 25755872 12835184
14:45:27 8862 8192000000 10943884448 11371708416 1993437360 19.571 100 54 108 2 2032118432 22794304 15886768
14:46:49 6084 8192000000 10942247456 11367399424 1990274400 19.546 100 64 128 0 2034845312 25826992 18743920
14:47:58 16960 8192000000 10953298560 11391074304 2000810352 19.629 100 50 101 1 2045348080 28974800 15562928
14:49:21 8484 8192000000 10970651024 11403272192 2009182064 19.695 100 59 118 2 2059470208 32185280 18102864
14:50:34 2164 8192000000 10963619792 11389308928 2003058576 19.647 100 75 148 3 2060265424 33588160 23618688
14:51:41 1293 8192000000 11017305696 11429920768 2025272464 19.822 100 85 170 0 2093296560 42033584 25990512
14:52:46 7057 8192000000 10989168096 11420958720 2006479984 19.674 100 61 122 1 2075254944 49177376 19597584
14:54:10 3699 8192000000 10977394960 11402543104 1979379264 19.460 99 64 128 0 2045859008 46063568 20416176
14:55:17 2677 8192000000 10983469024 11399282688 1987845120 19.527 100 89 178 1 2066132384 50906160 27381104
14:56:28 3323 8192000000 10965958992 11382779904 1968986880 19.377 100 80 159 3 2048993792 54951264 25055648
14:57:34 12678 8192000000 10929486688 11380011008 1936980288 19.123 98 29 58 1 1989483024 42800592 9702144
14:58:49 7322 8192000000 10918591008 11349520384 1940568496 19.151 99 43 86 1 2001172208 46519648 14084064
15:00:04 6840 8192000000 10925819824 11365335040 1953191472 19.252 100 47 94 0 2008779408 40786576 14801360
15:01:16 22719 8192000000 10917287648 11358699520 1909236592 18.901 97 29 58 2 1961108720 42117856 9754272
15:02:50 31202 8192000000 10927084304 11370110976 1951912816 19.242 100 27 54 0 2007605952 46354528 9338608
15:04:32 7067 8192000000 10911235104 11355467776 1907259424 18.885 98 27 52 1 1959060976 42838960 8962592
15:05:54 42152 8192000000 10868835920 11332935680 1938360576 19.134 100 19 38 1 1953680912 10183648 5136688
15:07:41 50600 8192000000 10902298080 11345047552 1939520112 19.143 99 34 68 1 1965919168 16732816 9666240
15:09:33 50696 8192000000 10912235248 11358666752 1962609552 19.327 100 24 48 1 1979954176 10352256 6992368
15:11:24 78423 8192000000 10908470912 11359465472 1924110624 19.020 98 22 44 2 1942352368 12093136 6148608
15:13:44 64030 8192000000 10884116512 11342557184 1876341072 18.636 95 19 38 1 1892800128 10670976 5788080
15:15:58 38879 8192000000 10903709760 11352719360 1946453216 19.198 99 33 64 2 1973318912 16638352 10227344
15:17:41 54550 8192000000 10896730064 11381788672 1974611088 19.422 100 20 40 3 1996340080 15198848 6530144
15:19:43 51652 8192000000 10887137312 11360460800 1945843984 19.193 99 12 24 2 1959459856 10130576 3485296
15:21:36 71284 8192000000 10893850320 11369476096 1953243840 19.252 99 13 26 0 1967965600 11465440 3256320
15:23:53 83964 8192000000 10891010096 11367464960 1934674608 19.104 98 15 30 0 1952116640 12921440 4520592
15:26:25 34767 8192000000 10903295840 11382808576 1977642736 19.446 100 21 43 2 1997947248 14680352 5624160
15:28:07 69591 8192000000 10921882096 11412193280 1969010320 19.378 99 12 24 0 1987740144 15254016 3475808
15:30:19 39249 8192000000 10898484960 11363622912 1948928224 19.218 99 14 28 2 1965616832 12341488 4347120
15:31:59 51372 8192000000 10917291904 11391885312 1958484592 19.294 98 23 46 0 1979263392 14183376 6595424
15:33:52 113819 8192000000 10905372768 11374501888 1872096960 18.601 94 7 14 0 1884423840 10378928 1947952
15:36:46 56796 8192000000 10923961072 11397595136 1950006432 19.227 98 33 67 2 1983362256 24633424 8722400
15:38:43 50578 8192000000 10893179408 11370094592 1894678896 18.783 96 17 34 1 1914588320 15194560 4714864
15:40:43 108182 8192000000 10876879072 11338952704 1891280032 18.756 96 25 43 3 1908229936 10593104 6356800
15:43:32 65107 8192000000 10877879472 11354673152 1940187200 19.148 99 12 24 1 1961051072 17456848 3407024
15:45:40 70041 8192000000 10877540240 11356704768 1928630480 19.056 99 6 11 1 1942340544 12485280 1224784
15:47:51 43064 8192000000 10876016912 11342082048 1907309632 18.885 97 20 40 0 1919947184 6865440 5772112
15:49:34 53219 8192000000 10891832368 11360432128 1923813456 19.017 98 14 27 1 1938240736 10105584 4321696
15:51:28 99937 8192000000 10907502480 11361759232 1928717824 19.057 98 17 33 0 1948037168 14449648 4869696
15:54:10 55701 8192000000 10887474064 11366625280 1938486800 19.135 99 17 34 3 1958514496 15503680 4524016
15:56:07 34412 8192000000 10903957856 11360256000 1925524320 19.031 98 36 72 0 1949409648 13578208 10307120
15:57:44 74634 8192000000 10902950992 11361710080 1897314704 18.805 97 14 27 1 1915017904 14111088 3592112
16:00:00 81540 8192000000 10885329872 11354337280 1924736688 19.025 98 33 66 1 1942453456 7686880 10029888
16:02:48 50347 8192000000 10890477456 11374792704 1961074752 19.315 100 15 29 1 1973747168 8244784 4427632
16:04:47 35224 8192000000 10889011632 11380523008 1972684928 19.407 100 4 8 0 1985007760 11507440 815392
16:06:25 56042 8192000000 10879704352 11353468928 1958175440 19.292 99 28 56 1 1975808768 9154800 8478528
16:09:37 69830 8192000000 10887726560 11356438528 1899839504 18.825 96 17 34 2 1921242128 16539920 4862704
16:12:03 66838 8192000000 10910226624 11393888256 1983918320 19.496 100 17 34 0 2001406864 12517248 4971296
16:14:13 60201 8192000000 10923897568 11399229440 1982349840 19.483 100 14 28 0 1998630848 12607584 3673424
16:16:14 60417 8192000000 10921327792 11411128320 1975574128 19.430 99 16 32 2 1995371904 15314624 4483152
16:18:29 40323 8192000000 10940639728 11410251776 2004570512 19.659 100 30 61 1 2025414784 12759920 8084352
16:20:11 73093 8192000000 10931062912 11407327232 1936433344 19.118 97 19 38 0 1956059424 14426576 5199504
16:22:24 107406 8192000000 10898578800 11372269568 1926993440 19.043 97 25 50 0 1944323904 10770864 6559600
16:25:47 91921 8192000000 10877754352 11370541056 1882533600 18.686 96 14 28 1 1906405248 19874176 3997472
16:28:20 91162 8192000000 10869380528 11356827648 1898101360 18.811 97 12 24 0 1917209568 14668272 4439936
16:30:53 71243 8192000000 10827533584 11312193536 1879896368 18.664 97 13 26 1 1894488752 10339776 4252608
16:33:47 78626 8192000000 10837708288 11323748352 1883107920 18.690 98 7 14 0 1895047872 10132960 1806992
16:36:10 60198 8192000000 10868638544 11340976128 1901220768 18.836 98 16 29 4 1919883392 14551424 4111200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 25-08-2021 16:39:32 to 25-08-2021 16:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-08-2021 16:39:47 to 25-08-2021 16:39:49.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239875
MON$OLDEST_ACTIVE               242012
MON$OLDEST_SNAPSHOT             242012
MON$NEXT_TRANSACTION            242012
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-03 16:41:55.3470
MON$PAGES                       2030624
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:454: 
:455: mscs-test-161.budzdorov.ru    Wed Aug 25 16:37:56 2021
:456:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:457: 
:458: 
:459: mscs-test-161.budzdorov.ru    Wed Aug 25 16:37:56 2021
:460:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:461: 
:462: 
:463: mscs-test-161.budzdorov.ru    Wed Aug 25 16:37:56 2021
:464:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:465: 
:466: 
:467: mscs-test-161.budzdorov.ru    Wed Aug 25 16:37:56 2021
:468:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:469: 
--- end of diff output ---

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

25.08.21 16:39:50. There are 3 logs in /var/tmp/logs-oltp30 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-08-25 16:39:53 No severe PSQL-related problems occured
25.08.21 16:39:54. End of report.