Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.03.2022 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16783544 kB
MemAvailable 31645728 kB
Buffers 0 kB
Cached 14962820 kB
SwapCached 2192 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.10
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 2022-03-28 22:38:10
DTS_END                         2022-03-28 22:38:10.5500
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/35350
TRN_ID                          247758
ATT_ID                          667
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: 9191.64 25556 1102997
customer order: creation 177.08 326 21250
customer order: refuse 14.77 344 1772
order to supplier: creation 47.87 1210 5744
order to supplier: removal 3.56 90096 427
invoice (draft): creation 48.60 3328 5832
invoice (draft): removal 51.03 804 6124
invoice accept: apply 43.99 17750 5279
invoice accept: cancel 45.78 768 5493
customer reserve: creation 3990.50 177 478860
customer reserve: removal 4463.49 55 535619
realization accept: apply 86.81 161 10417
realization accept: cancel 83.31 99 9997
payment from customer: creation 62.26 94 7471
payment from customer: removal 5.70 85 684
payment to supplier: creation 50.38 106 6046
payment to supplier: removal 3.56 69 427
service: total inventory turnovers 5.09 1056 611
service: total monetary turnovers 6.92 34 830
service: aggregate perf. data 0.92 16345 110
service: refresh index statistics 0.03 378211 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-03-2022 22:39:43 to 28-03-2022 22:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2807
TEST_TIME 2 3721
TEST_TIME 3 5151
TEST_TIME 4 5265
TEST_TIME 5 5480
TEST_TIME 6 5729
TEST_TIME 7 6237
TEST_TIME 8 6263
TEST_TIME 9 6419
TEST_TIME 10 6638
TEST_TIME 11 7092
TEST_TIME 12 7084
TEST_TIME 13 7029
TEST_TIME 14 7213
TEST_TIME 15 7235
TEST_TIME 16 7619
TEST_TIME 17 7745
TEST_TIME 18 8374
TEST_TIME 19 8461
TEST_TIME 20 8932
TEST_TIME 21 9171
TEST_TIME 22 9160
TEST_TIME 23 9298
TEST_TIME 24 9317
TEST_TIME 25 9555
TEST_TIME 26 10718
TEST_TIME 27 10566
TEST_TIME 28 10454
TEST_TIME 29 10685
TEST_TIME 30 10678
TEST_TIME 31 10490
TEST_TIME 32 10255
TEST_TIME 33 10135
TEST_TIME 34 10424
TEST_TIME 35 10258
TEST_TIME 36 10151
TEST_TIME 37 10125
TEST_TIME 38 10170
TEST_TIME 39 10165
TEST_TIME 40 10027
TEST_TIME 41 9954
TEST_TIME 42 9801
TEST_TIME 43 9701
TEST_TIME 44 9903
TEST_TIME 45 9756
TEST_TIME 46 9709
TEST_TIME 47 9767
TEST_TIME 48 9829
TEST_TIME 49 9764
TEST_TIME 50 9763
TEST_TIME 51 9757
TEST_TIME 52 9662
TEST_TIME 53 9763
TEST_TIME 54 9857
TEST_TIME 55 9808
TEST_TIME 56 9741
TEST_TIME 57 9895
TEST_TIME 58 9831
TEST_TIME 59 9893
TEST_TIME 60 9924
TEST_TIME 61 10000
TEST_TIME 62 9856
TEST_TIME 63 9773
TEST_TIME 64 9758
TEST_TIME 65 9645
TEST_TIME 66 9571
TEST_TIME 67 9598
TEST_TIME 68 9572
TEST_TIME 69 9533
TEST_TIME 70 9645
TEST_TIME 71 9585
TEST_TIME 72 9610
TEST_TIME 73 9554
TEST_TIME 74 9505
TEST_TIME 75 9583
TEST_TIME 76 9581
TEST_TIME 77 9619
TEST_TIME 78 9712
TEST_TIME 79 9706
TEST_TIME 80 9774
TEST_TIME 81 9667
TEST_TIME 82 9591
TEST_TIME 83 9554
TEST_TIME 84 9511
TEST_TIME 85 9482
TEST_TIME 86 9452
TEST_TIME 87 9387
TEST_TIME 88 9385
TEST_TIME 89 9342
TEST_TIME 90 9322
TEST_TIME 91 9304
TEST_TIME 92 9283
TEST_TIME 93 9284
TEST_TIME 94 9263
TEST_TIME 95 9288
TEST_TIME 96 9335
TEST_TIME 97 9360
TEST_TIME 98 9346
TEST_TIME 99 9398
TEST_TIME 100 9386
TEST_TIME 101 9378
TEST_TIME 102 9454
TEST_TIME 103 9459
TEST_TIME 104 9479
TEST_TIME 105 9493
TEST_TIME 106 9470
TEST_TIME 107 9427
TEST_TIME 108 9380
TEST_TIME 109 9354
TEST_TIME 110 9349
TEST_TIME 111 9338
TEST_TIME 112 9314
TEST_TIME 113 9278
TEST_TIME 114 9292
TEST_TIME 115 9245
TEST_TIME 116 9310
TEST_TIME 117 9299
TEST_TIME 118 9300
TEST_TIME 119 9272
TEST_TIME 120 9195

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-03-2022 22:39:47 to 28-03-2022 22:39:50.

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 1546122 1546122 0 0.00 0 28358 6 0 0
doc_list_biud 1546122 1546122 0 0.00 0 33784 25 0 0
sp_add_invoice_to_stock 5416 5279 137 2.52 34 173437 17750 0 137
sp_cancel_adding_invoice 5493 5493 0 0.00 4 75915 769 0 0
sp_cancel_client_order 1772 1772 0 0.00 4 15503 344 0 0
sp_cancel_customer_reserve 535619 535619 0 0.00 1 22549 56 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 2 11158 85 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 5708 69 0 0
sp_cancel_supplier_invoice 6141 6124 17 0.27 2 15215 804 0 17
sp_cancel_supplier_order 427 427 0 0.00 7 1050156 90096 0 0
sp_cancel_write_off 9997 9997 0 0.00 1 29466 99 0 0
sp_client_order 21250 21250 0 0.00 3 29911 326 0 0
sp_customer_reserve 478860 478860 0 0.00 0 73529 178 0 0
sp_fill_shopping_cart 32819 32819 0 0.00 1 11346 39 0 0
sp_fill_shopping_cart_clo_res 478851 478851 0 0.00 0 18161 14 0 0
sp_get_clo_for_invoice 5270 5270 0 0.00 0 40142 300 0 0
sp_kill_cost_storno 16601 16601 0 0.00 0 5852 52 0 0
sp_kill_qstorno_ret_qs2qd 1087786 1087786 0 0.00 0 29456 30 0 0
sp_kill_qty_storno 575119 575119 0 0.00 0 33783 67 0 0
sp_lock_selected_doc 21593 21593 0 0.00 0 9461 5 0 0
sp_make_cost_storno 29204 29204 0 0.00 0 9524 45 0 0
sp_multiply_rows_for_pdistr 29204 29204 0 0.00 0 7422 24 0 0
sp_multiply_rows_for_qdistr 21250 21250 0 0.00 0 28899 148 0 0
sp_pay_from_customer 7471 7471 0 0.00 4 19556 94 0 0
sp_pay_to_supplier 6046 6046 0 0.00 4 9570 106 0 0
sp_payment_common 13517 13517 0 0.00 1 7585 21 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 0 15495 314 0 0
sp_qd_handle_on_invoice_upd_sts 10763 10763 0 0.00 0 33782 355 0 0
sp_qd_handle_on_reserve_upd_sts 10417 10417 0 0.00 0 15513 57 0 0
sp_reserve_write_off 10417 10417 0 0.00 6 15541 161 0 0
sp_supplier_invoice 5832 5832 0 0.00 5 78491 3328 0 0
sp_supplier_order 5744 5744 0 0.00 5 30477 1210 0 0
srv_aggregate_perf_data 110 110 0 0.00 2682 156279 16345 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1297 178604 46065 0 0
srv_find_qd_qs_mism 1053314 1053314 0 0.00 0 28330 5 0 0
srv_make_invnt_saldo 611 611 0 0.00 41 53005 1057 0 0
srv_make_money_saldo 830 830 0 0.00 0 5741 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 180621 465049 378211 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5279 5279 0 0.00 0 19097 168 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 2793 76 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 3602 17 0 0
t$perf-norm:sp_cancel_customer_reserve 740 740 0 0.00 0 2263 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 423 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 1484 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 2976 22 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 25143 1680 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 1124 7 0 0
t$perf-norm:sp_client_order 21250 21250 0 0.00 0 8755 18 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 9045 18 0 0
t$perf-norm:sp_pay_from_customer 7471 7471 0 0.00 0 6126 8 0 0
t$perf-norm:sp_pay_to_supplier 6046 6046 0 0.00 0 3144 6 0 0
t$perf-norm:sp_reserve_write_off 10417 10417 0 0.00 0 3069 8 0 0
t$perf-norm:sp_supplier_invoice 5832 5832 0 0.00 0 16301 19 0 0
t$perf-norm:sp_supplier_order 5744 5744 0 0.00 0 3453 14 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 281 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1547 25 0 0
t$perf-norm:srv_make_invnt_saldo 611 611 0 0.00 0 2979 22 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 963 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 18 7 0 0
v_add_invoice_to_stock 10814 10540 274 2.53 0 9314 20 0 274
v_all_customers 38708 38708 0 0.00 0 15 1 0 0
v_all_suppliers 31906 31906 0 0.00 0 18 1 0 0
v_all_wares 42500 42500 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 583 6 0 0
v_cancel_client_order 3544 3544 0 0.00 0 2033 4 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 1579 4 0 0
v_cancel_customer_reserve 1480 1480 0 0.00 0 2859 6 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 424 4 0 0
v_cancel_supplier_invoice 882 848 34 3.85 0 603 14 0 34
v_cancel_supplier_order 854 854 0 0.00 0 801 6 0 0
v_cancel_write_off 1996 1996 0 0.00 0 2745 14 0 0
v_max_id_clo_ord 5744 5744 0 0.00 0 4305 3 0 0
v_max_id_clo_res 999 999 0 0.00 0 3377 17 0 0
v_max_id_ord_sup 5825 5825 0 0.00 0 3295 5 0 0
v_max_non_paid_invoice 1662 1662 0 0.00 0 45 2 0 0
v_max_non_paid_realizn 4010 4010 0 0.00 0 6510 6 0 0
v_min_id_clo_ord 5744 5744 0 0.00 0 10174 7 0 0
v_min_id_clo_res 999 999 0 0.00 0 4612 13 0 0
v_min_id_ord_sup 5825 5825 0 0.00 0 6749 8 0 0
v_min_non_paid_invoice 6046 6046 0 0.00 0 6175 14 0 0
v_min_non_paid_realizn 7471 7471 0 0.00 0 6519 13 0 0
v_our_firm 10714 10714 0 0.00 0 18 1 0 0
v_reserve_write_off 20834 20834 0 0.00 0 7014 7 0 0
x_lock_depdocs_on_canc_invoice 11193 11193 0 0.00 0 17256 130 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 9985 345 0 0
x_make_qstorno_1000_1200 5744 5744 0 0.00 1 30440 1119 0 0
x_make_qstorno_1200_2000 5832 5832 0 0.00 2 78470 3200 0 0
x_make_qstorno_2100_3300 478860 478860 0 0.00 0 73264 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-03-2022 22:39:51 to 28-03-2022 22:39:55.

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
20:08:14 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:14 25807 8192000000 9894185808 10202087424 984476288 10.728 100 87 172 2 1013301408 6442960 22382160
20:10:53 13746 8192000000 9946975712 10264174592 1027942624 11.149 100 94 188 1 1061153424 9237808 23972992
20:12:12 16457 8192000000 9973630320 10308100096 1062859504 11.484 100 77 154 0 1091729744 9931280 18938960
20:13:30 13946 8192000000 10036359792 10384932864 1117941152 12.008 100 74 148 0 1147062016 11758544 17362320
20:14:46 10181 8192000000 10085878880 10446966784 1175272864 12.546 100 86 172 0 1207986896 13265648 19448384
20:15:59 3112 8192000000 10129420368 10494484480 1212637696 12.894 100 83 164 1 1245058688 13881568 18539424
20:17:04 3913 8192000000 10148737312 10523734016 1247310800 13.214 100 71 140 1 1275659712 12893088 15455824
20:18:09 2860 8192000000 10198919824 10579591168 1284190736 13.551 100 77 152 1 1312449488 11933696 16325056
20:19:13 1939 8192000000 10216529808 10601635840 1300479232 13.700 100 90 183 2 1333356880 12979456 19898192
20:20:19 3096 8192000000 10218985248 10612035584 1310043264 13.786 100 57 114 1 1331010064 7554960 13411840
20:21:24 3971 8192000000 10214006528 10620362752 1321347296 13.889 100 43 86 1 1339153088 8261760 9544032
20:22:29 10957 8192000000 10208494112 10622263296 1322765040 13.902 100 25 50 2 1334267536 6096736 5405760
20:23:42 7763 8192000000 10207028608 10629218304 1327278176 13.943 100 18 36 0 1337325952 5713648 4334128
20:24:51 29051 8192000000 10246921392 10643324928 1335225376 14.014 100 65 127 2 1356676528 6394608 15056544
20:26:21 3123 8192000000 10226913888 10644230144 1331671888 13.982 100 35 72 3 1345877040 5081536 9123616
20:27:24 8499 8192000000 10211564912 10635669504 1325472256 13.926 100 25 50 0 1336232592 5363008 5397328
20:28:35 45592 8192000000 10183979504 10591854592 1262001360 13.348 97 30 62 1 1274940448 4585456 8353632
20:30:42 4172 8192000000 10155608448 10556436480 1252561440 13.262 99 43 85 1 1269614128 6826496 10226192
20:31:47 9322 8192000000 10139419648 10537299968 1218765904 12.950 97 18 35 0 1227967008 5369776 3831328
20:32:57 64703 8192000000 10152856656 10560434176 1212635088 12.894 95 27 54 0 1228427472 9616096 6176288
20:35:06 8185 8192000000 10195079744 10598596608 1292592480 13.628 100 29 58 0 1305447056 6653856 6200720
20:36:19 7720 8192000000 10194467744 10608619520 1302943216 13.722 100 15 28 0 1311635392 5558640 3133536
20:37:32 56958 8192000000 10205888880 10623385600 1315728944 13.838 100 28 56 0 1327995296 5448544 6817808
20:40:07 11316 8192000000 10259738384 10667233280 1346613344 14.117 100 62 123 1 1369693760 9384880 13695536
20:41:27 21081 8192000000 10290462336 10705907712 1372863728 14.353 100 46 92 0 1395928880 12606688 10458464
20:42:59 19339 8192000000 10297793056 10721173504 1382496032 14.439 100 37 74 0 1408829392 17847280 8486080
20:44:21 4188 8192000000 10311044032 10731683840 1392626448 14.529 100 51 102 0 1421809968 17219216 11964304
20:45:34 6246 8192000000 10315778336 10738417664 1386489408 14.475 99 63 124 2 1419171968 16884608 15797952
20:46:51 17676 8192000000 10298195968 10725314560 1371541312 14.341 99 45 91 0 1404663168 22254304 10867552
20:48:16 17668 8192000000 10320097440 10738778112 1392090336 14.525 100 69 138 2 1430443408 22581024 15772048
20:49:44 2124 8192000000 10339134480 10748821504 1395843696 14.558 100 90 180 1 1441772016 25184576 20743744
20:50:51 2967 8192000000 10324737616 10737127424 1387776592 14.486 100 81 163 2 1433790368 26807344 19206432
20:52:00 3938 8192000000 10324937840 10733465600 1376669840 14.387 100 77 154 2 1430666784 35225248 18771696
20:53:10 2569 8192000000 10322934224 10728734720 1372568368 14.350 100 87 174 0 1427642960 32871056 22203536
20:54:18 1297 8192000000 10310807568 10719551488 1365756688 14.289 100 80 160 2 1416974480 31385520 19832272
20:55:28 3140 8192000000 10331789008 10736308224 1360221088 14.239 99 83 166 0 1423464896 41674176 21569632
20:56:37 2789 8192000000 10326713696 10735980544 1361714240 14.253 100 83 166 0 1430361136 47413232 21233664
20:57:45 2468 8192000000 10307893216 10717151232 1345198032 14.104 98 79 158 1 1401595712 37303696 19093984
20:58:54 3907 8192000000 10328652128 10743144448 1379224784 14.410 100 76 150 1 1431449680 33587328 18637568
21:00:00 8899 8192000000 10307220160 10735071232 1364432880 14.277 99 31 62 1 1401894288 29292688 8168720
21:01:15 5718 8192000000 10300906640 10719420416 1362972160 14.264 100 39 77 0 1402670304 29564288 10133856
21:02:24 16855 8192000000 10297049312 10724225024 1339189504 14.050 98 31 61 0 1385215440 37669968 8355968
21:03:52 25002 8192000000 10285577872 10722373632 1334206288 14.005 99 26 52 1 1376062160 34590064 7265808
21:06:07 24195 8192000000 10272467328 10712612864 1354138192 14.185 99 21 42 0 1373917328 14604720 5174416
21:07:43 61943 8192000000 10280902560 10725306368 1363924912 14.273 100 20 39 3 1383532960 15023936 4584112
21:09:48 76998 8192000000 10314146144 10753806336 1394682032 14.548 100 40 80 1 1415585552 12670752 8232768
21:12:12 63390 8192000000 10313147728 10786586624 1400400272 14.599 100 11 22 0 1419750048 16897104 2452672
21:14:52 31102 8192000000 10301462992 10752905216 1397493536 14.573 99 25 50 1 1413374640 9821792 6059312
21:16:28 28089 8192000000 10294901024 10752176128 1391518704 14.519 99 41 80 3 1412701440 11666544 9516192
21:17:58 58815 8192000000 10313058272 10771353600 1407733392 14.664 100 14 28 0 1423183040 12352528 3097120
21:20:01 41385 8192000000 10321253424 10778218496 1399998848 14.595 99 12 24 1 1417437600 14386896 3051856
21:21:53 21858 8192000000 10304026896 10760097792 1387211104 14.481 99 16 32 0 1413256192 21982416 4062672
21:23:18 53908 8192000000 10308640176 10771619840 1401140368 14.605 100 24 48 2 1423563216 16773680 5649168
21:25:17 110300 8192000000 10319005264 10789597184 1406896240 14.656 100 15 30 0 1427058704 16374656 3787808
21:28:08 34248 8192000000 10296086912 10752172032 1390533744 14.511 99 28 56 0 1404098352 6999952 6564656
21:29:42 66773 8192000000 10326028144 10770628608 1362084256 14.256 97 44 88 0 1389728960 17856064 9788640
21:32:35 68156 8192000000 10301804528 10758762496 1393250544 14.535 99 11 22 0 1404237040 8748960 2237536
21:34:43 59395 8192000000 10303698256 10766352384 1343530032 14.089 95 19 38 0 1364853920 17073680 4250208
21:36:44 136598 8192000000 10273332848 10723713024 1215504304 12.920 88 20 40 0 1236380256 16488720 4387232
21:40:25 105667 8192000000 10267598320 10720141312 1352665904 14.171 99 23 44 1 1365541504 8347440 4528160
21:43:12 29074 8192000000 10253218592 10717904896 1340862784 14.065 98 4 8 0 1352348128 10814208 671136
21:44:41 47855 8192000000 10273039872 10740092928 1374964352 14.372 100 12 24 0 1390567424 12263840 3339232
21:46:29 46695 8192000000 10268834000 10718019584 1319316560 13.871 95 14 28 1 1333568496 11115168 3136768
21:48:17 57270 8192000000 10284691392 10739286016 1371942000 14.344 100 22 44 0 1393479952 16102928 5435024
21:50:14 49823 8192000000 10267662864 10716196864 1282991072 13.540 93 9 18 0 1291498096 6930992 1576032
21:52:05 83406 8192000000 10263793520 10720600064 1329993280 13.967 97 27 54 0 1350666944 14366608 6307056
21:54:59 37883 8192000000 10296899696 10739335168 1366669200 14.297 100 25 50 0 1386883392 14853248 5360944
21:56:42 178604 8192000000 10287418976 10753904640 1355923216 14.201 98 10 20 1 1379776736 21901808 1951712
22:01:13 48453 8192000000 10278930304 10737569792 1367641168 14.306 99 18 36 0 1387540032 15880496 4018368
22:03:02 67232 8192000000 10273678464 10736132096 1366332272 14.294 99 6 12 0 1380214576 12560880 1321424
22:06:20 59200 8192000000 10280967712 10740629504 1384699136 14.459 100 17 34 0 1396690816 8401136 3590544
22:08:21 55604 8192000000 10285580960 10747846656 1380346496 14.420 99 26 52 1 1396712304 10628336 5737472
22:10:17 82522 8192000000 10288371456 10760458240 1391118560 14.516 100 7 14 0 1403414144 11017264 1278320
22:12:40 49374 8192000000 10287631664 10745733120 1364844208 14.281 98 21 43 1 1379326384 8852288 5629888
22:14:30 65720 8192000000 10298184336 10758230016 1352583376 14.171 97 24 47 1 1372036976 14127792 5325808
22:16:43 24296 8192000000 10291703168 10743132160 1360038944 14.238 98 35 68 0 1382530256 14961808 7529504
22:18:13 76351 8192000000 10302943312 10755330048 1333977408 14.003 96 21 42 1 1355752768 17367648 4407712
22:20:38 103715 8192000000 10289870960 10736508928 1351756240 14.163 98 23 46 0 1369320576 12795104 4769232
22:23:24 59673 8192000000 10290091904 10731704320 1328762272 13.956 97 12 24 0 1349346448 17559568 3024608
22:25:25 76565 8192000000 10270065120 10721705984 1365961168 14.291 100 20 40 1 1378825568 8588864 4275536
22:27:45 45113 8192000000 10255716624 10704187392 1340852736 14.065 99 22 44 1 1360881152 14597760 5430656
22:29:32 80730 8192000000 10249798512 10707435520 1297311888 13.671 94 18 36 0 1309637632 8211792 4113952
22:31:52 74125 8192000000 10256282384 10723700736 1346535408 14.116 99 10 20 0 1362013088 13112576 2365104
22:34:28 61374 8192000000 10275961824 10736664576 1358326608 14.222 99 16 32 0 1376997648 14955040 3716000
22:36:37 62428 8192000000 10282857680 10736164864 1346420560 14.115 97 24 48 1 1365180528 12606240 6153728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-03-2022 22:39:57 to 28-03-2022 22:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 28-03-2022 22:40:13 to 28-03-2022 22:40:14.

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          244568
MON$OLDEST_ACTIVE               248125
MON$OLDEST_SNAPSHOT             248125
MON$NEXT_TRANSACTION            248125
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                       2029792
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:632: 
:633: mscs-test-161.budzdorov.ru    Mon Mar 28 22:38:12 2022
:634:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:635: 
:636: 
:637: mscs-test-161.budzdorov.ru    Mon Mar 28 22:38:18 2022
:638:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:639: 
:640: 
:641: mscs-test-161.budzdorov.ru    Mon Mar 28 22:38:18 2022
:642:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:643: 
:644: 
:645: mscs-test-161.budzdorov.ru    Mon Mar 28 22:38:20 2022
:646:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:647: 
:648: 
:649: mscs-test-161.budzdorov.ru    Mon Mar 28 22:38:20 2022
:650:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:651: 
--- end of diff output ---

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

28.03.22 22:40:16. 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
2022-03-28 22:40:18 No severe PSQL-related problems occured
28.03.22 22:40:19. End of report.