Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.11.2021 04:39
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 15875036 kB
MemAvailable 30806432 kB
Buffers 20 kB
Cached 15812592 kB
SwapCached 2448 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-11-08 04:37:07
DTS_END                         2021-11-08 04:37:07.7320
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/60420
TRN_ID                          224040
ATT_ID                          592
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: 9012.98 28919 1081557
customer order: creation 155.44 415 18653
customer order: refuse 13.43 321 1612
order to supplier: creation 42.23 1369 5068
order to supplier: removal 3.42 113992 410
invoice (draft): creation 41.75 3758 5010
invoice (draft): removal 53.87 989 6464
invoice accept: apply 38.90 19880 4668
invoice accept: cancel 48.60 884 5832
customer reserve: creation 3556.93 198 426832
customer reserve: removal 4778.51 63 573421
realization accept: apply 75.08 187 9010
realization accept: cancel 86.28 110 10353
payment from customer: creation 54.78 130 6573
payment from customer: removal 5.35 93 642
payment to supplier: creation 44.70 150 5364
payment to supplier: removal 3.03 84 363
service: total inventory turnovers 3.82 3652 458
service: total monetary turnovers 6.01 493 721
service: aggregate perf. data 0.83 15343 99
service: refresh index statistics 0.03 416265 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-11-2021 04:39:56 to 08-11-2021 04:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3971
TEST_TIME 2 4652
TEST_TIME 3 4877
TEST_TIME 4 4936
TEST_TIME 5 5915
TEST_TIME 6 6065
TEST_TIME 7 5815
TEST_TIME 8 6730
TEST_TIME 9 6901
TEST_TIME 10 6776
TEST_TIME 11 7142
TEST_TIME 12 7365
TEST_TIME 13 7607
TEST_TIME 14 7463
TEST_TIME 15 7604
TEST_TIME 16 7653
TEST_TIME 17 7658
TEST_TIME 18 7764
TEST_TIME 19 8625
TEST_TIME 20 8824
TEST_TIME 21 8555
TEST_TIME 22 8717
TEST_TIME 23 8629
TEST_TIME 24 9052
TEST_TIME 25 9087
TEST_TIME 26 9456
TEST_TIME 27 11131
TEST_TIME 28 10923
TEST_TIME 29 10639
TEST_TIME 30 10638
TEST_TIME 31 10436
TEST_TIME 32 10238
TEST_TIME 33 10235
TEST_TIME 34 10104
TEST_TIME 35 9949
TEST_TIME 36 9823
TEST_TIME 37 9831
TEST_TIME 38 9719
TEST_TIME 39 9641
TEST_TIME 40 9440
TEST_TIME 41 9401
TEST_TIME 42 9332
TEST_TIME 43 9365
TEST_TIME 44 9257
TEST_TIME 45 9239
TEST_TIME 46 9242
TEST_TIME 47 9303
TEST_TIME 48 9292
TEST_TIME 49 9223
TEST_TIME 50 9257
TEST_TIME 51 9327
TEST_TIME 52 9488
TEST_TIME 53 9491
TEST_TIME 54 9445
TEST_TIME 55 9279
TEST_TIME 56 9195
TEST_TIME 57 9200
TEST_TIME 58 9196
TEST_TIME 59 9141
TEST_TIME 60 9091
TEST_TIME 61 9007
TEST_TIME 62 8963
TEST_TIME 63 8913
TEST_TIME 64 8902
TEST_TIME 65 8874
TEST_TIME 66 8950
TEST_TIME 67 8985
TEST_TIME 68 8973
TEST_TIME 69 8985
TEST_TIME 70 8959
TEST_TIME 71 9030
TEST_TIME 72 9018
TEST_TIME 73 9105
TEST_TIME 74 9129
TEST_TIME 75 9170
TEST_TIME 76 9129
TEST_TIME 77 9158
TEST_TIME 78 9169
TEST_TIME 79 9057
TEST_TIME 80 9037
TEST_TIME 81 9031
TEST_TIME 82 8967
TEST_TIME 83 8910
TEST_TIME 84 8862
TEST_TIME 85 8817
TEST_TIME 86 8920
TEST_TIME 87 8937
TEST_TIME 88 8979
TEST_TIME 89 8919
TEST_TIME 90 8877
TEST_TIME 91 8919
TEST_TIME 92 9070
TEST_TIME 93 9050
TEST_TIME 94 9123
TEST_TIME 95 9153
TEST_TIME 96 9114
TEST_TIME 97 9123
TEST_TIME 98 9121
TEST_TIME 99 9113
TEST_TIME 100 9045
TEST_TIME 101 9048
TEST_TIME 102 8990
TEST_TIME 103 8974
TEST_TIME 104 8951
TEST_TIME 105 8884
TEST_TIME 106 8841
TEST_TIME 107 8860
TEST_TIME 108 8867
TEST_TIME 109 8848
TEST_TIME 110 8837
TEST_TIME 111 8912
TEST_TIME 112 8892
TEST_TIME 113 8889
TEST_TIME 114 8846
TEST_TIME 115 8975
TEST_TIME 116 9003
TEST_TIME 117 9004
TEST_TIME 118 9050
TEST_TIME 119 9036
TEST_TIME 120 9015

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-11-2021 04:40:00 to 08-11-2021 04:40:03.

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 1477655 1477655 0 0.00 0 22579 7 0 0
doc_list_biud 1477655 1477655 0 0.00 0 68011 31 0 0
sp_add_invoice_to_stock 4760 4668 92 1.93 42 200002 19881 0 92
sp_cancel_adding_invoice 5832 5832 0 0.00 6 65456 884 0 0
sp_cancel_client_order 1612 1612 0 0.00 5 10991 322 0 0
sp_cancel_customer_reserve 573421 573421 0 0.00 1 68013 64 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 3 5377 93 0 0
sp_cancel_pay_to_supplier 363 363 0 0.00 3 3000 84 0 0
sp_cancel_supplier_invoice 6472 6464 8 0.12 2 25802 990 0 8
sp_cancel_supplier_order 410 410 0 0.00 16 963574 113993 0 0
sp_cancel_write_off 10353 10353 0 0.00 1 12250 110 0 0
sp_client_order 18653 18653 0 0.00 4 40935 415 0 0
sp_customer_reserve 426832 426832 0 0.00 0 52312 198 0 0
sp_fill_shopping_cart 28721 28721 0 0.00 1 30480 51 0 0
sp_fill_shopping_cart_clo_res 426828 426828 0 0.00 0 12830 17 0 0
sp_get_clo_for_invoice 4664 4664 0 0.00 0 29298 435 0 0
sp_kill_cost_storno 17190 17190 0 0.00 0 17455 57 0 0
sp_kill_qstorno_ret_qs2qd 1164069 1164069 0 0.00 0 68000 35 0 0
sp_kill_qty_storno 611766 611766 0 0.00 0 68010 75 0 0
sp_lock_selected_doc 19121 19121 0 0.00 0 6578 4 0 0
sp_make_cost_storno 25611 25611 0 0.00 0 20189 58 0 0
sp_multiply_rows_for_pdistr 25611 25611 0 0.00 0 9718 35 0 0
sp_multiply_rows_for_qdistr 18653 18653 0 0.00 0 33437 205 0 0
sp_pay_from_customer 6573 6573 0 0.00 5 15885 131 0 0
sp_pay_to_supplier 5364 5364 0 0.00 4 20080 151 0 0
sp_payment_common 11937 11937 0 0.00 1 12874 29 0 0
sp_qd_handle_on_cancel_clo 1612 1612 0 0.00 0 10980 291 0 0
sp_qd_handle_on_invoice_upd_sts 10496 10496 0 0.00 0 21565 379 0 0
sp_qd_handle_on_reserve_upd_sts 9010 9010 0 0.00 0 18323 63 0 0
sp_reserve_write_off 9010 9010 0 0.00 7 18343 188 0 0
sp_supplier_invoice 5010 5010 0 0.00 7 73283 3759 0 0
sp_supplier_order 5068 5068 0 0.00 6 46960 1370 0 0
srv_aggregate_perf_data 99 99 0 0.00 3029 129603 15344 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1584 135334 47744 0 0
srv_find_qd_qs_mism 1038245 1038245 0 0.00 0 22577 6 0 0
srv_make_invnt_saldo 458 458 0 0.00 49 84943 3652 0 0
srv_make_money_saldo 721 721 0 0.00 0 11003 493 0 0
srv_recalc_idx_stat 4 4 0 0.00 153441 731019 416266 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4668 4668 0 0.00 0 31055 172 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 6015 102 0 0
t$perf-norm:sp_cancel_client_order 1612 1612 0 0.00 0 3944 17 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 10705 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 6451 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 363 363 0 0.00 0 6513 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 712 13 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 32316 1583 0 0
t$perf-norm:sp_cancel_write_off 920 920 0 0.00 0 11937 20 0 0
t$perf-norm:sp_client_order 18653 18653 0 0.00 0 24038 20 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 5888 15 0 0
t$perf-norm:sp_pay_from_customer 6573 6573 0 0.00 0 19382 14 0 0
t$perf-norm:sp_pay_to_supplier 5364 5364 0 0.00 0 5882 9 0 0
t$perf-norm:sp_reserve_write_off 9010 9010 0 0.00 0 6857 9 0 0
t$perf-norm:sp_supplier_invoice 5010 5010 0 0.00 0 9163 20 0 0
t$perf-norm:sp_supplier_order 5068 5068 0 0.00 0 6300 18 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 179 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 3309 55 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 7225 22 0 0
t$perf-norm:srv_make_money_saldo 721 721 0 0.00 0 2407 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9506 9322 184 1.93 0 3645 18 0 184
v_all_customers 34316 34316 0 0.00 0 30 1 0 0
v_all_suppliers 27980 27980 0 0.00 0 44 1 0 0
v_all_wares 37302 37302 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 510 7 0 0
v_cancel_client_order 3224 3224 0 0.00 0 2082 6 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 1151 6 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 1838 8 0 0
v_cancel_payment_to_supplier 726 726 0 0.00 0 753 4 0 0
v_cancel_supplier_invoice 808 792 16 1.98 0 350 13 0 16
v_cancel_supplier_order 820 820 0 0.00 0 2103 11 0 0
v_cancel_write_off 1840 1840 0 0.00 0 1545 14 0 0
v_max_id_clo_ord 5067 5067 0 0.00 0 3853 3 0 0
v_max_id_clo_res 939 939 0 0.00 0 1446 10 0 0
v_max_id_ord_sup 5003 5003 0 0.00 0 6297 5 0 0
v_max_non_paid_invoice 1444 1444 0 0.00 0 86 2 0 0
v_max_non_paid_realizn 3323 3323 0 0.00 0 4983 5 0 0
v_min_id_clo_ord 5067 5067 0 0.00 0 2239 6 0 0
v_min_id_clo_res 939 939 0 0.00 0 2918 11 0 0
v_min_id_ord_sup 5003 5003 0 0.00 0 5388 6 0 0
v_min_non_paid_invoice 5364 5364 0 0.00 0 12866 19 0 0
v_min_non_paid_realizn 6573 6573 0 0.00 0 12120 21 0 0
v_our_firm 9486 9486 0 0.00 0 24 1 0 0
v_reserve_write_off 18020 18020 0 0.00 0 2897 5 0 0
x_lock_depdocs_on_canc_invoice 11900 11900 0 0.00 0 23172 114 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 17355 367 0 0
x_make_qstorno_1000_1200 5068 5068 0 0.00 2 46951 1281 0 0
x_make_qstorno_1200_2000 5010 5010 0 0.00 3 73278 3564 0 0
x_make_qstorno_2100_3300 426832 426832 0 0.00 0 52310 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-11-2021 04:40:04 to 08-11-2021 04:40:08.

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 12 8192000000 8806675664 8821075968 9605120 0.117 3 3 4 0 9796848 104752 86976
02:08:13 29470 8192000000 9712683424 10008530944 792990208 8.825 100 88 176 0 822227760 7498560 21738992
02:09:55 15724 8192000000 9755708416 10059927552 830097904 9.200 100 92 183 0 864909328 12194640 22616784
02:11:16 13787 8192000000 9794502464 10114240512 869151440 9.592 100 81 162 0 910975296 22764624 19059232
02:12:35 14330 8192000000 9878943024 10211774464 920834560 10.104 100 79 158 0 983327152 45489264 17003328
02:13:55 8879 8192000000 9921523760 10267230208 964889328 10.537 100 74 145 1 1033133872 53053264 15191280
02:15:07 5456 8192000000 9967924704 10325090304 1003926656 10.917 100 77 153 0 1085559184 66192016 15440512
02:16:14 2480 8192000000 10002891232 10363273216 1030589040 11.174 100 86 172 0 1112892672 65161600 17142032
02:17:19 2199 8192000000 10038856960 10408398848 1054996656 11.409 100 84 165 3 1149712864 77490112 17226096
02:18:23 1688 8192000000 10057628208 10429386752 1067673424 11.530 100 92 183 0 1164202576 77782784 18746368
02:19:26 34537 8192000000 10047260624 10429513728 1081402544 11.661 100 52 104 1 1169172768 77201408 10568816
02:21:02 2873 8192000000 10034241376 10427641856 1084257712 11.688 100 46 89 1 1145649920 52081392 9310816
02:22:06 24882 8192000000 10003516128 10400952320 1086968256 11.714 100 47 92 5 1118924752 21765072 10191424
02:23:31 6001 8192000000 10000506560 10400137216 1087960928 11.723 100 32 64 0 1117616864 23510576 6145360
02:24:38 4470 8192000000 10003642512 10402095104 1088676384 11.730 100 49 100 1 1119176128 19879184 10620560
02:25:44 52140 8192000000 10011132000 10406162432 1087171568 11.716 99 38 76 0 1110429696 14549376 8708752
02:27:37 5517 8192000000 10002069824 10391511040 1070415632 11.556 99 44 87 0 1105747152 26534304 8797216
02:28:44 5929 8192000000 10008538016 10396807168 1053722864 11.396 98 46 90 1 1094263776 31041520 9499392
02:29:51 5594 8192000000 9938040592 10320027648 1009279744 10.968 97 32 59 2 1044582384 27937856 7364784
02:30:58 22436 8192000000 9943998864 10333999104 977442864 10.659 94 28 56 2 1005756880 22844080 5469936
02:32:21 10030 8192000000 9936018016 10319904768 1028972208 11.159 100 40 79 0 1051194528 13273920 8948400
02:33:32 9434 8192000000 9975608064 10359214080 1032897968 11.196 99 36 71 1 1070752512 30138176 7716368
02:34:46 69663 8192000000 9965714064 10367197184 1057948992 11.437 100 27 55 2 1083961424 20503600 5508832
02:36:56 10182 8192000000 9974107824 10378158080 1069720944 11.549 100 33 64 2 1097129488 19935152 7473392
02:38:14 14373 8192000000 10054055472 10443214848 1099165568 11.830 100 65 125 0 1154433792 41953312 13314912
02:39:37 5769 8192000000 10068529536 10465366016 1111627456 11.948 100 59 117 2 1182269088 58893504 11748128
02:40:49 64128 8192000000 10102515072 10529316864 1120621888 12.033 100 27 54 0 1234298240 107170256 6506096
02:43:01 54951 8192000000 10101581456 10534166528 1132204192 12.142 100 23 46 2 1230571568 93023824 5343552
02:45:03 3808 8192000000 10160112192 10571169792 1154225424 12.349 100 73 146 1 1274498640 104386592 15886624
02:46:15 7925 8192000000 10194813152 10596909056 1157933696 12.384 100 70 138 1 1297321696 124491120 14896880
02:47:29 6683 8192000000 10220982784 10631966720 1171281248 12.509 100 57 113 2 1323267200 139309344 12676608
02:48:39 12966 8192000000 10211380624 10626760704 1170070512 12.497 100 50 100 0 1315003536 133310080 11622944
02:49:59 3161 8192000000 10207229584 10615668736 1160012896 12.403 99 63 126 0 1289778352 116041728 13723728
02:51:06 2421 8192000000 10272102480 10673070080 1170304352 12.500 100 85 168 1 1370020432 180589904 19126176
02:52:13 1582 8192000000 10279070960 10681475072 1170882624 12.505 99 84 167 1 1377454832 187781264 18790944
02:53:18 2263 8192000000 10351518880 10754543616 1170651072 12.503 99 89 176 1 1439230640 248114688 20464880
02:54:25 2009 8192000000 10391862768 10794168320 1174717136 12.541 100 81 160 1 1489319664 296694784 17907744
02:55:29 2637 8192000000 10428506688 10842824704 1173357248 12.528 99 76 151 1 1528522992 337465472 17700272
02:56:37 4975 8192000000 10395187536 10838581248 1188112944 12.666 100 55 110 0 1496479344 294184000 14182400
02:57:45 8834 8192000000 10371759200 10807709696 1167884368 12.477 100 41 82 0 1469428928 291550192 9994368
02:58:57 1669 8192000000 10438065424 10838876160 1162733680 12.429 100 77 154 1 1532788528 352066016 17988832
03:00:07 9046 8192000000 10447954384 10872586240 1161813984 12.420 100 39 76 1 1550555936 378690208 10051744
03:01:55 22266 8192000000 10444386464 10899550208 1156157376 12.367 100 30 60 0 1565667792 401147744 8362672
03:03:19 19156 8192000000 10409770528 10890272768 1137564416 12.193 100 26 52 0 1541225040 395904320 7756304
03:04:50 24665 8192000000 10043161024 10486063104 1127161744 12.095 100 14 28 0 1217779824 87183920 3434160
03:06:15 119069 8192000000 10079000768 10542907392 1124067120 12.065 99 20 40 1 1175726768 47500016 4159632
03:09:19 61327 8192000000 10083875616 10552078336 1107128656 11.905 97 23 45 0 1184357776 72461488 4767632
03:11:26 41119 8192000000 10095081712 10551824384 1135942720 12.177 100 18 36 1 1189795744 50361024 3492000
03:13:08 135306 8192000000 10108196112 10575511552 1125514848 12.079 99 22 44 2 1190734416 59829120 5390448
03:16:57 77707 8192000000 10087918320 10527432704 1124339664 12.068 99 28 56 1 1180109936 49995248 5775024
03:20:22 49429 8192000000 10065386704 10532118528 1117531488 12.004 98 8 16 0 1169734080 50282400 1920192
03:22:14 22610 8192000000 10058136992 10485477376 1127389712 12.097 99 54 107 3 1160997840 22164256 11443872
03:23:46 95674 8192000000 10090572048 10557104128 1136078448 12.179 99 12 24 0 1175097584 36582400 2436736
03:26:33 62220 8192000000 10116623776 10589138944 1156323600 12.369 100 27 54 0 1228347808 66500304 5523904
03:28:58 89396 8192000000 10110968208 10593030144 1158943696 12.393 100 7 14 0 1235465696 75234336 1287664
03:31:35 103486 8192000000 10118176288 10566012928 1140784000 12.223 98 25 50 0 1205488624 59197488 5507136
03:34:19 34943 8192000000 10117518688 10592722944 1160662640 12.409 100 25 50 2 1222163488 56831984 4668864
03:35:54 26471 8192000000 10081845984 10533392384 1148246816 12.293 100 24 48 1 1189694512 36765696 4682000
03:37:21 60239 8192000000 10079674928 10550308864 1156940384 12.375 100 9 18 0 1196523072 37888768 1693920
03:39:22 76370 8192000000 10101573152 10559176704 1157280224 12.378 100 11 22 0 1189898736 30275184 2343328
03:41:42 64456 8192000000 10114907072 10577408000 1146967280 12.281 99 16 32 0 1199885152 49765904 3151968
03:44:01 70819 8192000000 10091920240 10532745216 1100709488 11.844 95 17 34 0 1130218176 26243536 3265152
03:46:12 47328 8192000000 10100257504 10524520448 1115602352 11.985 97 29 58 0 1173114448 52004672 5507424
03:48:01 46285 8192000000 10132302960 10596245504 1129983296 12.121 98 22 44 0 1217530816 82906656 4640864
03:49:48 16238 8192000000 10122797520 10559787008 1131344752 12.134 100 51 100 2 1222855312 79658320 11852240
03:51:09 52930 8192000000 10104769648 10570104832 1102233936 11.859 97 25 50 1 1186701584 79201200 5266448
03:53:13 20456 8192000000 10072952448 10484867072 1092494016 11.766 98 44 88 0 1131084672 29806816 8783840
03:54:36 71502 8192000000 10073449472 10543394816 1109408576 11.927 99 28 56 0 1181573680 66548128 5616976
03:57:24 78129 8192000000 10051719296 10521391104 1112801824 11.959 99 21 42 0 1168672656 51894096 3976736
03:59:49 62646 8192000000 10100809616 10542252032 1106649456 11.901 98 15 30 1 1178858096 68912752 3295888
04:01:53 105104 8192000000 10121731824 10647515136 1110684752 11.939 98 19 39 0 1206172736 91493120 3994864
04:04:43 93380 8192000000 10064952704 10528595968 1072618976 11.577 95 12 24 1 1132916832 57818544 2479312
04:07:22 54001 8192000000 10148651760 10601611264 1101688640 11.854 97 36 72 0 1214654400 105312768 7652992
04:09:17 84792 8192000000 10080887968 10522329088 1103375392 11.870 98 10 20 1 1175916384 70840864 1700128
04:11:44 42261 8192000000 10075505056 10547826688 1130187840 12.123 100 14 28 0 1180896896 48193792 2515264
04:13:28 17167 8192000000 10097086576 10531430400 1132788000 12.148 99 45 90 1 1171584896 29974032 8822864
04:14:51 124269 8192000000 10084347600 10578083840 1139302160 12.209 99 23 46 0 1238027488 94287120 4438208
04:18:02 39248 8192000000 10095347632 10549432320 1142733664 12.241 99 30 61 0 1194543136 45691824 6117648
04:19:48 41080 8192000000 10062811840 10545500160 1139741136 12.213 100 4 9 0 1180657184 40041152 874896
04:22:26 112539 8192000000 10117477136 10600148992 1149162320 12.302 99 17 34 0 1228524960 75738352 3624288
04:25:24 59897 8192000000 10138694928 10595319808 1164983520 12.450 100 24 48 1 1242206928 71932112 5291296
04:27:28 82286 8192000000 10099026496 10593996800 1157739136 12.382 99 19 39 0 1252320880 90529536 4052208
04:29:50 50678 8192000000 10160966400 10612862976 1139263936 12.209 98 30 60 0 1207590224 61768224 6558064
04:31:44 65009 8192000000 10135643744 10667323392 1158681392 12.391 100 18 36 0 1258026608 95543760 3801456
04:35:06 79157 8192000000 10120485552 10594021376 1154284064 12.350 99 18 36 0 1218826208 60668560 3873584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-11-2021 04:40:10 to 08-11-2021 04:40:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-11-2021 04:40:25 to 08-11-2021 04:40:27.

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          223014
MON$OLDEST_ACTIVE               224206
MON$OLDEST_SNAPSHOT             224206
MON$NEXT_TRANSACTION            224206
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                       2050976
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2653 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2653 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2653 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2653 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 ---
:125: 
:126: mscs-test-161.budzdorov.ru    Mon Nov  8 04:37:08 2021
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:128: 
:129: 
:130: mscs-test-161.budzdorov.ru    Mon Nov  8 04:37:19 2021
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:132: 
:133: 
:134: mscs-test-161.budzdorov.ru    Mon Nov  8 04:37:38 2021
:135:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:136: 
:137: 
:138: mscs-test-161.budzdorov.ru    Mon Nov  8 04:37:55 2021
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:140: 
:141: 
:142: mscs-test-161.budzdorov.ru    Mon Nov  8 04:37:58 2021
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:144: 
:145: 
:146: mscs-test-161.budzdorov.ru    Mon Nov  8 04:37:58 2021
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:148: 
--- end of diff output ---

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

08.11.21 04:40:28. 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-11-08 04:40:31 No severe PSQL-related problems occured
08.11.21 04:40:32. End of report.