Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.10.2021 01: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 16075788 kB
MemAvailable 30806700 kB
Buffers 0 kB
Cached 15592368 kB
SwapCached 4368 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-10-31 01:37:56
DTS_END                         2021-10-31 01:37:56.8000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42550
TRN_ID                          242062
ATT_ID                          722
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: 9380.85 23815 1125702
customer order: creation 172.53 341 20703
customer order: refuse 15.65 299 1878
order to supplier: creation 47.24 1253 5669
order to supplier: removal 3.76 95320 451
invoice (draft): creation 47.73 3386 5727
invoice (draft): removal 53.54 874 6425
invoice accept: apply 41.91 17215 5029
invoice accept: cancel 48.08 797 5769
customer reserve: creation 3911.39 168 469367
customer reserve: removal 4746.71 58 569605
realization accept: apply 84.61 151 10153
realization accept: cancel 76.98 107 9238
payment from customer: creation 59.24 93 7109
payment from customer: removal 5.88 73 706
payment to supplier: creation 49.65 95 5958
payment to supplier: removal 3.52 51 422
service: total inventory turnovers 5.15 923 618
service: total monetary turnovers 6.44 59 773
service: aggregate perf. data 0.82 19540 98
service: refresh index statistics 0.03 335502 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-10-2021 01:38:46 to 31-10-2021 01:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3104
TEST_TIME 2 4278
TEST_TIME 3 5099
TEST_TIME 4 4778
TEST_TIME 5 5401
TEST_TIME 6 5700
TEST_TIME 7 5593
TEST_TIME 8 5447
TEST_TIME 9 5789
TEST_TIME 10 5660
TEST_TIME 11 5604
TEST_TIME 12 5910
TEST_TIME 13 5978
TEST_TIME 14 6046
TEST_TIME 15 6353
TEST_TIME 16 7041
TEST_TIME 17 7051
TEST_TIME 18 7158
TEST_TIME 19 7216
TEST_TIME 20 7304
TEST_TIME 21 7842
TEST_TIME 22 7752
TEST_TIME 23 7997
TEST_TIME 24 8151
TEST_TIME 25 8163
TEST_TIME 26 8648
TEST_TIME 27 9590
TEST_TIME 28 10209
TEST_TIME 29 10381
TEST_TIME 30 10540
TEST_TIME 31 10439
TEST_TIME 32 10381
TEST_TIME 33 10082
TEST_TIME 34 10185
TEST_TIME 35 10084
TEST_TIME 36 9969
TEST_TIME 37 9913
TEST_TIME 38 9912
TEST_TIME 39 9989
TEST_TIME 40 9880
TEST_TIME 41 9816
TEST_TIME 42 9838
TEST_TIME 43 9766
TEST_TIME 44 9617
TEST_TIME 45 9466
TEST_TIME 46 9433
TEST_TIME 47 9380
TEST_TIME 48 9432
TEST_TIME 49 9477
TEST_TIME 50 9486
TEST_TIME 51 9487
TEST_TIME 52 9573
TEST_TIME 53 9486
TEST_TIME 54 9597
TEST_TIME 55 9621
TEST_TIME 56 9683
TEST_TIME 57 9684
TEST_TIME 58 9709
TEST_TIME 59 9669
TEST_TIME 60 9645
TEST_TIME 61 9562
TEST_TIME 62 9480
TEST_TIME 63 9476
TEST_TIME 64 9472
TEST_TIME 65 9368
TEST_TIME 66 9449
TEST_TIME 67 9405
TEST_TIME 68 9378
TEST_TIME 69 9407
TEST_TIME 70 9560
TEST_TIME 71 9562
TEST_TIME 72 9594
TEST_TIME 73 9547
TEST_TIME 74 9564
TEST_TIME 75 9600
TEST_TIME 76 9595
TEST_TIME 77 9596
TEST_TIME 78 9616
TEST_TIME 79 9715
TEST_TIME 80 9607
TEST_TIME 81 9547
TEST_TIME 82 9465
TEST_TIME 83 9511
TEST_TIME 84 9505
TEST_TIME 85 9432
TEST_TIME 86 9422
TEST_TIME 87 9435
TEST_TIME 88 9430
TEST_TIME 89 9396
TEST_TIME 90 9390
TEST_TIME 91 9346
TEST_TIME 92 9405
TEST_TIME 93 9388
TEST_TIME 94 9441
TEST_TIME 95 9467
TEST_TIME 96 9519
TEST_TIME 97 9525
TEST_TIME 98 9509
TEST_TIME 99 9525
TEST_TIME 100 9545
TEST_TIME 101 9623
TEST_TIME 102 9605
TEST_TIME 103 9574
TEST_TIME 104 9498
TEST_TIME 105 9451
TEST_TIME 106 9454
TEST_TIME 107 9422
TEST_TIME 108 9393
TEST_TIME 109 9379
TEST_TIME 110 9386
TEST_TIME 111 9365
TEST_TIME 112 9370
TEST_TIME 113 9356
TEST_TIME 114 9357
TEST_TIME 115 9361
TEST_TIME 116 9363
TEST_TIME 117 9389
TEST_TIME 118 9405
TEST_TIME 119 9406
TEST_TIME 120 9386

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-10-2021 01:38:50 to 31-10-2021 01:38:53.

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 1559857 1559857 0 0.00 0 12162 6 0 0
doc_list_biud 1559857 1559857 0 0.00 0 47988 27 0 0
sp_add_invoice_to_stock 5096 5029 67 1.31 33 188427 17216 0 67
sp_cancel_adding_invoice 5769 5769 0 0.00 5 82790 797 0 0
sp_cancel_client_order 1878 1878 0 0.00 4 7690 300 0 0
sp_cancel_customer_reserve 569605 569605 0 0.00 1 30246 59 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 2 8619 73 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 2613 52 0 0
sp_cancel_supplier_invoice 6431 6425 6 0.09 2 16295 875 0 6
sp_cancel_supplier_order 451 451 0 0.00 9 991865 95320 0 0
sp_cancel_write_off 9238 9238 0 0.00 1 10805 108 0 0
sp_client_order 20703 20703 0 0.00 3 20158 342 0 0
sp_customer_reserve 469367 469367 0 0.00 0 75157 169 0 0
sp_fill_shopping_cart 32085 32085 0 0.00 1 10446 36 0 0
sp_fill_shopping_cart_clo_res 469364 469364 0 0.00 0 14542 13 0 0
sp_get_clo_for_invoice 5020 5020 0 0.00 0 14650 294 0 0
sp_kill_cost_storno 16135 16135 0 0.00 0 9998 52 0 0
sp_kill_qstorno_ret_qs2qd 1155324 1155324 0 0.00 0 30241 32 0 0
sp_kill_qty_storno 608537 608537 0 0.00 0 47986 70 0 0
sp_lock_selected_doc 21189 21189 0 0.00 0 8255 5 0 0
sp_make_cost_storno 28238 28238 0 0.00 0 12132 45 0 0
sp_multiply_rows_for_pdistr 28238 28238 0 0.00 0 7891 25 0 0
sp_multiply_rows_for_qdistr 20703 20703 0 0.00 0 12424 166 0 0
sp_pay_from_customer 7109 7109 0 0.00 4 9821 93 0 0
sp_pay_to_supplier 5958 5958 0 0.00 5 12140 95 0 0
sp_payment_common 13067 13067 0 0.00 1 7665 19 0 0
sp_qd_handle_on_cancel_clo 1878 1878 0 0.00 0 7679 271 0 0
sp_qd_handle_on_invoice_upd_sts 10787 10787 0 0.00 0 47984 331 0 0
sp_qd_handle_on_reserve_upd_sts 10153 10153 0 0.00 0 17529 49 0 0
sp_reserve_write_off 10153 10153 0 0.00 6 17543 151 0 0
sp_supplier_invoice 5727 5727 0 0.00 7 96143 3386 0 0
sp_supplier_order 5669 5669 0 0.00 5 35080 1254 0 0
srv_aggregate_perf_data 99 98 1 1.01 3030 390213 19540 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1546 145265 49649 0 0
srv_find_qd_qs_mism 1077106 1077106 0 0.00 0 12162 5 0 0
srv_make_invnt_saldo 618 618 0 0.00 41 36730 923 0 0
srv_make_money_saldo 773 773 0 0.00 1 24846 59 0 0
srv_recalc_idx_stat 4 4 0 0.00 147359 407434 335502 0 0
t$perf-abend:sp_add_invoice_to_stock 67 67 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 5029 5029 0 0.00 0 8910 143 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 0 23175 144 0 0
t$perf-norm:sp_cancel_client_order 1878 1878 0 0.00 0 4733 15 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 6754 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 1773 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 1719 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 2396 18 0 0
t$perf-norm:sp_cancel_supplier_order 451 451 0 0.00 0 18078 1356 0 0
t$perf-norm:sp_cancel_write_off 1078 1078 0 0.00 0 4246 12 0 0
t$perf-norm:sp_client_order 20703 20703 0 0.00 0 9921 15 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 5457 9 0 0
t$perf-norm:sp_pay_from_customer 7109 7109 0 0.00 0 4909 7 0 0
t$perf-norm:sp_pay_to_supplier 5958 5958 0 0.00 0 8124 9 0 0
t$perf-norm:sp_reserve_write_off 10153 10153 0 0.00 0 8107 10 0 0
t$perf-norm:sp_supplier_invoice 5727 5727 0 0.00 0 7725 16 0 0
t$perf-norm:sp_supplier_order 5669 5669 0 0.00 0 8177 17 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 190 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 309 10 0 0
t$perf-norm:srv_make_invnt_saldo 618 618 0 0.00 0 932 18 0 0
t$perf-norm:srv_make_money_saldo 773 773 0 0.00 0 2044 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10162 10028 134 1.31 0 3922 19 0 134
v_all_customers 37826 37826 0 0.00 0 13 0 0 0
v_all_suppliers 31778 31778 0 0.00 0 17 1 0 0
v_all_wares 41404 41404 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 85 5 0 0
v_cancel_client_order 3756 3756 0 0.00 0 3184 6 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 1587 5 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 1888 7 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 2603 6 0 0
v_cancel_supplier_invoice 840 828 12 1.42 0 1104 14 0 12
v_cancel_supplier_order 902 902 0 0.00 0 1678 8 0 0
v_cancel_write_off 2156 2156 0 0.00 0 7687 21 0 0
v_max_id_clo_ord 5669 5669 0 0.00 0 618 1 0 0
v_max_id_clo_res 996 996 0 0.00 0 4246 18 0 0
v_max_id_ord_sup 5714 5714 0 0.00 0 7084 4 0 0
v_max_non_paid_invoice 1452 1452 0 0.00 0 2013 4 0 0
v_max_non_paid_realizn 3756 3756 0 0.00 0 2587 4 0 0
v_min_id_clo_ord 5669 5669 0 0.00 0 1615 5 0 0
v_min_id_clo_res 996 996 0 0.00 0 6227 15 0 0
v_min_id_ord_sup 5714 5714 0 0.00 0 7145 7 0 0
v_min_non_paid_invoice 5958 5958 0 0.00 0 4160 11 0 0
v_min_non_paid_realizn 7109 7109 0 0.00 0 7660 16 0 0
v_our_firm 10284 10284 0 0.00 0 15 1 0 0
v_reserve_write_off 20306 20306 0 0.00 0 3331 6 0 0
x_lock_depdocs_on_canc_invoice 11780 11780 0 0.00 0 29018 115 0 0
x_lock_depdocs_on_canc_sup_ord 451 451 0 0.00 0 3805 311 0 0
x_make_qstorno_1000_1200 5669 5669 0 0.00 1 35004 1179 0 0
x_make_qstorno_1200_2000 5727 5727 0 0.00 3 96133 3254 0 0
x_make_qstorno_2100_3300 469367 469367 0 0.00 0 75127 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-10-2021 01:38:54 to 31-10-2021 01:38:58.

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
23:08:00 6 8192000000 8803422848 8816267264 9097696 0.110 3 1 2 0 9214208 59104 57408
23:09:01 14572 8192000000 9889726912 10199601152 977490576 10.660 100 95 190 0 1008150704 5562144 25097984
23:10:24 22576 8192000000 9944590416 10265096192 1022000960 11.091 100 91 182 1 1054074976 8227712 23846304
23:11:53 4341 8192000000 9972905312 10300977152 1055073696 11.409 100 88 176 0 1086312208 9076288 22162224
23:13:05 13984 8192000000 10025691184 10372235264 1115648816 11.986 100 79 158 1 1144379472 9727424 19003232
23:14:23 8840 8192000000 10074070480 10439200768 1169516304 12.492 100 79 158 1 1199420048 11499424 18404320
23:15:36 4205 8192000000 10123533232 10496913408 1211992176 12.888 100 71 143 1 1239197456 10584640 16620640
23:16:43 9113 8192000000 10160476992 10538074112 1247921712 13.219 100 80 160 0 1279457360 14446496 17089152
23:17:53 2252 8192000000 10191642240 10570375168 1274489424 13.463 100 93 188 0 1309505440 13626224 21389792
23:18:57 24495 8192000000 10215978256 10606333952 1297166464 13.669 100 66 134 1 1325856704 13503968 15186272
23:20:23 8386 8192000000 10196502720 10610302976 1310901872 13.794 100 25 50 1 1323534128 6668928 5963328
23:21:32 8963 8192000000 10213643440 10627231744 1323669824 13.910 100 26 52 0 1336902224 7539760 5692640
23:22:43 61891 8192000000 10231429760 10645827584 1332216800 13.987 100 40 80 0 1347209712 5271568 9721344
23:24:49 7559 8192000000 10244520112 10661634048 1339556112 14.053 100 25 50 0 1351265552 6084000 5625440
23:25:57 10792 8192000000 10225727536 10651807744 1329894192 13.966 99 19 37 2 1340041744 6410064 3737488
23:27:09 10838 8192000000 10213882064 10629914624 1296297056 13.662 97 23 45 0 1307053936 5676048 5080832
23:28:22 29259 8192000000 10193633008 10609504256 1256616528 13.299 95 27 54 0 1268745984 5987968 6141488
23:29:54 3879 8192000000 10146047104 10542673920 1222925696 12.989 97 48 94 1 1239700944 5168560 11606688
23:31:02 8389 8192000000 10144019216 10539216896 1223283424 12.992 98 42 83 2 1242801296 10324672 9193200
23:32:13 69211 8192000000 10144213184 10549301248 1243082128 13.175 98 23 46 0 1257374960 9420752 4872080
23:34:25 9014 8192000000 10165886992 10581221376 1263280256 13.360 98 15 30 1 1272182672 5321072 3581344
23:35:35 8294 8192000000 10191977136 10608177152 1302778512 13.720 100 14 26 0 1310756176 5223808 2753856
23:36:44 7652 8192000000 10209092624 10626244608 1317514832 13.854 100 24 49 0 1328084336 4684912 5884592
23:37:57 28812 8192000000 10233664480 10643136512 1330174176 13.969 100 53 104 2 1352723216 11354816 11194224
23:39:35 17306 8192000000 10251729536 10670911488 1351098064 14.157 100 40 78 0 1367041344 7581104 8362176
23:40:57 5364 8192000000 10274991680 10693001216 1366509776 14.296 100 55 110 1 1389160720 9866400 12784544
23:42:08 22927 8192000000 10291471536 10715787264 1378804320 14.406 100 46 92 0 1401490848 11577280 11109248
23:43:42 12803 8192000000 10299823888 10725216256 1392946240 14.532 100 48 96 1 1414326880 10159264 11221376
23:45:02 21711 8192000000 10307621760 10734837760 1389444064 14.501 99 32 63 3 1409486496 12088064 7954368
23:46:30 6703 8192000000 10326743280 10749034496 1395585936 14.556 99 54 108 1 1423951616 15766080 12599600
23:47:41 20749 8192000000 10332050016 10762448896 1413364320 14.714 100 46 90 0 1441845488 17210656 11270512
23:49:07 11128 8192000000 10350368800 10779893760 1419884304 14.772 100 50 100 1 1458517184 25533648 13099232
23:50:21 3100 8192000000 10351519920 10773491712 1411049712 14.693 99 71 142 2 1450276912 20957472 18269728
23:51:27 4823 8192000000 10370120784 10791342080 1427846688 14.842 100 68 136 1 1473582416 28734336 17001392
23:52:42 9779 8192000000 10357909600 10782232576 1414994048 14.728 100 62 122 0 1467863824 36569728 16300048
23:53:56 4564 8192000000 10375880544 10791583744 1421431312 14.785 100 89 172 1 1480808144 37279104 22097728
23:55:04 1546 8192000000 10377688096 10793398272 1416147392 14.739 99 77 154 0 1477648976 42436544 19065040
23:56:16 3386 8192000000 10394539184 10808160256 1428524192 14.848 100 78 155 0 1492611232 44947392 19139648
23:57:29 7865 8192000000 10372988912 10789986304 1404573056 14.636 100 62 124 2 1474918336 53812400 16532880
23:58:40 3123 8192000000 10361882368 10783784960 1400229152 14.597 100 53 108 2 1459335392 44905504 14200736
23:59:47 11770 8192000000 10353373600 10772594688 1383722944 14.450 100 50 99 2 1451392624 54529296 13140384
00:01:14 54684 8192000000 10321865776 10757189632 1375256896 14.374 100 39 78 2 1445421360 58490384 11674080
00:03:11 21523 8192000000 10310287472 10748190720 1339157376 14.050 99 27 54 2 1413340816 65601568 8581872
00:04:49 28101 8192000000 10277224784 10719199232 1321253552 13.888 98 15 30 0 1377575680 51898224 4423904
00:06:22 27842 8192000000 10265176544 10686033920 1313408080 13.817 97 38 76 0 1335834960 13591232 8835648
00:07:50 65497 8192000000 10286361776 10709102592 1339653136 14.054 99 24 48 0 1358187664 13387920 5146608
00:10:04 132865 8192000000 10240587488 10692472832 1279080464 13.505 94 14 27 0 1293704976 11943856 2680656
00:13:18 5206 8192000000 10270395648 10709356544 1364200560 14.275 100 33 69 0 1376951360 5196848 7553952
00:14:25 77744 8192000000 10296248096 10731118592 1361775904 14.253 99 37 71 2 1382862640 12506592 8580144
00:17:01 130447 8192000000 10282657648 10731421696 1343253040 14.087 97 24 48 0 1356849120 8540672 5055408
00:20:57 76974 8192000000 10261726016 10721804288 1361786176 14.253 99 10 20 1 1371229168 7346288 2096704
00:23:15 43807 8192000000 10280928224 10735886336 1371535808 14.341 99 18 36 0 1384185248 8689088 3960352
00:24:59 43789 8192000000 10283558176 10756132864 1384920320 14.461 100 10 20 0 1397710144 10361088 2428736
00:26:51 29230 8192000000 10296133344 10757627904 1395235440 14.553 100 17 34 1 1408768256 9605952 3926864
00:29:09 50212 8192000000 10299271216 10758803456 1383998448 14.452 99 27 54 2 1405701040 15057744 6644848
00:31:03 54948 8192000000 10305550800 10775056384 1397523984 14.573 100 24 48 0 1421527808 18590480 5413344
00:32:58 69007 8192000000 10301862560 10767699968 1366078288 14.292 97 16 32 0 1385770912 16019696 3672928
00:35:09 60348 8192000000 10299358624 10729652224 1296388848 13.662 93 32 63 0 1310384288 7618768 6376672
00:37:11 105637 8192000000 10276048912 10721087488 1327253584 13.942 96 46 92 0 1349494848 11437184 10804080
00:39:58 12538 8192000000 10268782992 10706497536 1347442336 14.124 99 34 68 1 1362576512 6724288 8409888
00:41:11 44922 8192000000 10247469504 10708545536 1357588576 14.216 100 6 12 0 1366499456 7809024 1101856
00:42:57 57650 8192000000 10246032912 10707775488 1295995968 13.659 95 15 31 0 1314764480 15223440 3545072
00:44:57 64487 8192000000 10284167360 10730229760 1349015712 14.139 98 24 47 2 1369109024 14223536 5869776
00:47:08 54023 8192000000 10258756432 10721079296 1334476576 14.008 99 9 18 0 1354117888 17866384 1774928
00:49:10 71055 8192000000 10258735920 10693361664 1314797888 13.830 97 36 75 0 1332096512 8754144 8544480
00:51:22 68694 8192000000 10276154944 10727432192 1338737408 14.046 98 31 62 0 1359649664 14384656 6527600
00:53:31 70807 8192000000 10275458640 10721984512 1329328976 13.961 97 18 36 1 1345987248 12591904 4066368
00:55:45 125686 8192000000 10263342912 10715492352 1311019776 13.795 96 12 24 0 1332133712 18017088 3096848
00:59:12 63448 8192000000 10284651168 10732658688 1369604112 14.323 100 26 52 0 1395712720 20074784 6033824
01:01:16 81636 8192000000 10272238064 10731962368 1375617536 14.377 100 10 19 0 1388476944 10555600 2303808
01:03:38 76369 8192000000 10277351152 10743963648 1372806048 14.352 99 14 28 0 1386353664 10328560 3219056
01:07:11 40583 8192000000 10289805168 10743406592 1388165264 14.489 100 13 26 1 1400135440 9252624 2717552
01:08:56 98093 8192000000 10325585312 10765017088 1387276224 14.482 99 19 38 0 1398975328 7697968 4001136
01:11:35 95553 8192000000 10306649456 10778857472 1396457248 14.563 100 8 16 1 1416306944 17718944 2130752
01:14:11 65992 8192000000 10314737072 10760138752 1375121792 14.373 98 20 40 0 1388099616 9207072 3770752
01:16:17 73369 8192000000 10345079936 10798772224 1409962368 14.684 100 21 42 1 1425004352 10547200 4494784
01:18:32 145264 8192000000 10303319728 10774331392 1323605152 13.909 94 9 18 1 1351382400 26164816 1612432
01:22:05 31487 8192000000 10310479728 10761523200 1407410672 14.661 100 34 68 1 1423636928 8696592 7529664
01:23:36 49869 8192000000 10302067680 10765873152 1397230592 14.570 100 6 12 1 1409239264 10493184 1515488
01:25:31 69471 8192000000 10311454400 10773204992 1395857520 14.558 99 11 23 0 1415130560 16471568 2801472
01:27:48 101388 8192000000 10280882464 10752032768 1356795008 14.209 97 17 34 1 1376433904 15449488 4189408
01:30:30 36165 8192000000 10277369280 10731827200 1357168416 14.212 98 19 37 1 1370292864 8501200 4623248
01:32:09 27529 8192000000 10279067776 10729492480 1327970224 13.949 96 28 56 0 1346286608 12135552 6180832
01:33:39 73105 8192000000 10262267216 10716700672 1298835872 13.685 95 9 18 1 1311896928 10785504 2275552
01:35:52 103679 8192000000 10240278864 10682499072 1247737952 13.217 92 21 42 1 1264391872 12079632 4574288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-10-2021 01:39:00 to 31-10-2021 01:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 67 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-10-2021 01:39:16 to 31-10-2021 01:39:18.

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          239952
MON$OLDEST_ACTIVE               242191
MON$OLDEST_SNAPSHOT             242191
MON$NEXT_TRANSACTION            242191
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                       2025440
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.33525 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33525 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33525 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33525 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 ---
:53: 
:54: mscs-test-161.budzdorov.ru    Sun Oct 31 01:38:01 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Sun Oct 31 01:38:05 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Sun Oct 31 01:38:05 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Sun Oct 31 01:38:05 2021
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Sun Oct 31 01:38:05 2021
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:72: 
:73: 
:74: mscs-test-161.budzdorov.ru    Sun Oct 31 01:38:05 2021
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:76: 
:77: 
:78: mscs-test-161.budzdorov.ru    Sun Oct 31 01:38:05 2021
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:80: 
:81: 
:82: mscs-test-161.budzdorov.ru    Sun Oct 31 01:38:05 2021
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:84: 
:85: 
:86: mscs-test-161.budzdorov.ru    Sun Oct 31 01:38:05 2021
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:88: 
:89: 
:90: mscs-test-161.budzdorov.ru    Sun Oct 31 01:38:05 2021
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:92: 
--- end of diff output ---

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

31.10.21 01:39:19. 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-10-31 01:39:21 No severe PSQL-related problems occured
31.10.21 01:39:22. End of report.