Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.09.2021 07:48
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 16076080 kB
MemAvailable 30844140 kB
Buffers 0 kB
Cached 15657704 kB
SwapCached 696 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-20 07:46:58
DTS_END                         2021-09-20 07:46:58.8920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33248
TRN_ID                          242154
ATT_ID                          718
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: 8949.32 22712 1073918
customer order: creation 175.31 412 21037
customer order: refuse 15.27 331 1832
order to supplier: creation 47.53 1462 5703
order to supplier: removal 3.13 109592 376
invoice (draft): creation 47.73 3732 5728
invoice (draft): removal 48.08 937 5770
invoice accept: apply 43.41 18884 5209
invoice accept: cancel 43.58 915 5230
customer reserve: creation 3947.49 191 473699
customer reserve: removal 4279.70 64 513564
realization accept: apply 85.21 187 10225
realization accept: cancel 79.76 116 9571
payment from customer: creation 61.88 123 7426
payment from customer: removal 5.83 90 699
payment to supplier: creation 50.66 132 6079
payment to supplier: removal 3.48 95 418
service: total inventory turnovers 3.84 3380 461
service: total monetary turnovers 6.43 476 772
service: aggregate perf. data 0.96 15233 115
service: refresh index statistics 0.03 297893 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-09-2021 07:48:58 to 20-09-2021 07:49:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1870
TEST_TIME 2 3215
TEST_TIME 3 2910
TEST_TIME 4 3225
TEST_TIME 5 4146
TEST_TIME 6 4520
TEST_TIME 7 4684
TEST_TIME 8 5199
TEST_TIME 9 5577
TEST_TIME 10 5751
TEST_TIME 11 6126
TEST_TIME 12 6307
TEST_TIME 13 6358
TEST_TIME 14 6653
TEST_TIME 15 6807
TEST_TIME 16 6998
TEST_TIME 17 7158
TEST_TIME 18 7727
TEST_TIME 19 8098
TEST_TIME 20 8485
TEST_TIME 21 8645
TEST_TIME 22 9193
TEST_TIME 23 9835
TEST_TIME 24 9833
TEST_TIME 25 9801
TEST_TIME 26 9873
TEST_TIME 27 10778
TEST_TIME 28 10734
TEST_TIME 29 10972
TEST_TIME 30 10809
TEST_TIME 31 10808
TEST_TIME 32 10758
TEST_TIME 33 10609
TEST_TIME 34 10640
TEST_TIME 35 10348
TEST_TIME 36 10188
TEST_TIME 37 10107
TEST_TIME 38 10051
TEST_TIME 39 10066
TEST_TIME 40 10047
TEST_TIME 41 10055
TEST_TIME 42 9942
TEST_TIME 43 9850
TEST_TIME 44 9764
TEST_TIME 45 9609
TEST_TIME 46 9622
TEST_TIME 47 9671
TEST_TIME 48 9572
TEST_TIME 49 9647
TEST_TIME 50 9617
TEST_TIME 51 9754
TEST_TIME 52 9652
TEST_TIME 53 9632
TEST_TIME 54 9653
TEST_TIME 55 9663
TEST_TIME 56 9718
TEST_TIME 57 9818
TEST_TIME 58 9744
TEST_TIME 59 9804
TEST_TIME 60 9840
TEST_TIME 61 9698
TEST_TIME 62 9617
TEST_TIME 63 9551
TEST_TIME 64 9531
TEST_TIME 65 9475
TEST_TIME 66 9368
TEST_TIME 67 9366
TEST_TIME 68 9330
TEST_TIME 69 9326
TEST_TIME 70 9365
TEST_TIME 71 9330
TEST_TIME 72 9377
TEST_TIME 73 9383
TEST_TIME 74 9364
TEST_TIME 75 9418
TEST_TIME 76 9431
TEST_TIME 77 9421
TEST_TIME 78 9457
TEST_TIME 79 9482
TEST_TIME 80 9441
TEST_TIME 81 9389
TEST_TIME 82 9319
TEST_TIME 83 9282
TEST_TIME 84 9231
TEST_TIME 85 9234
TEST_TIME 86 9182
TEST_TIME 87 9173
TEST_TIME 88 9190
TEST_TIME 89 9146
TEST_TIME 90 9163
TEST_TIME 91 9129
TEST_TIME 92 9132
TEST_TIME 93 9182
TEST_TIME 94 9195
TEST_TIME 95 9203
TEST_TIME 96 9227
TEST_TIME 97 9205
TEST_TIME 98 9235
TEST_TIME 99 9257
TEST_TIME 100 9170
TEST_TIME 101 9117
TEST_TIME 102 9107
TEST_TIME 103 9050
TEST_TIME 104 9009
TEST_TIME 105 9049
TEST_TIME 106 9018
TEST_TIME 107 9028
TEST_TIME 108 9001
TEST_TIME 109 9045
TEST_TIME 110 9045
TEST_TIME 111 9075
TEST_TIME 112 9094
TEST_TIME 113 9101
TEST_TIME 114 9069
TEST_TIME 115 9094
TEST_TIME 116 9104
TEST_TIME 117 9046
TEST_TIME 118 9014
TEST_TIME 119 8993
TEST_TIME 120 8949

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-09-2021 07:49:02 to 20-09-2021 07:49:05.

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 1513550 1513550 0 0.00 0 23639 7 0 0
doc_list_biud 1513548 1513548 0 0.00 0 40739 28 0 0
sp_add_invoice_to_stock 5295 5209 86 1.62 37 163989 18885 0 86
sp_cancel_adding_invoice 5230 5230 0 0.00 5 82753 915 0 0
sp_cancel_client_order 1832 1832 0 0.00 4 23143 332 0 0
sp_cancel_customer_reserve 513564 513564 0 0.00 1 40740 64 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 5733 91 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 9787 95 0 0
sp_cancel_supplier_invoice 5775 5770 5 0.08 1 18467 938 0 5
sp_cancel_supplier_order 376 376 0 0.00 7 1028666 109592 0 0
sp_cancel_write_off 9571 9571 0 0.00 1 11389 117 0 0
sp_client_order 21037 21037 0 0.00 4 39024 412 0 0
sp_customer_reserve 473699 473699 0 0.00 0 106940 191 0 0
sp_fill_shopping_cart 32449 32449 0 0.00 1 12931 47 0 0
sp_fill_shopping_cart_clo_res 473698 473698 0 0.00 0 17473 16 0 0
sp_get_clo_for_invoice 5208 5208 0 0.00 0 29561 371 0 0
sp_kill_cost_storno 15918 15918 0 0.00 0 9778 55 0 0
sp_kill_qstorno_ret_qs2qd 1042845 1042845 0 0.00 0 40736 35 0 0
sp_kill_qty_storno 551774 551774 0 0.00 0 40739 75 0 0
sp_lock_selected_doc 21341 21341 0 0.00 0 10965 9 0 0
sp_make_cost_storno 28937 28937 0 0.00 0 19370 59 0 0
sp_multiply_rows_for_pdistr 28937 28937 0 0.00 0 8170 32 0 0
sp_multiply_rows_for_qdistr 21037 21037 0 0.00 0 39012 214 0 0
sp_pay_from_customer 7426 7426 0 0.00 4 13642 124 0 0
sp_pay_to_supplier 6079 6079 0 0.00 4 17018 133 0 0
sp_payment_common 13505 13505 0 0.00 1 10777 24 0 0
sp_qd_handle_on_cancel_clo 1832 1832 0 0.00 0 23134 300 0 0
sp_qd_handle_on_invoice_upd_sts 10437 10437 0 0.00 0 28312 332 0 0
sp_qd_handle_on_reserve_upd_sts 10224 10224 0 0.00 0 20937 52 0 0
sp_reserve_write_off 10225 10225 0 0.00 7 20973 188 0 0
sp_supplier_invoice 5728 5728 0 0.00 6 99362 3732 0 0
sp_supplier_order 5703 5703 0 0.00 5 51063 1462 0 0
srv_aggregate_perf_data 115 115 0 0.00 2989 137711 15233 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1777 122594 47948 0 0
srv_find_qd_qs_mism 1025360 1025360 0 0.00 0 23627 7 0 0
srv_make_invnt_saldo 461 461 0 0.00 41 99741 3380 0 0
srv_make_money_saldo 772 772 0 0.00 1 16111 476 0 0
srv_recalc_idx_stat 4 4 0 0.00 226666 415105 297893 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5209 5209 0 0.00 0 14993 160 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 1 1906 71 0 0
t$perf-norm:sp_cancel_client_order 1832 1832 0 0.00 0 5451 12 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 2698 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 665 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 5975 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 6392 42 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 16936 1377 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 6923 15 0 0
t$perf-norm:sp_client_order 21037 21037 0 0.00 0 7541 16 0 0
t$perf-norm:sp_customer_reserve 1064 1064 0 0.00 0 3884 10 0 0
t$perf-norm:sp_pay_from_customer 7426 7426 0 0.00 0 6522 8 0 0
t$perf-norm:sp_pay_to_supplier 6079 6079 0 0.00 0 6481 12 0 0
t$perf-norm:sp_reserve_write_off 10225 10225 0 0.00 0 7002 15 0 0
t$perf-norm:sp_supplier_invoice 5728 5728 0 0.00 0 7581 23 0 0
t$perf-norm:sp_supplier_order 5703 5703 0 0.00 0 5942 16 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 88 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2779 47 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 5466 27 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 386 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10586 10414 172 1.62 0 3916 16 0 172
v_all_customers 38270 38270 0 0.00 0 68 1 0 0
v_all_suppliers 31440 31440 0 0.00 0 24 1 0 0
v_all_wares 42064 42064 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 2249 13 0 0
v_cancel_client_order 3664 3664 0 0.00 0 5094 6 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 5727 14 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 5428 11 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 1806 7 0 0
v_cancel_supplier_invoice 764 754 10 1.30 0 3953 24 0 10
v_cancel_supplier_order 752 752 0 0.00 0 1911 7 0 0
v_cancel_write_off 2082 2082 0 0.00 0 3527 16 0 0
v_max_id_clo_ord 5699 5699 0 0.00 0 560 1 0 0
v_max_id_clo_res 1064 1064 0 0.00 0 1430 13 0 0
v_max_id_ord_sup 5717 5717 0 0.00 0 5685 5 0 0
v_max_non_paid_invoice 1775 1775 0 0.00 0 710 3 0 0
v_max_non_paid_realizn 4012 4012 0 0.00 0 7477 6 0 0
v_min_id_clo_ord 5699 5699 0 0.00 0 1456 4 0 0
v_min_id_clo_res 1064 1064 0 0.00 0 8119 25 0 0
v_min_id_ord_sup 5717 5717 0 0.00 0 6438 10 0 0
v_min_non_paid_invoice 6079 6079 0 0.00 0 10059 17 0 0
v_min_non_paid_realizn 7426 7426 0 0.00 0 7128 17 0 0
v_our_firm 10622 10622 0 0.00 0 31 1 0 0
v_reserve_write_off 20448 20448 0 0.00 0 5589 7 0 0
x_lock_depdocs_on_canc_invoice 10623 10623 0 0.00 0 25367 114 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 14149 377 0 0
x_make_qstorno_1000_1200 5703 5703 0 0.00 2 51053 1389 0 0
x_make_qstorno_1200_2000 5728 5728 0 0.00 3 93144 3541 0 0
x_make_qstorno_2100_3300 473699 473699 0 0.00 0 106939 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-09-2021 07:49:06 to 20-09-2021 07:49:10.

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
05:17:02 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:03 28248 8192000000 9715308448 10013839360 792114464 8.816 100 86 172 0 821159792 8041872 21003456
05:19:38 24672 8192000000 9760316848 10066481152 832059760 9.220 100 86 172 0 864241024 11467120 20714144
05:21:14 9866 8192000000 9811419872 10132475904 881179584 9.711 100 79 158 0 919583776 20637520 17766672
05:22:27 10476 8192000000 9871608992 10204332032 925023136 10.146 100 80 160 2 972224464 29998480 17202848
05:23:44 6409 8192000000 9934129360 10286133248 973780352 10.624 100 74 148 0 1050497536 61262496 15454688
05:24:54 3951 8192000000 9973677184 10337636352 1006342304 10.940 100 79 159 2 1096502848 74204544 15956000
05:26:01 1941 8192000000 10003052656 10365616128 1033492000 11.202 100 80 159 1 1119959552 69830112 16637440
05:27:06 1750 8192000000 10039126352 10406854656 1061035936 11.466 100 95 192 1 1150597264 70458048 19103280
05:28:09 23567 8192000000 10005115552 10390102016 1078717664 11.635 100 55 109 4 1125467632 34018400 12731568
05:29:34 5104 8192000000 10017182752 10409918464 1087419072 11.718 100 41 82 1 1128362256 32529472 8413712
05:30:40 5310 8192000000 10020300960 10411573248 1095129920 11.791 100 44 87 3 1130537776 26327280 9080576
05:31:46 9509 8192000000 10009351984 10422493184 1100035040 11.838 100 29 56 1 1137086832 31293440 5758352
05:32:58 46353 8192000000 10018473696 10431913984 1103805952 11.874 100 56 113 1 1134697248 18157472 12733824
05:34:45 12386 8192000000 10016373504 10435510272 1109059488 11.924 100 34 68 1 1138568688 21972032 7537168
05:35:58 9334 8192000000 10023606480 10435051520 1100154304 11.839 99 37 71 2 1137779152 29735104 7889744
05:37:10 15645 8192000000 9974341072 10380316672 1033512096 11.202 95 26 52 1 1063204368 23759840 5932432
05:38:26 30518 8192000000 9975255904 10365517824 997320864 10.853 93 31 62 0 1022561168 19031984 6208320
05:40:00 9173 8192000000 9910995104 10295783424 973697488 10.623 96 30 59 1 1011576000 31036304 6842208
05:41:10 6989 8192000000 9939610240 10324807680 1009402384 10.970 99 39 77 3 1038829536 20787312 8639840
05:42:20 7123 8192000000 9963178160 10362281984 1035762336 11.224 99 40 78 1 1066325872 22641808 7921728
05:43:29 38705 8192000000 9976020096 10380853248 1053055360 11.390 100 22 44 0 1084512800 26963232 4494208
05:45:11 9843 8192000000 9996557648 10390978560 1063086928 11.486 100 33 66 0 1091774256 21636672 7050656
05:46:21 3693 8192000000 9991146320 10383921152 1072908048 11.580 100 43 86 2 1105202896 21206688 11088160
05:47:30 16352 8192000000 10020849584 10417127424 1096703360 11.806 100 49 96 0 1130848944 24773776 9371808
05:48:47 81111 8192000000 10087660800 10493267968 1112392176 11.955 100 48 95 1 1179566304 57303408 9870720
05:51:12 25739 8192000000 10123130304 10553171968 1132897248 12.149 100 55 109 3 1238241936 93178528 12166160
05:52:40 22319 8192000000 10151479936 10568073216 1147244896 12.284 100 39 78 1 1264084048 108276240 8562912
05:54:05 14472 8192000000 10240047888 10652545024 1157384896 12.379 100 57 113 1 1340606288 169935824 13285568
05:55:24 13103 8192000000 10248097504 10674458624 1168520976 12.483 100 69 134 1 1361498464 177886192 15091296
05:56:48 10290 8192000000 10299350912 10717663232 1181145440 12.601 100 64 128 0 1403365984 207771280 14449264
05:58:05 4691 8192000000 10287852576 10708508672 1190361040 12.687 100 52 103 1 1399554720 197973024 11220656
05:59:14 4238 8192000000 10326324816 10736902144 1186767408 12.653 100 86 170 1 1425013296 219701760 18544128
06:00:24 8670 8192000000 10343633040 10754404352 1185232560 12.639 100 78 152 0 1441130032 239230416 16667056
06:01:41 1968 8192000000 10359493712 10771353600 1188544576 12.670 100 72 144 0 1460209312 255385008 16279728
06:02:45 1776 8192000000 10359679184 10768338944 1153436688 12.342 100 87 175 1 1464803984 290436384 20930912
06:03:51 6458 8192000000 10378285648 10787889152 1135810624 12.176 99 65 126 1 1480132864 329175712 15146528
06:05:04 12229 8192000000 10376714112 10816229376 1143993968 12.253 99 46 92 0 1470894880 315320480 11580432
06:06:22 11997 8192000000 10365475760 10778484736 1111620496 11.948 98 46 91 0 1448488656 325135824 11732336
06:07:45 10851 8192000000 10358707776 10777219072 1129055712 12.112 99 45 89 3 1445684064 305300576 11327776
06:09:04 8946 8192000000 10321685216 10774007808 1146469136 12.276 100 36 71 2 1421115280 265866352 8779792
06:10:15 29266 8192000000 10278231152 10732523520 1120135680 12.028 98 17 34 0 1363012208 238343360 4533168
06:11:51 18293 8192000000 10268944352 10705154048 1117450752 12.003 99 22 44 2 1357164640 234028976 5684912
06:13:15 23788 8192000000 10120368528 10646609920 1106889040 11.903 99 12 24 0 1271104736 161301360 2914336
06:14:40 73347 8192000000 10057790144 10492096512 1106498560 11.899 99 14 28 0 1186267648 76620352 3148736
06:16:57 80901 8192000000 10090718592 10518106112 1109506464 11.928 98 18 36 0 1167861280 54975440 3379376
06:19:23 27522 8192000000 10124891968 10545815552 1144877280 12.261 100 50 101 0 1213029184 56935696 11216208
06:20:57 88807 8192000000 10088369008 10515357696 1130235872 12.124 99 38 76 0 1164299936 25573264 8490800
06:23:28 50295 8192000000 10087475856 10529873920 1134762016 12.166 99 20 40 0 1184017072 45421184 3833872
06:25:46 47653 8192000000 10090880496 10568245248 1113932080 11.970 97 21 42 0 1171418064 52567920 4918064
06:27:40 53374 8192000000 10094222320 10539761664 1148400816 12.294 100 21 41 1 1185582720 32993712 4188192
06:29:33 45932 8192000000 10098883824 10577215488 1134608752 12.165 98 13 25 0 1192976848 55521808 2846288
06:31:20 85911 8192000000 10158712160 10638168064 1153276720 12.340 100 17 34 0 1255392000 98440400 3674880
06:34:46 75436 8192000000 10116243680 10635149312 1151583872 12.324 99 8 16 0 1210248416 56981520 1683024
06:37:19 88728 8192000000 10090753376 10541117440 1146394944 12.276 99 17 34 0 1194430096 44481728 3553424
06:39:51 38571 8192000000 10138462240 10603253760 1147076592 12.282 99 47 93 1 1196801424 40224064 9500768
06:41:49 52732 8192000000 10126426080 10572226560 1125578160 12.080 97 12 24 1 1210601568 82348672 2674736
06:43:42 38463 8192000000 10065239136 10496466944 1093912656 11.780 96 24 48 0 1125637936 26845488 4879792
06:45:22 61110 8192000000 10116639888 10579558400 1072555552 11.576 95 31 62 1 1155343152 76847456 5940144
06:47:28 119898 8192000000 10052897952 10488467456 1102086560 11.857 98 30 60 0 1129763600 20418624 7258416
06:50:28 59999 8192000000 10066766832 10522390528 1114205232 11.972 98 15 30 0 1162057648 44094592 3757824
06:52:29 38652 8192000000 10046461984 10485174272 1050029008 11.361 93 16 32 3 1102564848 49228560 3307280
06:54:15 65061 8192000000 10086507216 10525450240 1050630128 11.367 94 15 30 0 1114487104 60784912 3072064
06:56:24 37826 8192000000 10078680512 10542899200 1108231504 11.916 99 22 44 2 1191843680 78588880 5023296
06:58:03 37054 8192000000 10086583024 10548850688 1105705152 11.892 99 23 46 1 1192106560 81468800 4932608
06:59:48 87050 8192000000 10083594496 10522451968 1097388080 11.813 98 26 52 1 1168734848 65993120 5353648
07:03:09 54959 8192000000 10085997200 10522476544 1109901520 11.931 99 34 67 2 1159955680 42694080 7360080
07:05:07 87011 8192000000 10049322560 10529181696 1091727952 11.759 97 29 58 1 1155091872 57257424 6106496
07:07:35 73386 8192000000 10075495840 10543185920 1117532000 12.004 99 20 40 0 1165147664 43413504 4202160
07:11:00 73417 8192000000 10056173520 10553733120 1114170256 11.972 99 10 20 0 1158350048 41953440 2226352
07:13:19 47823 8192000000 10070494000 10531110912 1119716400 12.024 99 17 34 0 1162387504 39064336 3606768
07:15:07 21295 8192000000 10063538320 10507411456 1131977408 12.140 100 25 49 0 1166851216 29692688 5181120
07:16:29 60170 8192000000 10079410016 10533203968 1143710976 12.250 100 25 51 0 1174661024 25884960 5065088
07:18:29 73022 8192000000 10143966064 10616610816 1135949248 12.177 98 24 48 1 1245817568 104915280 4953040
07:21:04 122594 8192000000 10090450288 10563485696 1081844592 11.665 94 24 47 1 1128060752 41762496 4453664
07:24:10 93319 8192000000 10066893248 10544992256 1099015584 11.828 96 32 63 0 1148169136 43187920 5965632
07:27:26 50280 8192000000 10076899184 10554994688 1128381104 12.106 99 27 54 0 1174495600 40784672 5329824
07:29:18 50673 8192000000 10094687584 10597543936 1146576768 12.277 100 8 16 1 1211969808 63627072 1765968
07:31:10 47645 8192000000 10068236704 10539925504 1068936720 11.542 94 23 43 1 1111451376 37691584 4823072
07:33:05 55933 8192000000 10091990880 10538246144 1094088272 11.782 97 13 26 1 1143077248 46221056 2767920
07:35:01 96184 8192000000 10101738864 10621997056 1088251872 11.726 97 28 56 0 1215864656 121082576 6530208
07:37:44 56370 8192000000 10058822992 10523045888 1095720240 11.797 99 15 30 0 1143131936 44519424 2892272
07:39:59 29830 8192000000 10077061520 10504925184 1119167376 12.019 100 48 96 0 1171764080 43200768 9395936
07:41:30 64362 8192000000 10043641040 10509180928 1077959552 11.628 97 22 44 1 1117528768 35296336 4272880
07:43:37 88746 8192000000 10068248592 10540355584 1106941856 11.903 99 41 82 0 1155357360 39658176 8757328
07:46:11 44783 8192000000 10025596464 10504003584 1080982720 11.657 97 6 12 1 1114710720 32236848 1491152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-09-2021 07:49:12 to 20-09-2021 07:49:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-09-2021 07:49:28 to 20-09-2021 07:49:30.

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          242370
MON$OLDEST_ACTIVE               242371
MON$OLDEST_SNAPSHOT             242371
MON$NEXT_TRANSACTION            242371
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                       2038176
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             818
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2606 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2606 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2606 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2606 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Mon Sep 20 07:47:29 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

20.09.21 07:49:31. 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-20 07:49:34 No severe PSQL-related problems occured
20.09.21 07:49:35. End of report.