Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.08.2021 22: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 16071732 kB
MemAvailable 30948272 kB
Buffers 0 kB
Cached 15577000 kB
SwapCached 2604 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-19 22:37:48
DTS_END                         2021-08-19 22:37:48.3430
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/41602
TRN_ID                          254387
ATT_ID                          663
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: 9487.03 20243 1138444
customer order: creation 185.51 337 22261
customer order: refuse 15.93 348 1912
order to supplier: creation 49.23 1234 5907
order to supplier: removal 3.33 96773 399
invoice (draft): creation 50.24 3155 6029
invoice (draft): removal 52.43 725 6292
invoice accept: apply 45.15 17921 5418
invoice accept: cancel 47.34 696 5681
customer reserve: creation 4109.99 180 493199
customer reserve: removal 4611.60 55 553392
realization accept: apply 92.24 174 11069
realization accept: cancel 82.19 103 9863
payment from customer: creation 64.67 112 7760
payment from customer: removal 6.58 70 789
payment to supplier: creation 53.72 111 6446
payment to supplier: removal 3.81 67 457
service: total inventory turnovers 5.28 986 633
service: total monetary turnovers 7.05 41 846
service: aggregate perf. data 0.73 25205 87
service: refresh index statistics 0.03 256575 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-08-2021 22:38:26 to 19-08-2021 22:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6135
TEST_TIME 2 3537
TEST_TIME 3 4271
TEST_TIME 4 5508
TEST_TIME 5 5323
TEST_TIME 6 4975
TEST_TIME 7 5139
TEST_TIME 8 5066
TEST_TIME 9 5072
TEST_TIME 10 5323
TEST_TIME 11 5378
TEST_TIME 12 5696
TEST_TIME 13 5715
TEST_TIME 14 5920
TEST_TIME 15 6088
TEST_TIME 16 6619
TEST_TIME 17 6927
TEST_TIME 18 7013
TEST_TIME 19 7804
TEST_TIME 20 8390
TEST_TIME 21 8387
TEST_TIME 22 8577
TEST_TIME 23 8616
TEST_TIME 24 9139
TEST_TIME 25 9332
TEST_TIME 26 9617
TEST_TIME 27 9730
TEST_TIME 28 9921
TEST_TIME 29 9960
TEST_TIME 30 10051
TEST_TIME 31 10567
TEST_TIME 32 10387
TEST_TIME 33 10570
TEST_TIME 34 10527
TEST_TIME 35 10463
TEST_TIME 36 10382
TEST_TIME 37 10248
TEST_TIME 38 10167
TEST_TIME 39 10069
TEST_TIME 40 9970
TEST_TIME 41 9913
TEST_TIME 42 9983
TEST_TIME 43 9855
TEST_TIME 44 9798
TEST_TIME 45 9723
TEST_TIME 46 9610
TEST_TIME 47 9560
TEST_TIME 48 9773
TEST_TIME 49 9702
TEST_TIME 50 9639
TEST_TIME 51 9633
TEST_TIME 52 9488
TEST_TIME 53 9432
TEST_TIME 54 9600
TEST_TIME 55 9794
TEST_TIME 56 9692
TEST_TIME 57 9794
TEST_TIME 58 9775
TEST_TIME 59 9730
TEST_TIME 60 9834
TEST_TIME 61 9775
TEST_TIME 62 9824
TEST_TIME 63 9893
TEST_TIME 64 10026
TEST_TIME 65 10020
TEST_TIME 66 10124
TEST_TIME 67 10089
TEST_TIME 68 9979
TEST_TIME 69 9923
TEST_TIME 70 9862
TEST_TIME 71 9792
TEST_TIME 72 9762
TEST_TIME 73 9727
TEST_TIME 74 9697
TEST_TIME 75 9731
TEST_TIME 76 9671
TEST_TIME 77 9674
TEST_TIME 78 9605
TEST_TIME 79 9546
TEST_TIME 80 9562
TEST_TIME 81 9500
TEST_TIME 82 9462
TEST_TIME 83 9367
TEST_TIME 84 9346
TEST_TIME 85 9362
TEST_TIME 86 9342
TEST_TIME 87 9378
TEST_TIME 88 9413
TEST_TIME 89 9372
TEST_TIME 90 9361
TEST_TIME 91 9378
TEST_TIME 92 9430
TEST_TIME 93 9483
TEST_TIME 94 9482
TEST_TIME 95 9497
TEST_TIME 96 9544
TEST_TIME 97 9554
TEST_TIME 98 9550
TEST_TIME 99 9543
TEST_TIME 100 9523
TEST_TIME 101 9501
TEST_TIME 102 9451
TEST_TIME 103 9408
TEST_TIME 104 9410
TEST_TIME 105 9469
TEST_TIME 106 9419
TEST_TIME 107 9428
TEST_TIME 108 9405
TEST_TIME 109 9405
TEST_TIME 110 9431
TEST_TIME 111 9430
TEST_TIME 112 9446
TEST_TIME 113 9419
TEST_TIME 114 9491
TEST_TIME 115 9484
TEST_TIME 116 9476
TEST_TIME 117 9483
TEST_TIME 118 9520
TEST_TIME 119 9511
TEST_TIME 120 9490

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-08-2021 22:38:29 to 19-08-2021 22:38:33.

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 1594757 1594757 0 0.00 0 20956 6 0 0
doc_list_biud 1594757 1594757 0 0.00 0 25788 24 0 0
sp_add_invoice_to_stock 5562 5418 144 2.58 39 212116 17922 0 144
sp_cancel_adding_invoice 5681 5681 0 0.00 5 63623 697 0 0
sp_cancel_client_order 1912 1912 0 0.00 4 8255 349 0 0
sp_cancel_customer_reserve 553392 553392 0 0.00 1 15948 55 0 0
sp_cancel_pay_from_customer 789 789 0 0.00 3 2815 70 0 0
sp_cancel_pay_to_supplier 457 457 0 0.00 2 3913 67 0 0
sp_cancel_supplier_invoice 6310 6292 18 0.28 2 21773 725 0 18
sp_cancel_supplier_order 399 399 0 0.00 8 994385 96774 0 0
sp_cancel_write_off 9863 9863 0 0.00 1 21351 104 0 0
sp_client_order 22261 22261 0 0.00 4 29869 338 0 0
sp_customer_reserve 493199 493199 0 0.00 0 43955 181 0 0
sp_fill_shopping_cart 34186 34186 0 0.00 1 15758 39 0 0
sp_fill_shopping_cart_clo_res 493188 493188 0 0.00 0 29013 15 0 0
sp_get_clo_for_invoice 5407 5407 0 0.00 0 29335 281 0 0
sp_kill_cost_storno 16790 16790 0 0.00 0 4915 50 0 0
sp_kill_qstorno_ret_qs2qd 1123338 1123338 0 0.00 0 23015 30 0 0
sp_kill_qty_storno 594015 594015 0 0.00 0 25788 65 0 0
sp_lock_selected_doc 22780 22780 0 0.00 0 8507 5 0 0
sp_make_cost_storno 30682 30682 0 0.00 0 20938 51 0 0
sp_multiply_rows_for_pdistr 30682 30682 0 0.00 0 8270 26 0 0
sp_multiply_rows_for_qdistr 22261 22261 0 0.00 0 29785 151 0 0
sp_pay_from_customer 7760 7760 0 0.00 4 10955 113 0 0
sp_pay_to_supplier 6446 6446 0 0.00 4 10875 111 0 0
sp_payment_common 14206 14206 0 0.00 2 10950 28 0 0
sp_qd_handle_on_cancel_clo 1912 1912 0 0.00 0 8244 331 0 0
sp_qd_handle_on_invoice_upd_sts 11088 11088 0 0.00 0 25786 303 0 0
sp_qd_handle_on_reserve_upd_sts 11069 11069 0 0.00 0 16026 58 0 0
sp_reserve_write_off 11069 11069 0 0.00 7 16037 174 0 0
sp_supplier_invoice 6029 6029 0 0.00 5 103809 3155 0 0
sp_supplier_order 5907 5907 0 0.00 5 22217 1234 0 0
srv_aggregate_perf_data 87 87 0 0.00 2913 723506 25205 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1538 178484 41824 0 0
srv_find_qd_qs_mism 1086664 1086664 0 0.00 0 17762 6 0 0
srv_make_invnt_saldo 633 633 0 0.00 40 63944 986 0 0
srv_make_money_saldo 846 846 0 0.00 0 10935 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 52737 394921 256575 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5418 5418 0 0.00 0 10169 163 0 0
t$perf-norm:sp_cancel_adding_invoice 451 451 0 0.00 1 3951 77 0 0
t$perf-norm:sp_cancel_client_order 1912 1912 0 0.00 0 8601 21 0 0
t$perf-norm:sp_cancel_customer_reserve 770 770 0 0.00 0 2862 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 789 789 0 0.00 0 6211 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 457 457 0 0.00 0 9851 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 786 14 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 22674 1571 0 0
t$perf-norm:sp_cancel_write_off 1110 1110 0 0.00 0 5271 12 0 0
t$perf-norm:sp_client_order 22262 22262 0 0.00 0 20851 18 0 0
t$perf-norm:sp_customer_reserve 1120 1120 0 0.00 0 5229 13 0 0
t$perf-norm:sp_pay_from_customer 7760 7760 0 0.00 0 5381 14 0 0
t$perf-norm:sp_pay_to_supplier 6446 6446 0 0.00 0 12528 13 0 0
t$perf-norm:sp_reserve_write_off 11069 11069 0 0.00 0 8609 12 0 0
t$perf-norm:sp_supplier_invoice 6029 6029 0 0.00 0 5936 16 0 0
t$perf-norm:sp_supplier_order 5907 5907 0 0.00 0 5026 16 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 332 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 157 4 0 0
t$perf-norm:srv_make_invnt_saldo 633 633 0 0.00 0 1613 19 0 0
t$perf-norm:srv_make_money_saldo 846 846 0 0.00 0 2351 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 63 18 0 0
v_add_invoice_to_stock 11100 10812 288 2.59 0 4143 18 0 288
v_all_customers 39892 39892 0 0.00 0 75 1 0 0
v_all_suppliers 33012 33012 0 0.00 0 22 1 0 0
v_all_wares 44522 44522 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 902 902 0 0.00 0 4115 11 0 0
v_cancel_client_order 3824 3824 0 0.00 0 1654 4 0 0
v_cancel_customer_prepayment 1578 1578 0 0.00 0 2754 6 0 0
v_cancel_customer_reserve 1540 1540 0 0.00 0 5592 7 0 0
v_cancel_payment_to_supplier 914 914 0 0.00 0 227 3 0 0
v_cancel_supplier_invoice 870 834 36 4.13 0 245 16 0 36
v_cancel_supplier_order 798 798 0 0.00 0 271 3 0 0
v_cancel_write_off 2220 2220 0 0.00 0 6599 19 0 0
v_max_id_clo_ord 5904 5904 0 0.00 0 6593 3 0 0
v_max_id_clo_res 1120 1120 0 0.00 0 4437 13 0 0
v_max_id_ord_sup 6021 6021 0 0.00 0 4989 5 0 0
v_max_non_paid_invoice 1865 1865 0 0.00 0 241 2 0 0
v_max_non_paid_realizn 4447 4447 0 0.00 0 3439 5 0 0
v_min_id_clo_ord 5904 5904 0 0.00 0 2115 5 0 0
v_min_id_clo_res 1120 1120 0 0.00 0 11643 25 0 0
v_min_id_ord_sup 6021 6021 0 0.00 0 7036 7 0 0
v_min_non_paid_invoice 6446 6446 0 0.00 0 9727 15 0 0
v_min_non_paid_realizn 7760 7760 0 0.00 0 10894 18 0 0
v_our_firm 11256 11256 0 0.00 0 81 1 0 0
v_reserve_write_off 22138 22138 0 0.00 0 5841 6 0 0
x_lock_depdocs_on_canc_invoice 11556 11556 0 0.00 0 18478 107 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 10505 353 0 0
x_make_qstorno_1000_1200 5907 5907 0 0.00 2 22191 1154 0 0
x_make_qstorno_1200_2000 6029 6029 0 0.00 2 103458 3013 0 0
x_make_qstorno_2100_3300 493199 493199 0 0.00 0 43954 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-08-2021 22:38:34 to 19-08-2021 22:38:38.

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:07:51 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:52 30497 8192000000 10379529408 10698911744 1422453280 14.794 100 87 173 0 1456701312 6003152 28244880
20:10:27 13280 8192000000 10437358576 10767589376 1489411104 15.384 100 86 172 1 1525523376 8174080 27938192
20:11:49 19941 8192000000 10482902048 10823610368 1539097280 15.816 100 82 164 2 1574543184 9607536 25838368
20:13:19 10818 8192000000 10556284448 10911019008 1614714320 16.465 100 83 166 0 1651215312 11698624 24802368
20:14:34 12186 8192000000 10649795984 11018698752 1704929440 17.226 100 78 157 1 1739352528 11664976 22758112
20:15:48 5583 8192000000 10706790128 11086712832 1769302928 17.761 100 81 160 5 1804589728 12881920 22404880
20:16:55 2964 8192000000 10767712160 11155591168 1828448320 18.247 100 83 167 0 1864637056 13072640 23116096
20:17:59 1808 8192000000 10811166400 11199217664 1866387088 18.555 100 89 180 0 1905232928 14513712 24332128
20:19:02 3289 8192000000 10805809728 11216506880 1882032576 18.682 100 60 120 3 1908623808 9079664 17511568
20:20:06 23923 8192000000 10783620624 11220320256 1884782448 18.704 100 26 52 1 1898060048 5280832 7996768
20:21:32 3837 8192000000 10811375072 11235868672 1899364704 18.821 100 37 73 0 1917641792 8064032 10213056
20:22:36 9266 8192000000 10820631664 11251081216 1908031920 18.891 100 25 51 2 1921020736 5910032 7078784
20:23:46 11004 8192000000 10820815904 11261403136 1914574672 18.943 100 20 40 1 1930287488 9975120 5737696
20:24:59 35413 8192000000 10826234704 11267837952 1917202640 18.964 100 25 50 1 1930611248 6758704 6649904
20:26:36 6106 8192000000 10842839904 11269488640 1908138928 18.892 99 29 58 3 1922906240 6782496 7984816
20:27:42 5002 8192000000 10819697040 11257536512 1908779664 18.897 100 22 44 0 1921270416 6311008 6179744
20:28:48 18986 8192000000 10777630896 11201966080 1814445744 18.132 97 33 65 2 1833292480 9275344 9571392
20:30:46 5133 8192000000 10698463040 11117096960 1763474224 17.713 98 27 54 2 1777380848 6058816 7847808
20:31:52 7953 8192000000 10737301312 11165708288 1830094336 18.260 100 28 57 3 1844515472 6168832 8252304
20:33:01 10771 8192000000 10743313200 11170250752 1817181280 18.155 99 29 57 4 1832249648 6142080 8926288
20:34:14 42225 8192000000 10776121184 11210911744 1864794544 18.542 100 18 35 1 1879301968 9415232 5092192
20:35:59 6260 8192000000 10793536640 11224842240 1883521104 18.694 100 45 88 4 1902118736 6059296 12538336
20:37:05 6207 8192000000 10810260912 11241897984 1897478960 18.806 100 20 40 0 1908510928 5717456 5314512
20:38:15 18890 8192000000 10860995152 11281383424 1931329344 19.078 100 51 101 2 1952670896 7454848 13886704
20:39:35 59578 8192000000 10877255248 11312099328 1949344240 19.221 100 43 86 2 1973808144 11059776 13404128
20:41:39 12799 8192000000 10886816128 11324268544 1961258848 19.316 100 37 73 3 1983768800 12244432 10265520
20:42:56 9488 8192000000 10924107488 11349889024 1974805600 19.424 100 54 109 1 2007168976 16608496 15754880
20:44:10 8296 8192000000 10955781392 11380707328 1998600768 19.612 100 70 140 1 2044780784 26359104 19820912
20:45:28 7464 8192000000 10951941376 11381538816 1997213904 19.601 100 60 119 0 2040889040 26108720 17566416
20:46:45 19650 8192000000 10948628176 11384713216 1995291040 19.586 100 58 114 1 2041999872 29820912 16887920
20:48:09 10345 8192000000 10956108032 11382616064 1994034000 19.576 100 73 145 3 2046332944 31299632 20999312
20:49:29 5364 8192000000 10916919904 11347460096 1917753504 18.969 96 67 134 2 1974274528 36204992 20316032
20:50:40 7439 8192000000 10973472800 11399663616 2004206544 19.656 100 69 137 0 2062661600 37572592 20882464
20:51:50 3373 8192000000 10969915120 11387371520 1987227264 19.522 100 76 153 1 2050176096 39941008 23007824
20:53:00 2785 8192000000 10967693584 11388178432 1973239824 19.411 99 83 162 1 2043679888 45115440 25324624
20:54:13 1537 8192000000 10949984896 11356016640 1952348416 19.245 99 88 175 1 2021502576 41940320 27213840
20:55:20 3879 8192000000 10924788368 11347910656 1945435232 19.190 100 54 111 1 2005496880 42308576 17753072
20:56:31 8571 8192000000 10911931392 11345694720 1931234960 19.077 99 55 110 1 1996415680 47476496 17704224
20:57:50 9339 8192000000 10886667648 11326320640 1904463872 18.862 98 28 56 3 1949989088 35771904 9753312
20:59:03 21851 8192000000 10889303904 11327713280 1907784352 18.889 98 26 52 1 1948303120 31560144 8958624
21:00:28 5936 8192000000 10938023968 11376472064 1966510960 19.358 100 43 87 1 2024579856 44260288 13808608
21:01:44 8782 8192000000 10929953280 11378528256 1967707216 19.367 100 25 50 1 2011403536 35295808 8400512
21:02:55 14224 8192000000 10945491600 11390914560 1966671904 19.359 99 30 60 0 2014226656 37296992 10257760
21:04:14 12816 8192000000 10928564128 11389612032 1977240624 19.443 100 27 54 1 2016583776 30162896 9180256
21:05:58 21597 8192000000 10903326640 11357847552 1941633488 19.160 99 22 44 0 1977658560 28657216 7367856
21:07:45 37362 8192000000 10896407440 11355627520 1939134384 19.140 99 12 24 0 1970443488 27608288 3700816
21:09:29 178484 8192000000 10900618656 11359707136 1919903888 18.986 97 10 20 1 1934807392 12424720 2478784
21:13:28 38716 8192000000 10938840384 11376611328 1995718848 19.589 100 50 100 0 2018114432 7231808 15163776
21:15:32 42609 8192000000 10932993248 11369566208 1972440720 19.405 99 41 82 3 1994415920 9716592 12258608
21:17:29 55288 8192000000 10918536192 11369377792 1976442144 19.437 100 33 65 2 1995803984 9782112 9579728
21:19:28 58031 8192000000 10917035344 11377926144 1970153136 19.387 99 30 59 0 1988836256 9875072 8808048
21:21:46 31434 8192000000 10920760224 11384991744 1991782416 19.558 100 29 56 3 2013438672 12604480 9051776
21:23:18 19070 8192000000 10941644528 11391012864 1991885168 19.559 100 66 132 0 2027715232 16198080 19631984
21:24:38 35426 8192000000 10927201312 11398438912 1986333296 19.515 100 28 56 0 2017881296 22844368 8703632
21:26:16 58214 8192000000 10929861808 11399102464 1995276416 19.585 100 11 22 0 2010921616 12884992 2760208
21:28:15 16568 8192000000 10899254640 11376865280 1973653136 19.414 100 22 42 1 1997164368 15800880 7710352
21:29:36 81073 8192000000 10918695008 11398189056 1930236416 19.069 97 19 38 0 1958935680 22872528 5826736
21:31:58 78516 8192000000 10880774896 11365810176 1893929808 18.777 96 8 16 0 1907469104 11487952 2051344
21:34:16 58621 8192000000 10862898496 11337465856 1869961424 18.584 96 24 48 2 1897613008 21194496 6457088
21:36:16 35992 8192000000 10886043872 11348193280 1921320320 18.997 98 26 51 1 1945731040 17257056 7153664
21:37:57 47116 8192000000 10930342704 11373944832 1955308592 19.269 99 43 86 1 1983526384 16060384 12157408
21:39:46 64568 8192000000 10898500192 11371106304 1905150864 18.868 97 11 22 0 1919956992 12301920 2504208
21:41:52 77503 8192000000 10882942032 11360739328 1869360160 18.579 96 10 20 0 1892239296 20285328 2593808
21:44:11 105423 8192000000 10873683344 11319980032 1849332736 18.417 95 17 34 1 1867867696 14017392 4517568
21:47:29 15829 8192000000 10886384944 11343425536 1931565216 19.079 99 53 106 1 1956485632 10350016 14570400
21:48:47 21212 8192000000 10887148608 11343785984 1943910752 19.178 100 40 80 3 1967024720 12192480 10921488
21:50:10 16854 8192000000 10866087824 11328991232 1915884144 18.954 99 38 74 1 1939655920 13243040 10528736
21:51:27 38996 8192000000 10855948640 11333091328 1886707856 18.719 97 18 34 4 1906488992 14324256 5456880
21:53:06 55740 8192000000 10843028864 11316662272 1894676800 18.783 98 9 18 1 1907578768 10693264 2208704
21:55:03 1704 8192000000 10879991120 11325210624 1939167232 19.140 100 41 80 0 1955621952 4577552 11877168
21:56:12 83881 8192000000 10870084688 11355627520 1938142768 19.132 99 13 26 3 1955079328 12649664 4286896
21:58:40 51330 8192000000 10890982608 11370811392 1962725200 19.328 100 21 42 2 1977999392 9621728 5652464
22:00:31 53631 8192000000 10873491312 11358392320 1952499520 19.246 100 7 14 0 1963841312 9358160 1983632
22:02:26 45214 8192000000 10883713280 11349504000 1893062256 18.770 96 15 30 0 1904818656 8326816 3429584
22:04:14 92859 8192000000 10886126816 11368435712 1944663456 19.184 99 13 26 0 1959549024 11191680 3693888
22:06:47 17605 8192000000 10908565792 11367452672 1968786512 19.376 100 37 74 0 1988405376 9389120 10229744
22:08:11 50891 8192000000 10910969456 11385196544 1962577600 19.327 99 23 46 0 1983716320 14778800 6359920
22:10:07 80848 8192000000 10929826160 11404587008 1968850448 19.376 99 14 28 1 1984369040 12013040 3505552
22:12:32 87237 8192000000 10878952384 11361144832 1871793936 18.599 95 21 42 0 1892682624 14851888 6036800
22:15:15 65151 8192000000 10916722464 11366350848 1946279376 19.197 99 42 84 0 1965994688 7972400 11742912
22:17:24 98607 8192000000 10844121216 11309060096 1882312672 18.684 97 25 50 0 1904023632 14544480 7166480
22:20:14 48310 8192000000 10862079312 11330813952 1897197872 18.804 98 26 52 0 1924111872 18987232 7926768
22:22:04 66901 8192000000 10846698672 11337531392 1901843216 18.841 98 16 33 0 1926269472 19828192 4598064
22:24:12 53241 8192000000 10847996240 11318046720 1877276464 18.643 97 20 39 1 1891464688 8504128 5684096
22:26:06 77314 8192000000 10860833856 11334922240 1902994128 18.850 98 13 26 0 1916372336 9505696 3872512
22:28:24 34735 8192000000 10860093872 11319500800 1910431344 18.910 99 17 33 2 1922041344 7601680 4008320
22:30:12 39630 8192000000 10874798144 11337519104 1924030032 19.019 99 29 58 0 1943054480 10741712 8282736
22:31:59 69997 8192000000 10879439328 11350925312 1934094208 19.100 99 31 62 1 1952838800 9936016 8808576
22:34:19 94684 8192000000 10865533536 11339370496 1852197392 18.440 95 22 44 1 1873588704 14949808 6441504
22:37:07 45731 8192000000 9504371728 9813385216 644541648 7.294 34 10 20 0 651532416 4378208 2612560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-08-2021 22:38:39 to 19-08-2021 22:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 19-08-2021 22:38:56 to 19-08-2021 22:38:57.

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          253508
MON$OLDEST_ACTIVE               255344
MON$OLDEST_SNAPSHOT             255344
MON$NEXT_TRANSACTION            255344
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                       2048480
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 ---
:124: 
:125: mscs-test-161.budzdorov.ru    Thu Aug 19 22:37:55 2021
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Thu Aug 19 22:37:57 2021
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Thu Aug 19 22:37:57 2021
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:135: 
--- end of diff output ---

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

19.08.21 22:38:58. 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-19 22:39:01 No severe PSQL-related problems occured
19.08.21 22:39:02. End of report.