Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.09.2021 04:37
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 15677764 kB
MemAvailable 30759160 kB
Buffers 20 kB
Cached 15914592 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-17 04:37:07
DTS_END                         2021-09-17 04:37:07.6950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40306
TRN_ID                          238394
ATT_ID                          698
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: 9222.14 20674 1106657
customer order: creation 171.33 441 20560
customer order: refuse 14.69 346 1763
order to supplier: creation 46.58 1384 5589
order to supplier: removal 3.34 124113 401
invoice (draft): creation 45.16 3507 5419
invoice (draft): removal 51.86 982 6223
invoice accept: apply 41.68 18220 5002
invoice accept: cancel 47.43 857 5692
customer reserve: creation 3858.14 178 462977
customer reserve: removal 4645.42 70 557450
realization accept: apply 83.64 184 10037
realization accept: cancel 85.28 129 10234
payment from customer: creation 58.68 122 7041
payment from customer: removal 5.78 95 693
payment to supplier: creation 49.27 134 5912
payment to supplier: removal 3.24 92 389
service: total inventory turnovers 3.75 3911 450
service: total monetary turnovers 5.91 662 709
service: aggregate perf. data 0.93 18540 112
service: refresh index statistics 0.03 239512 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-09-2021 04:37:51 to 17-09-2021 04:37:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6329
TEST_TIME 2 4104
TEST_TIME 3 4736
TEST_TIME 4 5841
TEST_TIME 5 5920
TEST_TIME 6 5916
TEST_TIME 7 5936
TEST_TIME 8 5940
TEST_TIME 9 6373
TEST_TIME 10 6618
TEST_TIME 11 6581
TEST_TIME 12 6768
TEST_TIME 13 6817
TEST_TIME 14 6861
TEST_TIME 15 6996
TEST_TIME 16 7062
TEST_TIME 17 7276
TEST_TIME 18 7576
TEST_TIME 19 7824
TEST_TIME 20 7697
TEST_TIME 21 8260
TEST_TIME 22 8372
TEST_TIME 23 8396
TEST_TIME 24 8797
TEST_TIME 25 8862
TEST_TIME 26 8963
TEST_TIME 27 9135
TEST_TIME 28 9526
TEST_TIME 29 11347
TEST_TIME 30 11453
TEST_TIME 31 11351
TEST_TIME 32 11644
TEST_TIME 33 11598
TEST_TIME 34 11418
TEST_TIME 35 11119
TEST_TIME 36 10852
TEST_TIME 37 10738
TEST_TIME 38 10561
TEST_TIME 39 10545
TEST_TIME 40 10405
TEST_TIME 41 10401
TEST_TIME 42 10249
TEST_TIME 43 10229
TEST_TIME 44 10178
TEST_TIME 45 9993
TEST_TIME 46 9880
TEST_TIME 47 9931
TEST_TIME 48 9843
TEST_TIME 49 9733
TEST_TIME 50 9636
TEST_TIME 51 9519
TEST_TIME 52 9429
TEST_TIME 53 9338
TEST_TIME 54 9305
TEST_TIME 55 9428
TEST_TIME 56 9416
TEST_TIME 57 9416
TEST_TIME 58 9403
TEST_TIME 59 9473
TEST_TIME 60 9453
TEST_TIME 61 9485
TEST_TIME 62 9696
TEST_TIME 63 9661
TEST_TIME 64 9607
TEST_TIME 65 9711
TEST_TIME 66 9607
TEST_TIME 67 9518
TEST_TIME 68 9426
TEST_TIME 69 9430
TEST_TIME 70 9359
TEST_TIME 71 9283
TEST_TIME 72 9239
TEST_TIME 73 9260
TEST_TIME 74 9301
TEST_TIME 75 9290
TEST_TIME 76 9254
TEST_TIME 77 9241
TEST_TIME 78 9280
TEST_TIME 79 9278
TEST_TIME 80 9367
TEST_TIME 81 9367
TEST_TIME 82 9337
TEST_TIME 83 9455
TEST_TIME 84 9419
TEST_TIME 85 9318
TEST_TIME 86 9334
TEST_TIME 87 9297
TEST_TIME 88 9269
TEST_TIME 89 9261
TEST_TIME 90 9253
TEST_TIME 91 9269
TEST_TIME 92 9293
TEST_TIME 93 9330
TEST_TIME 94 9359
TEST_TIME 95 9343
TEST_TIME 96 9326
TEST_TIME 97 9357
TEST_TIME 98 9310
TEST_TIME 99 9338
TEST_TIME 100 9374
TEST_TIME 101 9409
TEST_TIME 102 9437
TEST_TIME 103 9427
TEST_TIME 104 9375
TEST_TIME 105 9346
TEST_TIME 106 9299
TEST_TIME 107 9291
TEST_TIME 108 9270
TEST_TIME 109 9237
TEST_TIME 110 9203
TEST_TIME 111 9195
TEST_TIME 112 9248
TEST_TIME 113 9213
TEST_TIME 114 9223
TEST_TIME 115 9201
TEST_TIME 116 9212
TEST_TIME 117 9298
TEST_TIME 118 9290
TEST_TIME 119 9295
TEST_TIME 120 9224

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-09-2021 04:37:55 to 17-09-2021 04:37:58.

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 1535843 1535843 0 0.00 0 14076 7 0 0
doc_list_biud 1535840 1535840 0 0.00 0 37336 32 0 0
sp_add_invoice_to_stock 5104 5002 102 1.99 50 184725 18221 0 102
sp_cancel_adding_invoice 5692 5692 0 0.00 5 71379 858 0 0
sp_cancel_client_order 1763 1763 0 0.00 5 19367 346 0 0
sp_cancel_customer_reserve 557450 557450 0 0.00 1 34465 70 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 7448 95 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 4552 93 0 0
sp_cancel_supplier_invoice 6229 6223 6 0.09 1 37343 983 0 6
sp_cancel_supplier_order 401 401 0 0.00 5 1345531 124114 0 0
sp_cancel_write_off 10234 10234 0 0.00 1 21045 130 0 0
sp_client_order 20560 20560 0 0.00 4 36103 441 0 0
sp_customer_reserve 462977 462977 0 0.00 0 49195 179 0 0
sp_fill_shopping_cart 31554 31554 0 0.00 1 13166 44 0 0
sp_fill_shopping_cart_clo_res 462958 462958 0 0.00 0 23793 16 0 0
sp_get_clo_for_invoice 4983 4983 0 0.00 0 28607 392 0 0
sp_kill_cost_storno 17008 17008 0 0.00 0 8338 64 0 0
sp_kill_qstorno_ret_qs2qd 1131758 1131758 0 0.00 0 37334 38 0 0
sp_kill_qty_storno 596783 596783 0 0.00 0 37336 81 0 0
sp_lock_selected_doc 20739 20739 0 0.00 0 8705 4 0 0
sp_make_cost_storno 27973 27973 0 0.00 0 8505 47 0 0
sp_multiply_rows_for_pdistr 27973 27973 0 0.00 0 10788 33 0 0
sp_multiply_rows_for_qdistr 20560 20560 0 0.00 0 20640 220 0 0
sp_pay_from_customer 7041 7041 0 0.00 4 29157 122 0 0
sp_pay_to_supplier 5912 5912 0 0.00 5 25903 135 0 0
sp_payment_common 12953 12953 0 0.00 1 5440 17 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 19357 313 0 0
sp_qd_handle_on_invoice_upd_sts 10675 10675 0 0.00 0 27893 370 0 0
sp_qd_handle_on_reserve_upd_sts 10037 10037 0 0.00 0 20040 63 0 0
sp_reserve_write_off 10037 10037 0 0.00 6 20299 184 0 0
sp_supplier_invoice 5419 5419 0 0.00 7 75104 3508 0 0
sp_supplier_order 5589 5589 0 0.00 6 57630 1385 0 0
srv_aggregate_perf_data 112 112 0 0.00 2662 236243 18540 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2096 134439 41985 0 0
srv_find_qd_qs_mism 1059304 1059304 0 0.00 0 13070 6 0 0
srv_make_invnt_saldo 450 450 0 0.00 45 95450 3911 0 0
srv_make_money_saldo 709 709 0 0.00 0 12673 662 0 0
srv_recalc_idx_stat 4 4 0 0.00 220652 284813 239512 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 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-norm:sp_add_invoice_to_stock 5002 5002 0 0.00 0 34093 194 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 1 20284 203 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 18489 23 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 1530 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 11899 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 3731 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 1797 19 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 18370 1632 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 1758 6 0 0
t$perf-norm:sp_client_order 20560 20560 0 0.00 0 26224 20 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 2004 14 0 0
t$perf-norm:sp_pay_from_customer 7041 7041 0 0.00 0 10255 11 0 0
t$perf-norm:sp_pay_to_supplier 5912 5912 0 0.00 0 17477 10 0 0
t$perf-norm:sp_reserve_write_off 10037 10037 0 0.00 0 6380 11 0 0
t$perf-norm:sp_supplier_invoice 5419 5419 0 0.00 0 12346 21 0 0
t$perf-norm:sp_supplier_order 5589 5589 0 0.00 0 5547 15 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 3419 34 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 493 8 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 521 6 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 8097 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 393 116 0 0
v_add_invoice_to_stock 10170 9966 204 2.00 0 27458 20 0 204
v_all_customers 37730 37730 0 0.00 0 17 1 0 0
v_all_suppliers 30850 30850 0 0.00 0 15 1 0 0
v_all_wares 41120 41120 0 0.00 0 84 1 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 1833 10 0 0
v_cancel_client_order 3526 3526 0 0.00 0 1929 4 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 3418 12 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 2381 9 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 1675 6 0 0
v_cancel_supplier_invoice 736 724 12 1.63 0 239 11 0 12
v_cancel_supplier_order 802 802 0 0.00 0 396 4 0 0
v_cancel_write_off 1990 1990 0 0.00 0 2357 15 0 0
v_max_id_clo_ord 5586 5586 0 0.00 0 371 1 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 2078 12 0 0
v_max_id_ord_sup 5408 5408 0 0.00 0 3639 6 0 0
v_max_non_paid_invoice 1481 1481 0 0.00 0 216 2 0 0
v_max_non_paid_realizn 3587 3587 0 0.00 0 454 2 0 0
v_min_id_clo_ord 5586 5586 0 0.00 0 2291 6 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 10467 21 0 0
v_min_id_ord_sup 5408 5408 0 0.00 0 5847 8 0 0
v_min_non_paid_invoice 5912 5912 0 0.00 0 5436 12 0 0
v_min_non_paid_realizn 7041 7041 0 0.00 0 4233 10 0 0
v_our_firm 10298 10298 0 0.00 0 21 1 0 0
v_reserve_write_off 20074 20074 0 0.00 0 13383 7 0 0
x_lock_depdocs_on_canc_invoice 11553 11553 0 0.00 0 9855 111 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 19369 377 0 0
x_make_qstorno_1000_1200 5589 5589 0 0.00 2 57615 1301 0 0
x_make_qstorno_1200_2000 5419 5419 0 0.00 3 75097 3334 0 0
x_make_qstorno_2100_3300 462977 462977 0 0.00 0 49185 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-09-2021 04:37:59 to 17-09-2021 04:38:03.

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
02:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:11 20841 8192000000 9718887584 10014511104 799268992 8.889 100 86 172 0 827773200 7388944 21115264
02:09:39 24637 8192000000 9765868800 10070175744 836019648 9.260 100 94 188 0 872228928 13989792 22219488
02:11:06 10347 8192000000 9793189056 10106818560 857235312 9.473 100 86 172 0 898459568 21126960 20097296
02:12:21 10548 8192000000 9840830000 10171166720 904920880 9.947 100 78 156 0 953817376 31820432 17076064
02:13:36 7046 8192000000 9918369264 10259259392 949244576 10.384 100 82 163 0 1022965280 56878096 16842608
02:14:46 3190 8192000000 9958115472 10309279744 985231024 10.735 100 77 152 0 1063605808 63266560 15108224
02:15:50 2379 8192000000 9984831728 10344296448 1013008976 11.004 100 86 169 2 1098749792 68629120 17111696
02:16:54 2475 8192000000 10000444848 10368569344 1035083456 11.217 100 76 150 1 1112450976 62328768 15038752
02:17:58 1244 8192000000 10032175168 10397458432 1055202528 11.411 100 95 190 0 1142863472 68323488 19337456
02:18:59 1658 8192000000 10001323536 10379755520 1065160192 11.506 100 85 169 1 1116632032 33160704 18311136
02:20:04 8056 8192000000 9985873312 10382823424 1069404912 11.546 100 28 56 0 1094747648 19706608 5636128
02:21:14 6557 8192000000 9988829024 10386636800 1082572400 11.672 100 21 40 1 1107669936 21000032 4097504
02:22:21 5356 8192000000 10022555088 10419191808 1090317312 11.746 100 40 81 0 1132118496 33521312 8279872
02:23:27 22233 8192000000 10003712256 10409455616 1095809760 11.798 100 34 68 0 1121560576 18783184 6967632
02:24:50 3822 8192000000 10011276192 10414161920 1098584304 11.824 100 45 92 3 1129471184 20728336 10158544
02:25:57 6027 8192000000 10015127296 10404622336 1095998032 11.800 100 31 62 2 1128231824 24929008 7304784
02:27:04 9234 8192000000 10006043040 10409652224 1069041184 11.543 99 40 77 2 1106734240 29795616 7897440
02:28:52 6514 8192000000 9986691520 10373828608 1027787152 11.147 97 29 57 0 1069007568 35587568 5632848
02:29:59 4524 8192000000 9945201984 10326237184 1003897600 10.916 97 34 66 1 1038273680 26913472 7462608
02:31:05 4212 8192000000 9926020720 10308972544 1007008528 10.946 99 38 73 4 1039951824 23471376 9471920
02:32:11 42297 8192000000 9945644832 10328322048 1006619360 10.943 98 34 67 1 1034024496 20342096 7063040
02:33:54 6211 8192000000 9973402720 10366459904 1026291824 11.133 99 40 80 1 1062382864 27058912 9032128
02:35:01 7457 8192000000 9971636528 10364379136 1050956800 11.370 100 30 59 0 1070038160 12467888 6613472
02:36:10 4078 8192000000 9977099040 10378072064 1061089344 11.467 100 44 88 2 1094870096 24125472 9655280
02:37:16 50487 8192000000 10006844464 10405941248 1079034688 11.638 100 39 78 1 1107480752 19720928 8725136
02:39:23 17802 8192000000 10051122304 10450108416 1099741552 11.835 100 42 83 1 1148900976 41005952 8153472
02:40:44 20657 8192000000 10089674352 10509348864 1115296016 11.983 100 41 82 0 1196880208 72550048 9034144
02:42:08 5055 8192000000 10138167152 10539327488 1123204208 12.057 100 63 124 0 1247181264 111079376 12897680
02:43:18 6797 8192000000 10142397648 10548965376 1137032704 12.188 100 54 105 0 1239567488 91980224 10554560
02:44:29 18946 8192000000 10176935616 10597933056 1146575904 12.277 100 44 87 0 1286858144 130758576 9523664
02:45:54 11277 8192000000 10186677360 10605535232 1127728288 12.100 99 52 104 1 1290661456 151104480 11828688
02:47:07 3651 8192000000 10194579184 10603851776 1148879616 12.299 100 56 112 1 1300203808 139148176 12176016
02:48:15 5628 8192000000 10220950128 10629861376 1151331744 12.322 100 69 136 2 1322127552 155675360 15120448
02:49:25 6107 8192000000 10290759808 10701742080 1152323520 12.331 100 78 154 1 1390613024 221780032 16509472
02:50:39 6359 8192000000 10277901568 10685415424 1151896880 12.327 100 61 120 1 1374383712 209031104 13455728
02:51:57 6102 8192000000 10293591760 10690744320 1138580928 12.202 100 75 148 2 1389131440 233144048 17406464
02:53:09 3333 8192000000 10290644000 10695344128 1121405696 12.040 100 81 162 0 1388276576 247529536 19341344
02:54:22 3526 8192000000 10358127008 10757447680 1141403888 12.229 100 80 160 1 1452976480 293422064 18150528
02:55:27 6113 8192000000 10357205632 10764300288 1147439920 12.285 100 61 121 0 1454910752 292953952 14516880
02:56:41 4366 8192000000 10396427264 10807472128 1140058208 12.216 99 58 117 0 1487834688 333008160 14768320
02:57:54 4054 8192000000 10459422608 10879606784 1156264896 12.368 100 71 142 3 1551638992 378245472 17128624
02:59:00 6115 8192000000 10435633408 10850578432 1151410272 12.323 100 47 92 0 1533944752 370596768 11937712
03:00:13 2095 8192000000 10469135984 10879750144 1151953472 12.328 100 56 112 2 1562733264 396857056 13922736
03:01:35 11163 8192000000 10454055904 10890964992 1165012848 12.450 100 39 76 1 1536265056 361694816 9557392
03:02:50 20355 8192000000 10511233056 10941075456 1150202128 12.311 100 29 58 0 1597366320 439073712 8090480
03:04:13 28606 8192000000 10538691344 10992713728 1133974496 12.159 100 27 54 0 1624044416 482371248 7698672
03:05:57 25280 8192000000 10374164736 10917122048 1116684128 11.996 100 22 44 1 1529736768 406792112 6260528
03:07:27 29817 8192000000 10056091216 10471448576 1107041376 11.904 98 32 62 3 1127058784 13482480 6534928
03:08:57 51261 8192000000 10149299904 10640760832 1145153456 12.264 100 12 24 0 1267075312 118947888 2973968
03:10:52 109113 8192000000 10062569920 10519920640 1154646016 12.353 100 30 59 1 1182740032 21559728 6534288
03:13:53 13584 8192000000 10090099872 10526502912 1158104992 12.386 100 56 116 1 1185658688 15171696 12382000
03:15:08 56275 8192000000 10123768384 10575110144 1151167936 12.320 99 34 66 1 1210325808 51881872 7276000
03:17:08 41388 8192000000 10129981104 10595307520 1161215136 12.415 100 45 88 3 1233384352 61630608 10538608
03:18:58 33728 8192000000 10115176032 10586923008 1133872112 12.158 98 8 16 1 1200520624 64811184 1837328
03:20:32 48480 8192000000 10096343024 10615492608 1124242336 12.067 98 8 16 0 1202729232 76542560 1944336
03:22:23 65144 8192000000 10162303776 10619473920 1145025968 12.263 100 13 26 1 1246776320 98641056 3109296
03:25:47 65834 8192000000 10090371344 10549248000 1136654480 12.184 99 22 43 1 1173364848 31607456 5102912
03:28:09 77402 8192000000 10082468224 10567708672 1151888384 12.327 100 4 8 0 1177779600 24930688 960528
03:30:27 66010 8192000000 10157450752 10609782784 1149587696 12.306 99 18 36 1 1231017664 77471632 3958336
03:32:34 24259 8192000000 10074190096 10516209664 1127740848 12.100 98 30 60 1 1157284448 23319248 6224352
03:33:58 35955 8192000000 10078328656 10527424512 1142737360 12.241 99 39 78 0 1191966816 40163008 9066448
03:35:37 55129 8192000000 10135761344 10592747520 1149077808 12.301 99 25 50 0 1217338288 62950720 5309760
03:37:41 54504 8192000000 10135931408 10597339136 1095658944 11.796 95 28 54 1 1199570992 97860752 6051296
03:39:38 47433 8192000000 10081338256 10598281216 1132349376 12.144 100 7 14 0 1210401120 76804736 1247008
03:41:26 29715 8192000000 10091470320 10517999616 1110534880 11.937 98 48 96 1 1144675344 24916832 9223632
03:43:01 93586 8192000000 10074905088 10512920576 1121297552 12.039 99 30 60 0 1169998368 42538800 6162016
03:46:06 38033 8192000000 10045894272 10533572608 1100399872 11.841 98 16 32 0 1144087312 40223616 3463824
03:47:45 49285 8192000000 10018948592 10473017344 1059858880 11.455 95 5 10 0 1082943328 22088016 996432
03:49:34 56705 8192000000 10069833568 10539741184 1105104944 11.886 99 9 18 1 1168563488 61358560 2099984
03:51:35 35423 8192000000 10040996688 10481094656 1092316768 11.765 98 13 26 0 1129465056 34640288 2508000
03:53:13 26744 8192000000 10052587520 10493624320 1092913760 11.770 98 29 58 2 1126034272 27359616 5760896
03:54:42 71054 8192000000 10076059488 10523865088 1068737440 11.540 96 18 36 1 1157448944 85026272 3685232
03:56:57 82491 8192000000 10051598512 10491518976 1058725648 11.444 95 22 44 0 1116130864 52944048 4461168
03:59:42 71880 8192000000 10083904912 10580381696 1110384384 11.936 99 16 32 0 1202649888 88090448 4175056
04:01:56 134439 8192000000 10094585696 10592784384 1126396000 12.087 100 34 66 2 1207443680 73224880 7822800
04:05:19 68869 8192000000 10104010784 10583785472 1122708656 12.053 99 13 26 2 1193797200 68117328 2971216
04:07:28 55491 8192000000 10103962784 10565369856 1140379856 12.219 100 15 30 0 1198494080 55194016 2920208
04:09:34 69459 8192000000 10119191872 10582519808 1132551904 12.145 99 16 32 1 1188303152 52573424 3177824
04:11:51 50962 8192000000 10101544912 10557853696 1137665920 12.194 99 24 47 2 1177280480 34660288 4954272
04:13:42 22617 8192000000 10089538080 10523992064 1124092912 12.066 98 39 76 2 1147463808 15296864 8074032
04:15:05 43601 8192000000 10100431216 10536136704 1137328960 12.190 99 27 54 2 1177053712 34439104 5285648
04:16:51 121901 8192000000 10097862928 10585530368 1090839408 11.751 95 21 42 0 1152509056 57874992 3794656
04:19:53 69514 8192000000 10068908128 10510970880 1082471024 11.671 95 25 49 1 1106941056 18861472 5608560
04:22:11 83748 8192000000 10060716336 10502209536 1126982608 12.093 99 22 43 1 1178063008 46714976 4365424
04:24:39 40887 8192000000 10054918384 10530484224 1114713776 11.977 98 16 32 0 1173856944 55813248 3329920
04:26:20 34624 8192000000 10082912496 10544529408 1117393280 12.002 99 9 18 0 1149540960 30193968 1953712
04:27:55 82147 8192000000 10116918448 10616569856 1119997536 12.027 99 12 24 1 1230011152 107200896 2812720
04:30:49 84871 8192000000 10130990752 10598617088 1115447168 11.984 98 17 34 0 1175683632 56653456 3583008
04:33:18 61080 8192000000 10141771216 10590961664 1101550016 11.852 97 14 27 1 1206744336 102203216 2991104
04:35:27 42534 8192000000 10071998784 10509910016 1097321776 11.812 97 33 66 0 1136779088 32547824 6909488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-09-2021 04:38:05 to 17-09-2021 04:38:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 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:01, from 17-09-2021 04:38:22 to 17-09-2021 04:38:23.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          235519
MON$OLDEST_ACTIVE               238604
MON$OLDEST_SNAPSHOT             238604
MON$NEXT_TRANSACTION            238604
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-30 07:30:08.6030 GMT
MON$PAGES                       2071072
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             809
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2602 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2602 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2602 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2602 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Fri Sep 17 04:37:11 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
--- end of diff output ---

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

17.09.21 04:38:25. There are 3 logs in /var/tmp/logs-oltp40 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-17 04:38:27 No severe PSQL-related problems occured
17.09.21 04:38:28. End of report.