Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.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 16012816 kB
MemAvailable 30785512 kB
Buffers 20 kB
Cached 15607496 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-16 04:37:08
DTS_END                         2021-09-16 04:37:08.4030
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(68:17)
IP                              ::1/52938
TRN_ID                          223518
ATT_ID                          659
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: 8736.95 33041 1048434
customer order: creation 154.72 456 18566
customer order: refuse 14.28 371 1713
order to supplier: creation 43.40 1600 5208
order to supplier: removal 3.39 131405 407
invoice (draft): creation 42.88 3918 5146
invoice (draft): removal 50.68 1197 6081
invoice accept: apply 39.02 20496 4682
invoice accept: cancel 45.31 1017 5437
customer reserve: creation 3624.83 199 434980
customer reserve: removal 4442.14 76 533057
realization accept: apply 78.67 207 9440
realization accept: cancel 78.45 138 9414
payment from customer: creation 54.74 121 6569
payment from customer: removal 5.26 113 631
payment to supplier: creation 45.64 143 5477
payment to supplier: removal 3.38 81 406
service: total inventory turnovers 3.54 4359 425
service: total monetary turnovers 5.68 636 682
service: aggregate perf. data 0.90 16634 108
service: refresh index statistics 0.04 477644 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-09-2021 04:38:01 to 16-09-2021 04:38:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8172
TEST_TIME 2 6969
TEST_TIME 3 6537
TEST_TIME 4 6139
TEST_TIME 5 5974
TEST_TIME 6 5649
TEST_TIME 7 5435
TEST_TIME 8 5464
TEST_TIME 9 5421
TEST_TIME 10 5601
TEST_TIME 11 5625
TEST_TIME 12 6025
TEST_TIME 13 6374
TEST_TIME 14 6540
TEST_TIME 15 6741
TEST_TIME 16 6718
TEST_TIME 17 7046
TEST_TIME 18 7058
TEST_TIME 19 7246
TEST_TIME 20 7203
TEST_TIME 21 7227
TEST_TIME 22 7518
TEST_TIME 23 7854
TEST_TIME 24 8013
TEST_TIME 25 8128
TEST_TIME 26 7908
TEST_TIME 27 8049
TEST_TIME 28 8401
TEST_TIME 29 9867
TEST_TIME 30 10434
TEST_TIME 31 10249
TEST_TIME 32 10132
TEST_TIME 33 10187
TEST_TIME 34 9985
TEST_TIME 35 9892
TEST_TIME 36 9773
TEST_TIME 37 9709
TEST_TIME 38 9538
TEST_TIME 39 9556
TEST_TIME 40 9507
TEST_TIME 41 9348
TEST_TIME 42 9203
TEST_TIME 43 9267
TEST_TIME 44 9210
TEST_TIME 45 9163
TEST_TIME 46 9096
TEST_TIME 47 9076
TEST_TIME 48 9044
TEST_TIME 49 8941
TEST_TIME 50 8874
TEST_TIME 51 8808
TEST_TIME 52 8926
TEST_TIME 53 8944
TEST_TIME 54 8949
TEST_TIME 55 8968
TEST_TIME 56 8920
TEST_TIME 57 8903
TEST_TIME 58 8846
TEST_TIME 59 8971
TEST_TIME 60 9036
TEST_TIME 61 9118
TEST_TIME 62 9120
TEST_TIME 63 9196
TEST_TIME 64 9187
TEST_TIME 65 9151
TEST_TIME 66 9124
TEST_TIME 67 9051
TEST_TIME 68 9070
TEST_TIME 69 9001
TEST_TIME 70 9052
TEST_TIME 71 8976
TEST_TIME 72 8924
TEST_TIME 73 8854
TEST_TIME 74 8799
TEST_TIME 75 8824
TEST_TIME 76 8877
TEST_TIME 77 8942
TEST_TIME 78 8977
TEST_TIME 79 8966
TEST_TIME 80 8954
TEST_TIME 81 8923
TEST_TIME 82 8946
TEST_TIME 83 9076
TEST_TIME 84 9124
TEST_TIME 85 9086
TEST_TIME 86 9098
TEST_TIME 87 9116
TEST_TIME 88 9162
TEST_TIME 89 9076
TEST_TIME 90 9009
TEST_TIME 91 8960
TEST_TIME 92 8922
TEST_TIME 93 8892
TEST_TIME 94 8910
TEST_TIME 95 8863
TEST_TIME 96 8851
TEST_TIME 97 8811
TEST_TIME 98 8788
TEST_TIME 99 8782
TEST_TIME 100 8838
TEST_TIME 101 8853
TEST_TIME 102 8850
TEST_TIME 103 8865
TEST_TIME 104 8821
TEST_TIME 105 8914
TEST_TIME 106 8947
TEST_TIME 107 8940
TEST_TIME 108 8980
TEST_TIME 109 8987
TEST_TIME 110 8910
TEST_TIME 111 8874
TEST_TIME 112 8864
TEST_TIME 113 8827
TEST_TIME 114 8810
TEST_TIME 115 8788
TEST_TIME 116 8786
TEST_TIME 117 8796
TEST_TIME 118 8818
TEST_TIME 119 8810
TEST_TIME 120 8741

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-09-2021 04:38:04 to 16-09-2021 04:38:08.

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 1449222 1449222 0 0.00 0 21057 7 0 0
doc_list_biud 1449219 1449219 0 0.00 0 38621 36 0 0
sp_add_invoice_to_stock 4803 4682 121 2.51 19 191325 20497 0 121
sp_cancel_adding_invoice 5437 5437 0 0.00 4 97543 1017 0 0
sp_cancel_client_order 1713 1713 0 0.00 5 10416 372 0 0
sp_cancel_customer_reserve 533057 533057 0 0.00 1 24205 77 0 0
sp_cancel_pay_from_customer 631 631 0 0.00 3 10106 114 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 2030 81 0 0
sp_cancel_supplier_invoice 6092 6081 11 0.18 1 38641 1198 0 11
sp_cancel_supplier_order 407 407 0 0.00 14 1379228 131406 0 0
sp_cancel_write_off 9414 9414 0 0.00 1 12943 138 0 0
sp_client_order 18566 18566 0 0.00 4 45871 457 0 0
sp_customer_reserve 434980 434980 0 0.00 0 105918 200 0 0
sp_fill_shopping_cart 28916 28916 0 0.00 1 20107 53 0 0
sp_fill_shopping_cart_clo_res 434972 434972 0 0.00 0 18129 17 0 0
sp_get_clo_for_invoice 4674 4674 0 0.00 0 31571 409 0 0
sp_kill_cost_storno 15888 15888 0 0.00 0 12940 74 0 0
sp_kill_qstorno_ret_qs2qd 1082016 1082016 0 0.00 0 38619 42 0 0
sp_kill_qty_storno 570222 570222 0 0.00 0 38621 91 0 0
sp_lock_selected_doc 19630 19630 0 0.00 0 6435 5 0 0
sp_make_cost_storno 26160 26160 0 0.00 0 14715 63 0 0
sp_multiply_rows_for_pdistr 26158 26158 0 0.00 0 10062 36 0 0
sp_multiply_rows_for_qdistr 18566 18566 0 0.00 0 45861 241 0 0
sp_pay_from_customer 6569 6569 0 0.00 4 13805 121 0 0
sp_pay_to_supplier 5477 5477 0 0.00 5 12120 143 0 0
sp_payment_common 12045 12045 0 0.00 1 13796 23 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 0 10407 346 0 0
sp_qd_handle_on_invoice_upd_sts 10110 10110 0 0.00 0 23972 428 0 0
sp_qd_handle_on_reserve_upd_sts 9440 9440 0 0.00 0 23684 61 0 0
sp_reserve_write_off 9440 9440 0 0.00 7 23716 208 0 0
sp_supplier_invoice 5146 5146 0 0.00 8 122636 3919 0 0
sp_supplier_order 5208 5208 0 0.00 5 30965 1600 0 0
srv_aggregate_perf_data 108 108 0 0.00 2717 150368 16635 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2577 126313 44917 0 0
srv_find_qd_qs_mism 1003185 1003185 0 0.00 0 21057 6 0 0
srv_make_invnt_saldo 425 425 0 0.00 61 108988 4359 0 0
srv_make_money_saldo 682 682 0 0.00 1 17529 637 0 0
srv_recalc_idx_stat 5 5 0 0.00 375722 610894 477645 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4682 4682 0 0.00 0 15515 234 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 14542 147 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 5914 19 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 3772 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 631 631 0 0.00 0 8127 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 1420 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 347 347 0 0.00 0 3268 27 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 26388 1831 0 0
t$perf-norm:sp_cancel_write_off 938 938 0 0.00 0 5485 14 0 0
t$perf-norm:sp_client_order 18566 18566 0 0.00 0 9028 23 0 0
t$perf-norm:sp_customer_reserve 927 927 0 0.00 0 4000 8 0 0
t$perf-norm:sp_pay_from_customer 6569 6569 0 0.00 0 7150 13 0 0
t$perf-norm:sp_pay_to_supplier 5477 5477 0 0.00 0 15519 16 0 0
t$perf-norm:sp_reserve_write_off 9440 9440 0 0.00 0 8775 16 0 0
t$perf-norm:sp_supplier_invoice 5146 5146 0 0.00 0 15238 30 0 0
t$perf-norm:sp_supplier_order 5208 5208 0 0.00 0 9325 24 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 906 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 127 3 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 3418 19 0 0
t$perf-norm:srv_make_money_saldo 682 682 0 0.00 0 426 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 23 9 0 0
v_add_invoice_to_stock 9586 9344 242 2.52 0 5616 21 0 242
v_all_customers 34074 34074 0 0.00 0 51 1 0 0
v_all_suppliers 28434 28434 0 0.00 0 29 1 0 0
v_all_wares 37128 37128 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 1224 10 0 0
v_cancel_client_order 3426 3426 0 0.00 0 2046 4 0 0
v_cancel_customer_prepayment 1262 1262 0 0.00 0 2770 8 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 1954 6 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1969 9 0 0
v_cancel_supplier_invoice 716 694 22 3.07 0 724 13 0 22
v_cancel_supplier_order 814 814 0 0.00 0 2964 11 0 0
v_cancel_write_off 1876 1876 0 0.00 0 3150 17 0 0
v_max_id_clo_ord 5206 5206 0 0.00 0 115 1 0 0
v_max_id_clo_res 927 927 0 0.00 0 4600 21 0 0
v_max_id_ord_sup 5146 5146 0 0.00 0 7616 6 0 0
v_max_non_paid_invoice 1611 1611 0 0.00 0 386 3 0 0
v_max_non_paid_realizn 3458 3458 0 0.00 0 2991 4 0 0
v_min_id_clo_ord 5206 5206 0 0.00 0 2631 7 0 0
v_min_id_clo_res 927 927 0 0.00 0 8324 16 0 0
v_min_id_ord_sup 5146 5146 0 0.00 0 10886 14 0 0
v_min_non_paid_invoice 5476 5476 0 0.00 0 5753 13 0 0
v_min_non_paid_realizn 6569 6569 0 0.00 0 7090 18 0 0
v_our_firm 9276 9276 0 0.00 0 21 1 0 0
v_reserve_write_off 18878 18878 0 0.00 0 5382 7 0 0
x_lock_depdocs_on_canc_invoice 11171 11171 0 0.00 0 23498 137 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 14934 427 0 0
x_make_qstorno_1000_1200 5208 5208 0 0.00 2 30957 1507 0 0
x_make_qstorno_1200_2000 5146 5146 0 0.00 4 122586 3714 0 0
x_make_qstorno_2100_3300 434980 434980 0 0.00 0 105916 180 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 16-09-2021 04:38:08 to 16-09-2021 04:38:13.

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:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:13 48498 8192000000 9736356048 10036023296 803243040 8.929 100 88 175 1 832426384 7949872 21233472
02:10:08 39070 8192000000 9783412640 10093858816 855842512 9.459 100 95 190 0 891978128 13841424 22294192
02:11:56 18040 8192000000 9841793648 10166132736 895458336 9.853 100 85 170 1 941876032 27440720 18976976
02:13:21 13554 8192000000 9902705968 10241572864 937418832 10.268 100 84 168 1 1010450704 54992992 18038880
02:14:38 6483 8192000000 9944209664 10295300096 971093600 10.597 100 76 152 0 1057819168 70943392 15782176
02:15:46 3455 8192000000 9963179584 10319491072 998596848 10.865 100 78 156 0 1074996304 60420368 15979088
02:16:52 2878 8192000000 9997695248 10361061376 1028253360 11.152 100 83 166 0 1113801088 68152048 17395680
02:17:57 2757 8192000000 10010084016 10378936320 1050897088 11.369 100 87 170 3 1124297312 56078352 17321872
02:19:02 1445 8192000000 10032960192 10404552704 1061996224 11.476 100 83 165 0 1141506096 62924544 16585328
02:20:05 1868 8192000000 10043151024 10425851904 1068022752 11.533 100 81 162 0 1155882304 71358736 16500816
02:21:11 7075 8192000000 9998269616 10391142400 1074854656 11.598 100 57 114 1 1118269280 30696576 12718048
02:22:23 34944 8192000000 9988667792 10382901248 1077578960 11.624 100 34 68 1 1099942240 14964592 7398688
02:24:00 3886 8192000000 9997627136 10389585920 1079528816 11.643 100 56 111 0 1111745792 20159984 12056992
02:25:06 5634 8192000000 9996324672 10398531584 1084817328 11.693 100 35 70 1 1114083024 21531552 7734144
02:26:14 8815 8192000000 9993007728 10396782592 1074361136 11.594 99 27 54 0 1109903744 29699616 5842992
02:27:25 25708 8192000000 9982499184 10390220800 1074299584 11.593 99 27 54 0 1104632480 24358880 5974016
02:28:53 5132 8192000000 9978976368 10374520832 1071518320 11.567 100 43 86 0 1096057424 14068912 10470192
02:30:01 4552 8192000000 9963420288 10346860544 1034761168 11.214 98 34 68 0 1058693168 16139952 7792048
02:31:06 48627 8192000000 9909734336 10283814912 946552880 10.357 91 38 75 2 972498000 17433280 8511840
02:33:00 5609 8192000000 9926777728 10310115328 960669072 10.496 94 39 78 1 1001860928 33113408 8078448
02:34:07 6556 8192000000 9953412832 10343542784 1016931680 11.042 98 52 103 2 1046031504 17763232 11336592
02:35:17 9117 8192000000 9970363584 10364432384 1055284576 11.411 100 42 87 0 1081878800 15976272 10617952
02:36:30 26807 8192000000 9968658032 10369093632 1061768208 11.473 100 24 48 1 1089694912 22095872 5830832
02:38:06 15685 8192000000 10021718992 10419884032 1075219040 11.602 100 58 116 0 1128528176 41491744 11817392
02:39:27 34297 8192000000 10040321504 10445414400 1086873216 11.713 100 52 104 0 1149525664 50807024 11845424
02:41:09 47412 8192000000 10067301200 10481971200 1108302560 11.916 100 35 70 0 1173992432 57295664 8394208
02:43:07 40962 8192000000 10125014080 10539737088 1121230192 12.039 100 53 106 0 1228770720 95908704 11631824
02:44:52 25662 8192000000 10164354912 10581291008 1130436928 12.125 100 48 96 1 1285688096 144621920 10629248
02:46:23 9730 8192000000 10191480416 10597576704 1148381648 12.294 100 59 118 0 1293772048 132732608 12657792
02:47:37 9358 8192000000 10254665328 10671407104 1163226256 12.433 100 67 134 0 1356096592 178704400 14165936
02:49:07 4828 8192000000 10289207968 10708824064 1177390928 12.566 100 69 135 1 1388204368 196057728 14755712
02:50:18 6029 8192000000 10310972480 10728816640 1178829904 12.579 100 76 149 1 1408647360 213947264 15870192
02:51:29 3678 8192000000 10349586528 10754187264 1170161120 12.498 100 81 160 0 1445250096 257463232 17625744
02:52:38 4079 8192000000 10379913600 10805481472 1189019200 12.674 100 82 158 0 1478562800 272213376 17330224
02:53:45 3239 8192000000 10403251056 10809311232 1191138496 12.694 100 92 184 1 1498897552 285916736 21842320
02:54:56 3322 8192000000 10420451120 10836107264 1188471840 12.669 100 72 142 1 1519993872 314549664 16972368
02:56:10 6439 8192000000 10420558160 10851233792 1185375136 12.640 100 69 138 1 1523158960 320840160 16943664
02:57:28 2575 8192000000 10444933936 10864320512 1178202128 12.573 100 88 175 1 1546115744 345904720 22008896
02:58:35 3142 8192000000 10487706176 10895204352 1181614496 12.605 100 91 181 1 1583682416 380252816 21815104
02:59:45 5943 8192000000 10512742496 10950176768 1189471440 12.678 100 53 106 0 1596625104 393575584 13578080
03:01:11 11061 8192000000 10453079840 10882514944 1173400352 12.529 100 46 91 1 1535357152 350605024 11351776
03:02:25 8926 8192000000 10455838272 10901577728 1165234368 12.452 100 44 85 2 1553744512 378033312 10476832
03:03:37 16809 8192000000 10520476416 10958061568 1138232160 12.199 100 36 71 1 1595686464 447671072 9783232
03:05:10 17226 8192000000 10526154512 10993876992 1113613952 11.967 99 41 82 3 1618545776 493489280 11442544
03:06:29 28244 8192000000 10185036800 10739548160 1123589296 12.061 100 25 50 0 1447024928 316636096 6799536
03:08:00 21588 8192000000 10054811920 10483052544 1098308176 11.822 98 28 56 1 1145412672 40751680 6352816
03:09:31 36790 8192000000 10090783472 10550906880 1121669632 12.043 100 17 34 0 1197707824 72413088 3625104
03:11:09 126312 8192000000 10078141632 10526834688 1105495264 11.890 98 11 22 1 1160222960 52600080 2127616
03:14:58 43605 8192000000 10108692208 10541129728 1128924688 12.111 100 29 58 0 1193752160 57934416 6893056
03:16:54 123794 8192000000 10102579440 10554671104 1113685008 11.967 98 27 54 0 1213039264 92994000 6360256
03:20:15 62425 8192000000 10113971424 10582671360 1111128784 11.943 97 17 34 1 1186694704 72157280 3408640
03:22:19 13909 8192000000 10068796080 10529873920 1136081408 12.179 100 31 61 0 1176103328 34004192 6017728
03:23:38 41090 8192000000 10098499680 10538213376 1143739776 12.251 100 38 77 0 1190659536 39146480 7773280
03:25:21 91932 8192000000 10092133808 10539585536 1127209152 12.095 98 12 23 0 1170173616 40954448 2010016
03:27:53 37551 8192000000 10107755200 10598809600 1151502544 12.324 100 11 22 1 1219933440 66054816 2376080
03:29:31 53929 8192000000 10065443488 10567180288 1121866384 12.045 98 15 28 1 1187608896 63108128 2634384
03:31:31 58255 8192000000 10100737280 10579914752 1127472144 12.098 98 16 32 0 1180830848 49762416 3596288
03:33:35 39061 8192000000 10115862320 10551672832 1130231856 12.124 98 38 74 1 1207784224 69921296 7631072
03:35:21 70624 8192000000 10133085120 10642247680 1140203792 12.217 99 17 34 0 1257465200 113018512 4242896
03:37:32 39529 8192000000 10107581664 10560552960 1141187312 12.227 100 31 62 1 1201111296 52324272 7599712
03:39:16 121276 8192000000 10081080256 10547687424 1084075440 11.686 94 10 20 0 1136710640 50857696 1777504
03:42:24 85302 8192000000 10082030576 10557566976 1143772640 12.251 100 21 42 0 1182724336 34067072 4884624
03:44:57 59556 8192000000 10106521120 10551738368 1138547200 12.202 99 27 54 1 1227830464 84257216 5026048
03:47:04 38545 8192000000 10072893184 10555121664 1147404880 12.285 100 30 59 2 1220368416 66342176 6621360
03:49:01 44300 8192000000 10139932976 10582900736 1151010352 12.319 99 22 44 1 1215147616 59516576 4620688
03:50:47 91110 8192000000 10188460288 10666881024 1150711536 12.316 99 14 28 0 1275081200 120818304 3551360
03:54:26 75211 8192000000 10066718944 10541408256 1095777168 11.798 95 17 34 0 1172237200 73099600 3360432
03:56:46 50461 8192000000 10083098288 10523238400 1128121952 12.104 99 22 44 1 1165432112 33158368 4151792
03:58:50 64587 8192000000 10147896160 10623680512 1108067536 11.914 97 20 40 0 1247609616 135403760 4138320
04:00:58 39206 8192000000 10080802272 10566557696 1101823552 11.855 98 24 46 2 1153902144 47533184 4545408
04:02:47 27305 8192000000 10100825744 10516983808 1118301568 12.011 99 65 130 0 1176507120 44442496 13763056
04:04:18 24162 8192000000 10104665600 10548305920 1111196192 11.944 98 42 84 0 1168119360 48282208 8640960
04:05:46 74177 8192000000 10071087072 10551107584 1130438608 12.125 100 22 44 0 1180758736 45938192 4381936
04:08:23 65135 8192000000 10098930960 10558894080 1130226432 12.123 99 22 46 2 1180164400 45464240 4473728
04:10:29 69317 8192000000 10100985376 10572316672 1110060624 11.933 98 9 18 0 1175584384 63473696 2050064
04:12:43 47845 8192000000 10053179648 10504798208 1095027488 11.790 97 14 28 1 1132375280 34345216 3002576
04:14:41 47031 8192000000 10083114176 10531024896 1128566416 12.108 100 23 46 0 1188314464 55237840 4510208
04:16:29 75145 8192000000 10089251792 10550906880 1088608336 11.729 96 25 50 1 1153137936 59007120 5522480
04:18:46 45935 8192000000 10050162480 10486751232 1086806624 11.712 97 17 34 0 1135784384 45305424 3672336
04:20:35 77598 8192000000 10145273616 10588024832 1119033968 12.018 99 27 54 1 1205204784 80599744 5571072
04:22:53 73638 8192000000 10085574288 10515116032 1110688656 11.939 98 14 28 0 1153073632 39528848 2856128
04:25:07 66357 8192000000 10070551952 10537312256 1089499920 11.738 96 12 24 0 1151628512 60046992 2081600
04:27:20 80610 8192000000 10074141792 10511974400 1119107216 12.019 99 36 72 1 1166299360 39923024 7269120
04:29:43 70962 8192000000 10043393520 10495033344 1079721424 11.645 95 6 12 1 1106606528 25845376 1039728
04:31:56 79142 8192000000 10067518912 10532212736 1073628592 11.587 94 18 37 1 1133396240 55071920 4695728
04:34:20 78889 8192000000 10080854128 10549329920 1125096608 12.075 99 31 62 0 1161367808 29325872 6945328
04:36:41 32759 8192000000 9471809904 9803001856 588341328 6.700 52 31 61 0 620862080 26175840 6344912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-09-2021 04:38:14 to 16-09-2021 04:38:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-09-2021 04:38:30 to 16-09-2021 04:38:32.

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          222782
MON$OLDEST_ACTIVE               224073
MON$OLDEST_SNAPSHOT             224073
MON$NEXT_TRANSACTION            224073
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                       2038560
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2592 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2592 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2592 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2592 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Sep 16 04:37:15 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Sep 16 04:37:17 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Thu Sep 16 04:37:21 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

16.09.21 04:38:33. 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-16 04:38:35 No severe PSQL-related problems occured
16.09.21 04:38:37. End of report.