Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.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 16243844 kB
MemAvailable 31008168 kB
Buffers 0 kB
Cached 15395064 kB
SwapCached 2576 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-06 22:37:43
DTS_END                         2021-08-06 22:37:43.8690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34902
TRN_ID                          241329
ATT_ID                          729
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: 9127.75 20012 1095330
customer order: creation 175.48 354 21058
customer order: refuse 15.20 396 1824
order to supplier: creation 46.29 1329 5555
order to supplier: removal 3.13 104581 376
invoice (draft): creation 45.98 3852 5518
invoice (draft): removal 50.53 889 6064
invoice accept: apply 42.22 19940 5066
invoice accept: cancel 45.60 865 5472
customer reserve: creation 3934.88 196 472186
customer reserve: removal 4473.89 57 536867
realization accept: apply 84.07 176 10088
realization accept: cancel 82.08 109 9850
payment from customer: creation 59.02 109 7082
payment from customer: removal 5.48 73 657
payment to supplier: creation 48.09 127 5771
payment to supplier: removal 3.33 60 400
service: total inventory turnovers 4.42 1377 530
service: total monetary turnovers 7.28 49 873
service: aggregate perf. data 0.74 23913 89
service: refresh index statistics 0.03 241778 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-08-2021 22:38:55 to 06-08-2021 22:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6816
TEST_TIME 2 4091
TEST_TIME 3 4287
TEST_TIME 4 4739
TEST_TIME 5 5461
TEST_TIME 6 5592
TEST_TIME 7 5280
TEST_TIME 8 5178
TEST_TIME 9 5377
TEST_TIME 10 5404
TEST_TIME 11 5778
TEST_TIME 12 6507
TEST_TIME 13 6749
TEST_TIME 14 7071
TEST_TIME 15 7332
TEST_TIME 16 7495
TEST_TIME 17 7671
TEST_TIME 18 7952
TEST_TIME 19 8220
TEST_TIME 20 8265
TEST_TIME 21 8225
TEST_TIME 22 8382
TEST_TIME 23 8968
TEST_TIME 24 8988
TEST_TIME 25 9179
TEST_TIME 26 9171
TEST_TIME 27 9864
TEST_TIME 28 10127
TEST_TIME 29 10592
TEST_TIME 30 10744
TEST_TIME 31 10616
TEST_TIME 32 10548
TEST_TIME 33 10646
TEST_TIME 34 10429
TEST_TIME 35 10142
TEST_TIME 36 10175
TEST_TIME 37 10079
TEST_TIME 38 9946
TEST_TIME 39 9780
TEST_TIME 40 9696
TEST_TIME 41 9599
TEST_TIME 42 9709
TEST_TIME 43 9614
TEST_TIME 44 9506
TEST_TIME 45 9486
TEST_TIME 46 9499
TEST_TIME 47 9370
TEST_TIME 48 9274
TEST_TIME 49 9204
TEST_TIME 50 9169
TEST_TIME 51 9083
TEST_TIME 52 9200
TEST_TIME 53 9219
TEST_TIME 54 9219
TEST_TIME 55 9182
TEST_TIME 56 9227
TEST_TIME 57 9194
TEST_TIME 58 9279
TEST_TIME 59 9376
TEST_TIME 60 9368
TEST_TIME 61 9501
TEST_TIME 62 9524
TEST_TIME 63 9540
TEST_TIME 64 9572
TEST_TIME 65 9678
TEST_TIME 66 9708
TEST_TIME 67 9652
TEST_TIME 68 9552
TEST_TIME 69 9470
TEST_TIME 70 9452
TEST_TIME 71 9358
TEST_TIME 72 9278
TEST_TIME 73 9240
TEST_TIME 74 9191
TEST_TIME 75 9214
TEST_TIME 76 9157
TEST_TIME 77 9117
TEST_TIME 78 9080
TEST_TIME 79 9143
TEST_TIME 80 9111
TEST_TIME 81 9140
TEST_TIME 82 9142
TEST_TIME 83 9138
TEST_TIME 84 9090
TEST_TIME 85 9177
TEST_TIME 86 9163
TEST_TIME 87 9220
TEST_TIME 88 9208
TEST_TIME 89 9273
TEST_TIME 90 9266
TEST_TIME 91 9269
TEST_TIME 92 9238
TEST_TIME 93 9180
TEST_TIME 94 9125
TEST_TIME 95 9147
TEST_TIME 96 9076
TEST_TIME 97 9075
TEST_TIME 98 9049
TEST_TIME 99 9007
TEST_TIME 100 9053
TEST_TIME 101 9049
TEST_TIME 102 9036
TEST_TIME 103 9077
TEST_TIME 104 9219
TEST_TIME 105 9204
TEST_TIME 106 9202
TEST_TIME 107 9185
TEST_TIME 108 9265
TEST_TIME 109 9277
TEST_TIME 110 9254
TEST_TIME 111 9218
TEST_TIME 112 9206
TEST_TIME 113 9184
TEST_TIME 114 9153
TEST_TIME 115 9115
TEST_TIME 116 9088
TEST_TIME 117 9064
TEST_TIME 118 9114
TEST_TIME 119 9164
TEST_TIME 120 9129

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-08-2021 22:38:59 to 06-08-2021 22:39:02.

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 1531148 1531148 0 0.00 0 19462 6 0 0
doc_list_biud 1531124 1531124 0 0.00 0 85487 26 0 0
sp_add_invoice_to_stock 5204 5066 138 2.65 40 188610 19941 0 138
sp_cancel_adding_invoice 5472 5472 0 0.00 5 52244 866 0 0
sp_cancel_client_order 1824 1824 0 0.00 4 16268 397 0 0
sp_cancel_customer_reserve 536867 536867 0 0.00 1 36485 57 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 3 3581 74 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 1254 60 0 0
sp_cancel_supplier_invoice 6079 6064 15 0.24 1 85497 890 0 15
sp_cancel_supplier_order 376 376 0 0.00 8 901426 104581 0 0
sp_cancel_write_off 9850 9850 0 0.00 1 11899 110 0 0
sp_client_order 21058 21058 0 0.00 4 22764 354 0 0
sp_customer_reserve 472186 472186 0 0.00 0 40781 196 0 0
sp_fill_shopping_cart 32130 32130 0 0.00 1 11563 44 0 0
sp_fill_shopping_cart_clo_res 472186 472186 0 0.00 0 13195 14 0 0
sp_get_clo_for_invoice 5065 5065 0 0.00 0 26015 314 0 0
sp_kill_cost_storno 16379 16379 0 0.00 0 19456 55 0 0
sp_kill_qstorno_ret_qs2qd 1090024 1090024 0 0.00 0 85481 32 0 0
sp_kill_qty_storno 575591 575591 0 0.00 0 85485 69 0 0
sp_lock_selected_doc 20842 20842 0 0.00 0 8771 6 0 0
sp_make_cost_storno 28006 28006 0 0.00 0 17212 53 0 0
sp_multiply_rows_for_pdistr 27998 27998 0 0.00 0 6008 27 0 0
sp_multiply_rows_for_qdistr 21058 21058 0 0.00 0 13496 164 0 0
sp_pay_from_customer 7082 7082 0 0.00 4 14961 109 0 0
sp_pay_to_supplier 5771 5771 0 0.00 5 16337 128 0 0
sp_payment_common 12844 12844 0 0.00 1 9001 26 0 0
sp_qd_handle_on_cancel_clo 1824 1824 0 0.00 0 16241 353 0 0
sp_qd_handle_on_invoice_upd_sts 10530 10530 0 0.00 0 48226 337 0 0
sp_qd_handle_on_reserve_upd_sts 10080 10080 0 0.00 0 10565 56 0 0
sp_reserve_write_off 10088 10088 0 0.00 7 12885 176 0 0
sp_supplier_invoice 5518 5518 0 0.00 7 81174 3852 0 0
sp_supplier_order 5555 5555 0 0.00 5 19317 1330 0 0
srv_aggregate_perf_data 89 89 0 0.00 2635 433630 23913 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1450 134363 46382 0 0
srv_find_qd_qs_mism 1046407 1046407 0 0.00 0 18918 6 0 0
srv_make_invnt_saldo 530 530 0 0.00 42 66531 1378 0 0
srv_make_money_saldo 873 873 0 0.00 1 14766 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 91564 395654 241779 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5066 5066 0 0.00 0 12783 199 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 2381 108 0 0
t$perf-norm:sp_cancel_client_order 1824 1824 0 0.00 0 6085 20 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 945 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 2788 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 584 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1144 15 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 25354 1741 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 1273 9 0 0
t$perf-norm:sp_client_order 21058 21058 0 0.00 0 11902 19 0 0
t$perf-norm:sp_customer_reserve 1071 1071 0 0.00 0 967 7 0 0
t$perf-norm:sp_pay_from_customer 7082 7082 0 0.00 0 5669 10 0 0
t$perf-norm:sp_pay_to_supplier 5771 5771 0 0.00 0 8899 15 0 0
t$perf-norm:sp_reserve_write_off 10088 10088 0 0.00 0 6297 11 0 0
t$perf-norm:sp_supplier_invoice 5518 5518 0 0.00 0 5415 21 0 0
t$perf-norm:sp_supplier_order 5555 5555 0 0.00 0 4953 20 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 351 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 256 6 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 1334 24 0 0
t$perf-norm:srv_make_money_saldo 873 873 0 0.00 0 1761 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 7 0 0
v_add_invoice_to_stock 10388 10112 276 2.65 0 6763 18 0 276
v_all_customers 37994 37994 0 0.00 0 21 1 0 0
v_all_suppliers 30258 30258 0 0.00 0 21 1 0 0
v_all_wares 42116 42116 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 1472 7 0 0
v_cancel_client_order 3648 3648 0 0.00 0 4239 8 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 3515 9 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 5778 10 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 954 5 0 0
v_cancel_supplier_invoice 780 750 30 3.84 0 1239 16 0 30
v_cancel_supplier_order 752 752 0 0.00 0 1596 9 0 0
v_cancel_write_off 1962 1962 0 0.00 0 851 12 0 0
v_max_id_clo_ord 5555 5555 0 0.00 0 5154 3 0 0
v_max_id_clo_res 1071 1071 0 0.00 0 1192 5 0 0
v_max_id_ord_sup 5517 5517 0 0.00 0 5968 7 0 0
v_max_non_paid_invoice 1712 1712 0 0.00 0 640 3 0 0
v_max_non_paid_realizn 3783 3783 0 0.00 0 1426 4 0 0
v_min_id_clo_ord 5555 5555 0 0.00 0 3804 9 0 0
v_min_id_clo_res 1071 1071 0 0.00 0 6426 9 0 0
v_min_id_ord_sup 5517 5517 0 0.00 0 10961 10 0 0
v_min_non_paid_invoice 5769 5769 0 0.00 0 3916 16 0 0
v_min_non_paid_realizn 7073 7073 0 0.00 0 8677 14 0 0
v_our_firm 10704 10704 0 0.00 0 22 1 0 0
v_reserve_write_off 20160 20160 0 0.00 0 8844 7 0 0
x_lock_depdocs_on_canc_invoice 11161 11161 0 0.00 0 24082 141 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 7240 388 0 0
x_make_qstorno_1000_1200 5555 5555 0 0.00 2 19306 1241 0 0
x_make_qstorno_1200_2000 5518 5518 0 0.00 4 75475 3692 0 0
x_make_qstorno_2100_3300 472186 472186 0 0.00 0 40762 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-08-2021 22:39:03 to 06-08-2021 22:39:07.

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:47 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:48 25802 8192000000 10382396416 10702917632 1429794288 14.859 100 87 174 1 1463863008 6071904 27996816
20:10:28 22207 8192000000 10447890112 10777583616 1502622272 15.499 100 89 178 0 1539655552 8464592 28568688
20:11:57 17862 8192000000 10486707008 10826076160 1540976560 15.832 100 82 164 0 1576208880 9103184 26129136
20:13:20 15324 8192000000 10570709712 10930057216 1628626960 16.583 100 73 146 1 1660588128 9960624 22000544
20:14:42 10215 8192000000 10662865648 11032567808 1720942064 17.360 100 74 148 0 1754068784 12229648 20897072
20:15:55 3775 8192000000 10710617136 11090317312 1772740112 17.790 100 81 160 2 1809371184 14201584 22429488
20:17:01 2636 8192000000 10756909584 11139198976 1820521600 18.182 100 92 182 2 1859755664 13968048 25266016
20:18:06 1761 8192000000 10803326352 11189383168 1856658512 18.476 100 86 172 2 1894358384 13722880 23976992
20:19:10 30494 8192000000 10795384288 11205349376 1868278608 18.570 100 56 112 2 1894291632 10789360 15223664
20:20:42 6016 8192000000 10798488752 11209814016 1877062752 18.641 100 40 78 2 1893646048 5098256 11485040
20:21:49 5788 8192000000 10796692496 11224014848 1885598464 18.710 100 30 60 1 1900455824 6404320 8453040
20:22:59 7907 8192000000 10802550992 11240022016 1901016448 18.834 100 22 44 1 1914118912 6554160 6548304
20:24:12 28058 8192000000 10815789376 11251331072 1906598688 18.879 100 26 52 3 1920457984 5839024 8020272
20:25:42 8808 8192000000 10830462688 11258036224 1911810992 18.921 100 25 50 1 1924845712 5559168 7475552
20:26:52 10692 8192000000 10820172544 11254775808 1907583216 18.887 100 12 24 0 1916512208 5317296 3611696
20:28:03 39212 8192000000 10731548336 11164397568 1805129376 18.056 98 29 57 1 1820700640 5377504 10193760
20:29:58 4634 8192000000 10750961312 11164676096 1829483360 18.255 100 35 70 1 1845178112 5518064 10176688
20:31:03 7224 8192000000 10737298880 11154706432 1824460880 18.214 100 34 69 0 1840505168 5620816 10423472
20:32:11 8950 8192000000 10724726496 11139194880 1759104528 17.677 97 20 40 1 1770313120 5338224 5870368
20:33:24 37451 8192000000 10751642240 11181334528 1844728640 18.379 100 34 68 2 1859818304 5348768 9740896
20:35:04 5655 8192000000 10792153936 11219509248 1869418784 18.580 100 28 56 3 1883983984 6107632 8457568
20:36:10 10653 8192000000 10792238784 11224850432 1878315264 18.652 100 13 26 2 1887613488 5667360 3630864
20:37:24 21666 8192000000 10827138640 11249156096 1907594704 18.887 100 19 38 1 1918922640 5406144 5921792
20:38:49 102897 8192000000 10877313104 11300474880 1938979296 19.139 100 58 116 0 1964741856 9008096 16754464
20:42:00 15246 8192000000 10908460944 11339218944 1966448080 19.357 100 57 114 1 2002261760 20208240 15605440
20:43:19 13117 8192000000 10909823040 11350302720 1979434096 19.460 100 51 102 1 2009785536 15509920 14841520
20:44:39 22414 8192000000 10927870944 11359285248 1973694128 19.415 99 55 108 3 2004512608 15471984 15346496
20:46:07 19851 8192000000 10917373984 11358031872 1971322336 19.396 99 46 93 1 2002166096 17209488 13634272
20:47:31 12194 8192000000 10948551360 11374505984 1979191472 19.458 99 63 124 0 2016834400 19287616 18355312
20:48:46 8887 8192000000 10940765488 11376795648 1972555936 19.406 99 55 110 2 2016065392 25661808 17847648
20:49:59 2582 8192000000 10938571424 11360071680 1967834368 19.368 99 79 157 3 2013155456 21687856 23633232
20:51:08 5075 8192000000 10943289296 11366727680 1980292528 19.467 100 73 144 2 2029364624 27145552 21926544
20:52:17 2993 8192000000 10954592848 11373060096 1983009104 19.489 100 89 178 2 2040022176 30521472 26491600
20:53:22 2303 8192000000 10973142384 11391311872 1993010640 19.568 100 92 185 2 2053214608 33188560 27015408
20:54:28 2663 8192000000 10962319232 11377025024 1962575776 19.327 99 88 176 0 2030363072 41050848 26736448
20:55:34 3445 8192000000 10955178864 11371499520 1972967440 19.409 100 78 155 0 2038868880 42509088 23392352
20:56:48 1537 8192000000 10934151568 11343679488 1948470256 19.214 100 88 176 1 2017743296 41240944 28032096
20:57:55 1449 8192000000 10952722144 11363745792 1967088224 19.362 100 79 154 3 2025731424 35599248 23043952
20:59:01 3505 8192000000 10950470384 11368910848 1961835616 19.321 100 71 140 0 2031040848 45404160 23801072
21:00:08 9702 8192000000 10916011936 11358183424 1942771152 19.169 100 38 76 1 2004166320 47798112 13597056
21:01:24 22264 8192000000 10917505808 11361366016 1951856544 19.241 100 25 50 1 2002790944 42443616 8490784
21:02:55 18327 8192000000 10926035392 11366952960 1948951216 19.218 100 35 70 1 2001021040 40380640 11689184
21:04:19 11617 8192000000 10921444912 11373047808 1948637632 19.216 100 34 68 0 2016732592 56999184 11095776
21:05:39 32074 8192000000 10899749840 11355365376 1934644032 19.104 99 24 49 2 1974252272 31717056 7891184
21:07:52 63993 8192000000 10890856704 11343482880 1917767312 18.969 98 13 26 0 1936412128 15014256 3630560
21:09:57 25170 8192000000 10917889040 11358560256 1962752736 19.328 100 40 80 3 1990416592 16720240 10943616
21:11:25 100996 8192000000 10901188048 11352141824 1961026640 19.314 100 19 39 0 1975992528 9772208 5193680
21:14:13 65840 8192000000 10900893536 11362926592 1966928400 19.361 100 22 44 3 1980615648 7594208 6093040
21:16:20 55968 8192000000 10905861504 11361398784 1966350496 19.356 99 25 50 1 1982814496 9047856 7416144
21:18:19 65931 8192000000 10898667184 11364569088 1956239584 19.276 99 8 17 2 1970515600 11471904 2804112
21:20:42 69067 8192000000 10896407504 11363069952 1968004672 19.370 100 13 26 1 1980825024 8889472 3930880
21:22:52 47264 8192000000 10894187104 11368411136 1973444624 19.413 100 12 24 1 1989404928 11906368 4053936
21:24:52 20553 8192000000 10903930240 11355648000 1939681184 19.144 98 44 86 2 1960267216 7577424 13008608
21:26:16 32087 8192000000 10896013280 11365343232 1944285872 19.181 98 23 45 1 1963371152 12145392 6939888
21:27:59 84742 8192000000 10903454128 11386245120 1977309824 19.443 100 12 24 0 1994277328 13480784 3486720
21:30:24 93580 8192000000 10898955232 11370913792 1922605408 19.008 97 16 30 0 1937071328 10184624 4281296
21:33:09 26320 8192000000 10908477968 11368271872 1936848352 19.122 98 32 64 0 1957230176 11618432 8763392
21:34:38 49542 8192000000 10901061776 11382992896 1956458224 19.278 99 18 36 1 1984275264 22030432 5786608
21:36:28 56914 8192000000 10922350112 11373858816 1926220272 19.037 97 24 48 2 1953011760 19644336 7147152
21:38:30 106191 8192000000 10884391232 11362099200 1817202048 18.155 92 11 22 3 1831311360 11288672 2820640
21:41:17 53131 8192000000 10866452928 11332792320 1831793680 18.274 94 7 14 0 1852735872 19324032 1618160
21:43:11 134362 8192000000 10846846160 11317071872 1823208832 18.204 94 13 26 0 1847262912 20300176 3753904
21:46:54 54571 8192000000 10807349312 11276767232 1882227472 18.683 98 20 40 1 1896337024 8527424 5582128
21:48:55 59645 8192000000 10856617280 11319644160 1899716784 18.824 98 12 23 0 1912086320 9343392 3026144
21:52:27 54281 8192000000 10834834448 11310583808 1875479104 18.629 97 26 51 3 1902596912 19475968 7641840
21:54:32 87548 8192000000 10880198720 11356155904 1943698016 19.176 100 21 42 0 1975231456 26170112 5363328
21:56:59 47758 8192000000 10859129040 11330715648 1875792640 18.631 96 15 30 2 1888364592 8560672 4011280
21:58:48 67245 8192000000 10883439872 11343421440 1911472672 18.918 98 14 27 1 1922475232 7046400 3956160
22:00:57 46855 8192000000 10920005088 11362308096 1958025072 19.290 100 25 50 2 1977529712 12501792 7002848
22:02:47 23122 8192000000 10914224304 11376082944 1977835200 19.448 100 41 82 2 1998885616 9528080 11522336
22:04:10 80945 8192000000 10898713232 11379568640 1939216576 19.141 98 14 28 4 1958873136 15885040 3771520
22:06:31 92869 8192000000 10911307408 11371442176 1921990464 19.003 97 14 28 2 1935169408 9939200 3239744
22:09:05 38364 8192000000 10916107440 11380195328 1979956000 19.464 100 31 62 0 2002895536 14389184 8550352
22:10:45 74631 8192000000 10918041248 11372331008 1977314288 19.443 99 29 56 2 1992212848 6733072 8165488
22:13:00 63485 8192000000 10898414192 11382693888 1954811168 19.265 98 5 10 0 1966203648 9913280 1479200
22:15:04 20329 8192000000 10913042704 11389480960 1983740864 19.494 100 18 35 2 2002354176 13998528 4614784
22:16:26 88149 8192000000 10911957856 11397001216 1953633600 19.255 98 13 26 1 1972033424 14605696 3794128
22:19:06 51109 8192000000 10944767536 11414564864 1973125456 19.410 98 24 48 0 1995887088 14523504 8238128
22:20:58 116355 8192000000 10910916432 11394625536 1894368448 18.781 95 9 18 0 1920039168 22921904 2748816
22:24:04 62347 8192000000 10905805264 11359293440 1902241632 18.844 96 17 34 1 1920668512 13653632 4773248
22:26:11 57899 8192000000 10878373632 11348873216 1916241552 18.957 98 23 46 1 1934903296 11627872 7033872
22:28:12 15572 8192000000 10915441872 11351543808 1951417248 19.238 100 59 117 3 1978851584 11288992 16145344
22:29:37 85739 8192000000 10857342128 11328544768 1890238416 18.748 97 14 28 1 1902142976 7232496 4672064
22:32:07 98147 8192000000 10861371136 11334615040 1856524928 18.475 95 14 28 2 1872471072 11650592 4295552
22:35:14 44924 8192000000 10849982336 11325378560 1896135104 18.795 99 20 40 0 1918660096 16599376 5925616
22:37:07 49026 8192000000 9204212960 9467764736 362540560 4.237 20 4 7 0 368593616 4946144 1106912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-08-2021 22:39:09 to 06-08-2021 22:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-08-2021 22:39:25 to 06-08-2021 22:39:27.

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          242946
MON$OLDEST_ACTIVE               242947
MON$OLDEST_SNAPSHOT             242947
MON$NEXT_TRANSACTION            242947
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                       2038624
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:296: 
:297: mscs-test-161.budzdorov.ru    Fri Aug  6 22:37:58 2021
:298:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:299: 
--- end of diff output ---

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

06.08.21 22:39:28. 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-06 22:39:30 No severe PSQL-related problems occured
06.08.21 22:39:31. End of report.