Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.09.2021 16: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 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 15850968 kB
MemAvailable 30862816 kB
Buffers 0 kB
Cached 15855068 kB
SwapCached 416 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-09-14 16:37:50
DTS_END                         2021-09-14 16:37:50.4410
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53376
TRN_ID                          247470
ATT_ID                          690
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: 9618.24 18705 1154189
customer order: creation 177.23 366 21267
customer order: refuse 14.97 323 1796
order to supplier: creation 46.83 1282 5620
order to supplier: removal 3.37 108871 404
invoice (draft): creation 45.08 3385 5410
invoice (draft): removal 54.38 806 6525
invoice accept: apply 43.53 17281 5223
invoice accept: cancel 49.98 805 5998
customer reserve: creation 3968.68 173 476242
customer reserve: removal 4908.53 58 589024
realization accept: apply 85.66 174 10279
realization accept: cancel 88.06 116 10567
payment from customer: creation 60.63 137 7276
payment from customer: removal 6.03 95 723
payment to supplier: creation 49.93 134 5991
payment to supplier: removal 3.28 71 394
service: total inventory turnovers 4.88 1385 586
service: total monetary turnovers 6.37 31 764
service: aggregate perf. data 0.80 20947 96
service: refresh index statistics 0.03 217651 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-09-2021 16:39:48 to 14-09-2021 16:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 724
TEST_TIME 2 1488
TEST_TIME 3 2578
TEST_TIME 4 3409
TEST_TIME 5 3881
TEST_TIME 6 3870
TEST_TIME 7 3941
TEST_TIME 8 4199
TEST_TIME 9 4878
TEST_TIME 10 5141
TEST_TIME 11 5522
TEST_TIME 12 5599
TEST_TIME 13 5754
TEST_TIME 14 5784
TEST_TIME 15 5976
TEST_TIME 16 6404
TEST_TIME 17 6512
TEST_TIME 18 6828
TEST_TIME 19 6883
TEST_TIME 20 7078
TEST_TIME 21 7457
TEST_TIME 22 7681
TEST_TIME 23 7912
TEST_TIME 24 8588
TEST_TIME 25 8952
TEST_TIME 26 9101
TEST_TIME 27 9095
TEST_TIME 28 9417
TEST_TIME 29 9788
TEST_TIME 30 10233
TEST_TIME 31 10380
TEST_TIME 32 10241
TEST_TIME 33 10236
TEST_TIME 34 10299
TEST_TIME 35 10115
TEST_TIME 36 10003
TEST_TIME 37 10239
TEST_TIME 38 10128
TEST_TIME 39 10122
TEST_TIME 40 10142
TEST_TIME 41 10032
TEST_TIME 42 9900
TEST_TIME 43 9811
TEST_TIME 44 9892
TEST_TIME 45 9783
TEST_TIME 46 9747
TEST_TIME 47 9782
TEST_TIME 48 9726
TEST_TIME 49 9772
TEST_TIME 50 10023
TEST_TIME 51 9899
TEST_TIME 52 9844
TEST_TIME 53 9735
TEST_TIME 54 9624
TEST_TIME 55 9762
TEST_TIME 56 9689
TEST_TIME 57 9752
TEST_TIME 58 9739
TEST_TIME 59 9702
TEST_TIME 60 9692
TEST_TIME 61 9692
TEST_TIME 62 9827
TEST_TIME 63 9912
TEST_TIME 64 9879
TEST_TIME 65 9893
TEST_TIME 66 10035
TEST_TIME 67 10066
TEST_TIME 68 10069
TEST_TIME 69 10146
TEST_TIME 70 10078
TEST_TIME 71 9983
TEST_TIME 72 9995
TEST_TIME 73 9977
TEST_TIME 74 9932
TEST_TIME 75 9905
TEST_TIME 76 9866
TEST_TIME 77 9834
TEST_TIME 78 9789
TEST_TIME 79 9771
TEST_TIME 80 9765
TEST_TIME 81 9712
TEST_TIME 82 9765
TEST_TIME 83 9743
TEST_TIME 84 9750
TEST_TIME 85 9726
TEST_TIME 86 9740
TEST_TIME 87 9799
TEST_TIME 88 9785
TEST_TIME 89 9834
TEST_TIME 90 9870
TEST_TIME 91 9906
TEST_TIME 92 9898
TEST_TIME 93 9975
TEST_TIME 94 9900
TEST_TIME 95 9810
TEST_TIME 96 9774
TEST_TIME 97 9758
TEST_TIME 98 9695
TEST_TIME 99 9660
TEST_TIME 100 9627
TEST_TIME 101 9596
TEST_TIME 102 9603
TEST_TIME 103 9551
TEST_TIME 104 9574
TEST_TIME 105 9634
TEST_TIME 106 9585
TEST_TIME 107 9583
TEST_TIME 108 9578
TEST_TIME 109 9585
TEST_TIME 110 9634
TEST_TIME 111 9626
TEST_TIME 112 9636
TEST_TIME 113 9682
TEST_TIME 114 9709
TEST_TIME 115 9731
TEST_TIME 116 9768
TEST_TIME 117 9723
TEST_TIME 118 9676
TEST_TIME 119 9645
TEST_TIME 120 9618

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-09-2021 16:39:51 to 14-09-2021 16:39:55.

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 1594203 1594203 0 0.00 0 23747 7 0 0
doc_list_biud 1594179 1594179 0 0.00 0 32351 27 0 0
sp_add_invoice_to_stock 5449 5223 226 4.14 35 188003 17282 0 226
sp_cancel_adding_invoice 5998 5998 0 0.00 5 88863 805 0 0
sp_cancel_client_order 1796 1796 0 0.00 4 11772 323 0 0
sp_cancel_customer_reserve 589024 589024 0 0.00 1 17251 59 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 2 8216 96 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 2728 71 0 0
sp_cancel_supplier_invoice 6546 6525 21 0.32 2 32381 806 0 21
sp_cancel_supplier_order 404 404 0 0.00 9 1006327 108871 0 0
sp_cancel_write_off 10567 10567 0 0.00 1 22969 116 0 0
sp_client_order 21267 21267 0 0.00 4 31100 367 0 0
sp_customer_reserve 476242 476242 0 0.00 0 109721 174 0 0
sp_fill_shopping_cart 32290 32290 0 0.00 1 12979 39 0 0
sp_fill_shopping_cart_clo_res 476242 476242 0 0.00 0 17048 13 0 0
sp_get_clo_for_invoice 5223 5223 0 0.00 0 17671 272 0 0
sp_kill_cost_storno 17682 17682 0 0.00 0 6996 53 0 0
sp_kill_qstorno_ret_qs2qd 1195544 1195544 0 0.00 0 32338 32 0 0
sp_kill_qty_storno 629801 629801 0 0.00 0 32349 69 0 0
sp_lock_selected_doc 21374 21374 0 0.00 0 9177 6 0 0
sp_make_cost_storno 28769 28769 0 0.00 0 18351 62 0 0
sp_multiply_rows_for_pdistr 28745 28745 0 0.00 0 16975 29 0 0
sp_multiply_rows_for_qdistr 21267 21267 0 0.00 0 16996 150 0 0
sp_pay_from_customer 7276 7276 0 0.00 4 17088 138 0 0
sp_pay_to_supplier 5991 5991 0 0.00 5 14806 134 0 0
sp_payment_common 13258 13258 0 0.00 2 12878 30 0 0
sp_qd_handle_on_cancel_clo 1796 1796 0 0.00 0 11765 291 0 0
sp_qd_handle_on_invoice_upd_sts 11214 11214 0 0.00 0 17844 323 0 0
sp_qd_handle_on_reserve_upd_sts 10271 10271 0 0.00 0 13716 49 0 0
sp_reserve_write_off 10279 10279 0 0.00 6 18368 175 0 0
sp_supplier_invoice 5410 5410 0 0.00 8 78833 3386 0 0
sp_supplier_order 5620 5620 0 0.00 5 32133 1282 0 0
srv_aggregate_perf_data 97 96 1 1.03 3294 227172 20947 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1688 156029 45640 0 0
srv_find_qd_qs_mism 1104806 1104806 0 0.00 0 23746 6 0 0
srv_make_invnt_saldo 586 586 0 0.00 49 45454 1385 0 0
srv_make_money_saldo 764 764 0 0.00 1 3406 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 130345 357115 217652 0 0
t$perf-abend:sp_add_invoice_to_stock 226 226 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 21 21 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5223 5223 0 0.00 0 9921 144 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 5128 83 0 0
t$perf-norm:sp_cancel_client_order 1796 1796 0 0.00 0 3797 12 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 2738 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 341 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 1385 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 473 9 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 24323 1688 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 1680 4 0 0
t$perf-norm:sp_client_order 21267 21267 0 0.00 0 6435 17 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 1070 5 0 0
t$perf-norm:sp_pay_from_customer 7276 7276 0 0.00 0 7016 12 0 0
t$perf-norm:sp_pay_to_supplier 5991 5991 0 0.00 0 5569 13 0 0
t$perf-norm:sp_reserve_write_off 10279 10279 0 0.00 0 6538 11 0 0
t$perf-norm:sp_supplier_invoice 5410 5410 0 0.00 0 10107 22 0 0
t$perf-norm:sp_supplier_order 5620 5620 0 0.00 0 2762 16 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 372 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 40 1 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 565 17 0 0
t$perf-norm:srv_make_money_saldo 764 764 0 0.00 0 2268 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10884 10432 452 4.15 0 5799 22 0 452
v_all_customers 38782 38782 0 0.00 0 137 1 0 0
v_all_suppliers 30502 30502 0 0.00 0 122 1 0 0
v_all_wares 42524 42524 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 274 6 0 0
v_cancel_client_order 3592 3592 0 0.00 0 2182 6 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 4442 10 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 3960 9 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 2723 13 0 0
v_cancel_supplier_invoice 804 762 42 5.22 0 4787 27 0 42
v_cancel_supplier_order 808 808 0 0.00 0 4098 9 0 0
v_cancel_write_off 2076 2076 0 0.00 0 5237 22 0 0
v_max_id_clo_ord 5619 5619 0 0.00 0 1863 2 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 2687 20 0 0
v_max_id_ord_sup 5409 5409 0 0.00 0 7957 5 0 0
v_max_non_paid_invoice 1763 1763 0 0.00 0 1070 3 0 0
v_max_non_paid_realizn 3817 3817 0 0.00 0 5046 7 0 0
v_min_id_clo_ord 5619 5619 0 0.00 0 6915 11 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 2196 6 0 0
v_min_id_ord_sup 5409 5409 0 0.00 0 10416 7 0 0
v_min_non_paid_invoice 5986 5986 0 0.00 0 6291 15 0 0
v_min_non_paid_realizn 7270 7270 0 0.00 0 8329 19 0 0
v_our_firm 10648 10648 0 0.00 0 16 1 0 0
v_reserve_write_off 20538 20538 0 0.00 0 6140 7 0 0
x_lock_depdocs_on_canc_invoice 12142 12142 0 0.00 0 11021 113 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 6406 324 0 0
x_make_qstorno_1000_1200 5620 5620 0 0.00 2 32123 1194 0 0
x_make_qstorno_1200_2000 5410 5410 0 0.00 4 78770 3248 0 0
x_make_qstorno_2100_3300 476242 476242 0 0.00 0 109714 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-09-2021 16:39:56 to 14-09-2021 16:40:00.

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:54 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:55 20920 8192000000 10373185568 10690486272 1420173840 14.774 100 84 168 0 1453446704 6112032 27160832
14:10:21 14236 8192000000 10444052096 10775851008 1500118576 15.477 100 91 182 0 1537606752 8431312 29056864
14:11:45 21611 8192000000 10499059840 10840911872 1552932592 15.935 100 88 176 0 1589600704 9822176 26845936
14:13:14 15368 8192000000 10564933792 10925154304 1630208592 16.597 100 85 170 0 1666144288 10923856 25011840
14:14:32 7085 8192000000 10626756912 10997764096 1697052016 17.160 100 79 158 0 1733073152 12832288 23188848
14:15:44 3827 8192000000 10689995440 11068014592 1751695008 17.616 100 78 156 1 1786280688 13080192 21505488
14:16:49 2696 8192000000 10741060784 11119550464 1800351264 18.017 100 89 178 0 1839270032 13969440 24949328
14:17:53 2522 8192000000 10773157344 11160817664 1837239584 18.318 100 86 171 3 1873893328 13294880 23358864
14:18:58 1698 8192000000 10794512688 11186683904 1857403488 18.482 100 83 160 0 1891378816 11238816 22736512
14:20:01 2307 8192000000 10818876112 11214458880 1874558400 18.621 100 87 173 3 1911803344 12066144 25178800
14:21:04 4833 8192000000 10787058720 11212345344 1878564768 18.654 100 33 66 0 1894316240 6551264 9200208
14:22:10 8024 8192000000 10789111424 11221221376 1885776784 18.712 100 25 46 2 1898675520 6265584 6633152
14:23:19 5385 8192000000 10798778336 11231678464 1894440608 18.782 100 24 47 2 1908172976 6935552 6796816
14:24:27 52024 8192000000 10811933536 11244814336 1895220160 18.788 100 37 73 3 1911779248 5193840 11365248
14:26:23 9112 8192000000 10815590656 11249913856 1882345616 18.684 99 36 72 0 1904087808 11072496 10669696
14:27:36 7944 8192000000 10780241264 11215503360 1870375840 18.587 100 21 42 0 1882079888 5792320 5911728
14:28:44 19053 8192000000 10693948592 11106127872 1709760544 17.267 93 23 46 1 1721611344 4928160 6922640
14:30:04 8683 8192000000 10681444592 11092549632 1630974080 16.603 90 22 44 0 1642652672 5486544 6192048
14:31:13 3169 8192000000 10724234784 11142336512 1809696080 18.093 100 33 66 0 1826690304 7350784 9643440
14:32:18 7328 8192000000 10730423808 11151749120 1800653536 18.019 99 26 52 0 1818044848 9933024 7458288
14:33:28 49620 8192000000 10804798736 11204431872 1859208816 18.497 100 60 124 0 1884563840 6220448 19134576
14:35:18 6103 8192000000 10787329440 11203117056 1861089952 18.512 100 30 61 3 1875332720 5924432 8318336
14:36:24 7091 8192000000 10791269760 11222249472 1873752560 18.615 100 20 40 2 1889697200 9576032 6368608
14:37:33 7627 8192000000 10822789824 11250196480 1904357088 18.861 100 18 36 1 1914168608 5210720 4600800
14:38:51 49535 8192000000 10836701232 11269754880 1920826720 18.993 100 42 82 3 1941632432 8401248 12404464
14:40:59 20478 8192000000 10869188128 11303477248 1926286176 19.037 99 38 76 0 1947744480 10847904 10610400
14:42:24 35315 8192000000 10888550784 11323957248 1951891104 19.242 100 47 94 2 1977435456 12720448 12823904
14:44:20 9144 8192000000 10927552704 11357032448 1975464336 19.429 100 50 101 1 2006776000 16843824 14467840
14:45:32 11213 8192000000 10913487040 11348090880 1942865040 19.170 98 54 105 0 1980840032 22028784 15946208
14:46:47 32505 8192000000 10927138032 11370364928 1976919568 19.440 100 39 78 1 2009681504 20813424 11948512
14:48:22 6103 8192000000 10967167024 11393634304 2006795280 19.676 100 58 114 1 2048644656 25139216 16710160
14:49:31 6223 8192000000 10964808512 11382038528 1989678000 19.541 100 73 145 0 2041373504 30277232 21418272
14:50:44 10952 8192000000 10942210816 11387211776 1987092928 19.521 99 40 80 0 2030794160 30550672 13150560
14:52:10 1687 8192000000 10930456320 11349942272 1954864352 19.265 99 67 134 1 2010792304 34685200 21242752
14:53:18 3214 8192000000 10953337280 11368022016 1969641152 19.383 100 87 173 1 2035829328 39178272 27009904
14:54:25 6031 8192000000 10956734576 11381940224 1983516112 19.493 100 62 124 2 2038682000 35701056 19464832
14:55:37 2868 8192000000 10944550144 11366739968 1969474496 19.381 100 57 113 1 2024904752 37569568 17860688
14:56:51 3124 8192000000 10954102752 11381530624 1968510272 19.374 100 57 114 1 2034897328 47837984 18549072
14:57:59 6766 8192000000 10977956976 11404054528 1982331760 19.483 100 46 91 1 2050392976 52152368 15908848
14:59:09 10348 8192000000 10975544544 11412701184 1991752720 19.558 100 48 95 0 2063126176 54657760 16715696
15:00:23 17555 8192000000 10961097744 11397533696 1960172720 19.307 99 40 81 1 2029271824 54327536 14771568
15:01:43 16714 8192000000 10945436192 11392888832 1976086288 19.434 100 34 68 2 2033397744 45303728 12007728
15:03:06 19436 8192000000 10923264896 11382439936 1948491152 19.214 99 21 42 2 1997966048 42789712 6685184
15:04:48 21378 8192000000 10922142016 11376037888 1952382960 19.245 100 24 48 0 2002521936 41915504 8223472
15:06:21 29492 8192000000 10901248224 11371720704 1956403168 19.277 100 16 32 0 1999293616 37215888 5674560
15:07:52 102450 8192000000 10920974672 11354140672 1930623504 19.072 98 11 22 0 1947442128 14251952 2566672
15:10:35 87079 8192000000 10920399136 11385397248 1942357552 19.166 98 12 24 0 1963339056 17529584 3451920
15:13:09 30959 8192000000 10947122224 11379380224 1973025088 19.409 99 52 102 0 2000518656 12764784 14728784
15:14:44 97600 8192000000 10934284032 11392987136 1991012992 19.552 100 21 42 1 2013987760 17665856 5308912
15:17:28 52771 8192000000 10927136528 11405062144 1990813184 19.550 100 11 22 0 2014593056 20788000 2991872
15:19:29 33302 8192000000 10908222592 11355418624 1917319152 18.965 96 23 46 1 1933576864 10576144 5681568
15:21:06 156029 8192000000 10967857088 11407818752 1999633296 19.620 100 47 94 2 2035915760 22061744 14220720
15:24:43 12637 8192000000 10952893584 11413630976 2007376592 19.681 100 31 62 1 2028233536 11581632 9275312
15:26:12 43627 8192000000 10957992368 11420385280 2012610336 19.722 100 51 102 1 2046943344 18613264 15719744
15:28:00 15484 8192000000 10920435264 11416821760 1978825968 19.455 99 7 15 0 1995441568 14644752 1970848
15:29:17 58335 8192000000 10931660688 11403984896 1983822880 19.495 99 16 32 1 2000508928 12113584 4572464
15:31:18 60881 8192000000 10914469296 11383820288 1965761056 19.352 99 19 38 0 1985467616 13691600 6014960
15:33:26 44574 8192000000 10908147600 11381436416 1934869328 19.106 97 26 52 2 1955486864 12366144 8251392
15:35:14 53428 8192000000 10898783904 11376615424 1945073184 19.187 98 30 59 1 1963402128 9704912 8624032
15:37:14 16077 8192000000 10872386032 11331653632 1894949824 18.786 97 21 41 1 1911887728 11589568 5348336
15:38:33 28309 8192000000 10857461232 11311775744 1860720208 18.509 96 32 64 0 1885581728 16131440 8730080
15:40:04 67653 8192000000 10854132528 11323883520 1850611936 18.427 96 21 41 3 1880110832 23982752 5516144
15:42:13 88019 8192000000 10866191680 11329048576 1859894624 18.502 96 18 36 0 1889081136 23896560 5289952
15:44:45 51371 8192000000 10870018640 11332616192 1857161840 18.480 96 12 24 0 1878039664 17844128 3033696
15:46:37 42688 8192000000 10868790528 11318611968 1852275152 18.441 96 23 46 1 1868324608 9807216 6242240
15:48:23 123011 8192000000 10882880096 11346276352 1916353248 18.958 99 31 62 2 1941270544 16168832 8748464
15:51:32 34798 8192000000 10887841104 11354660864 1952396432 19.246 100 33 66 0 1974925248 13460320 9068496
15:53:09 64974 8192000000 10878937776 11336011776 1892902016 18.769 97 23 46 0 1909116240 9726656 6487568
15:55:17 62828 8192000000 10875509808 11350650880 1950999840 19.234 100 17 34 0 1966559104 10438256 5121008
15:57:22 25429 8192000000 10894324144 11362500608 1956221920 19.276 100 23 46 0 1970323408 7865056 6236432
15:58:48 52117 8192000000 10867613952 11350552576 1943752192 19.177 100 14 28 2 1958750480 10705504 4292784
16:00:41 23323 8192000000 10867347120 11331076096 1924206768 19.021 99 38 76 1 1944011648 9002176 10802704
16:02:09 29755 8192000000 10901507264 11365752832 1962485392 19.326 100 31 62 0 1983139408 12081696 8572320
16:03:42 68186 8192000000 10894635024 11376009216 1963494640 19.334 100 13 26 1 1979213296 11688928 4029728
16:05:59 76995 8192000000 10912501552 11395760128 1955528320 19.270 99 16 32 0 1982967488 23297456 4141712
16:08:53 75086 8192000000 10914391568 11374948352 1868980896 18.576 94 23 46 1 1889488192 14262880 6244416
16:11:13 104837 8192000000 10863513568 11329761280 1882352304 18.684 96 18 36 0 1902791920 15142704 5296912
16:14:13 60555 8192000000 10891717296 11373182976 1966566336 19.358 100 10 18 0 1981388752 12593488 2228928
16:16:29 71638 8192000000 10898202816 11393486848 1955560096 19.271 99 4 8 1 1970425568 14027344 838128
16:18:42 55432 8192000000 10886952048 11366035456 1961244848 19.316 100 13 26 0 1978822400 13545968 4031584
16:20:44 49755 8192000000 10896676576 11373428736 1931620080 19.080 98 17 34 0 1950351920 13229440 5502400
16:22:35 37022 8192000000 10863574240 11338731520 1882937472 18.689 96 14 28 0 1896610896 10338928 3334496
16:24:18 62690 8192000000 10879798432 11350265856 1891848192 18.761 97 26 52 0 1912336912 13380336 7108384
16:26:25 84863 8192000000 10864478272 11340296192 1865023888 18.544 96 11 22 1 1882370896 14257744 3089264
16:28:55 40023 8192000000 10876866048 11352489984 1931006016 19.075 100 21 42 0 1952656272 15795504 5854752
16:30:48 113069 8192000000 10862254608 11349086208 1878231488 18.651 97 8 16 0 1899366688 19215456 1919744
16:34:15 129953 8192000000 10887362480 11352227840 1948772576 19.217 100 28 56 0 1964552480 8244528 7535376
16:37:43 11137 8192000000 9409904752 9712459776 559955040 6.398 30 4 8 0 567671280 6995360 720880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-09-2021 16:40:02 to 14-09-2021 16:40:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 226 v_add_invoice_to_stock 335544517
except 21 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 14-09-2021 16:40:18 to 14-09-2021 16:40:19.

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          247760
MON$OLDEST_ACTIVE               247761
MON$OLDEST_SNAPSHOT             247761
MON$NEXT_TRANSACTION            247761
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                       2062816
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.33495 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33495 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33495 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33495 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 ---
:186: 
:187: mscs-test-161.budzdorov.ru    Tue Sep 14 16:38:07 2021
:188:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:189: 
:190: 
:191: mscs-test-161.budzdorov.ru    Tue Sep 14 16:38:07 2021
:192:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:193: 
--- end of diff output ---

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

14.09.21 16:40:21. 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-09-14 16:40:23 No severe PSQL-related problems occured
14.09.21 16:40:24. End of report.