Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.09.2021 04:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15860960 kB
MemAvailable 30764628 kB
Buffers 20 kB
Cached 15816596 kB
SwapCached 3072 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-28 04:37:07
DTS_END                         2021-09-28 04:37:07.0380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43138
TRN_ID                          232949
ATT_ID                          674
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: 8506.56 28250 1020787
customer order: creation 168.86 411 20263
customer order: refuse 13.95 401 1674
order to supplier: creation 44.40 1608 5328
order to supplier: removal 2.89 129708 347
invoice (draft): creation 45.19 4482 5423
invoice (draft): removal 45.21 1059 5425
invoice accept: apply 41.46 21647 4975
invoice accept: cancel 40.92 1038 4910
customer reserve: creation 3770.32 215 452438
customer reserve: removal 4049.47 73 485936
realization accept: apply 81.26 229 9751
realization accept: cancel 75.33 125 9040
payment from customer: creation 59.30 117 7116
payment from customer: removal 5.65 99 678
payment to supplier: creation 48.26 145 5791
payment to supplier: removal 3.68 83 441
service: total inventory turnovers 3.11 5173 373
service: total monetary turnovers 6.43 629 771
service: aggregate perf. data 0.86 20838 103
service: refresh index statistics 0.03 376921 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-09-2021 04:38:45 to 28-09-2021 04:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3935
TEST_TIME 2 5066
TEST_TIME 3 5348
TEST_TIME 4 5706
TEST_TIME 5 6047
TEST_TIME 6 5765
TEST_TIME 7 5643
TEST_TIME 8 5824
TEST_TIME 9 6422
TEST_TIME 10 7115
TEST_TIME 11 7232
TEST_TIME 12 7450
TEST_TIME 13 7541
TEST_TIME 14 7516
TEST_TIME 15 7502
TEST_TIME 16 7665
TEST_TIME 17 8286
TEST_TIME 18 8278
TEST_TIME 19 8213
TEST_TIME 20 8429
TEST_TIME 21 8341
TEST_TIME 22 8453
TEST_TIME 23 8442
TEST_TIME 24 9071
TEST_TIME 25 9057
TEST_TIME 26 9355
TEST_TIME 27 9612
TEST_TIME 28 9795
TEST_TIME 29 10456
TEST_TIME 30 10897
TEST_TIME 31 10568
TEST_TIME 32 10314
TEST_TIME 33 10198
TEST_TIME 34 10115
TEST_TIME 35 10048
TEST_TIME 36 10142
TEST_TIME 37 10068
TEST_TIME 38 10002
TEST_TIME 39 9834
TEST_TIME 40 9705
TEST_TIME 41 9570
TEST_TIME 42 9508
TEST_TIME 43 9559
TEST_TIME 44 9593
TEST_TIME 45 9522
TEST_TIME 46 9431
TEST_TIME 47 9403
TEST_TIME 48 9266
TEST_TIME 49 9173
TEST_TIME 50 9040
TEST_TIME 51 8968
TEST_TIME 52 8935
TEST_TIME 53 8856
TEST_TIME 54 8764
TEST_TIME 55 8772
TEST_TIME 56 8847
TEST_TIME 57 8842
TEST_TIME 58 8794
TEST_TIME 59 8792
TEST_TIME 60 8772
TEST_TIME 61 8800
TEST_TIME 62 8777
TEST_TIME 63 8903
TEST_TIME 64 8836
TEST_TIME 65 8801
TEST_TIME 66 8787
TEST_TIME 67 8751
TEST_TIME 68 8808
TEST_TIME 69 8857
TEST_TIME 70 8824
TEST_TIME 71 8869
TEST_TIME 72 8879
TEST_TIME 73 8779
TEST_TIME 74 8715
TEST_TIME 75 8664
TEST_TIME 76 8674
TEST_TIME 77 8608
TEST_TIME 78 8619
TEST_TIME 79 8576
TEST_TIME 80 8496
TEST_TIME 81 8488
TEST_TIME 82 8600
TEST_TIME 83 8576
TEST_TIME 84 8585
TEST_TIME 85 8620
TEST_TIME 86 8576
TEST_TIME 87 8709
TEST_TIME 88 8752
TEST_TIME 89 8710
TEST_TIME 90 8678
TEST_TIME 91 8780
TEST_TIME 92 8898
TEST_TIME 93 8903
TEST_TIME 94 8869
TEST_TIME 95 8824
TEST_TIME 96 8785
TEST_TIME 97 8736
TEST_TIME 98 8705
TEST_TIME 99 8686
TEST_TIME 100 8734
TEST_TIME 101 8725
TEST_TIME 102 8727
TEST_TIME 103 8710
TEST_TIME 104 8697
TEST_TIME 105 8714
TEST_TIME 106 8722
TEST_TIME 107 8717
TEST_TIME 108 8737
TEST_TIME 109 8727
TEST_TIME 110 8792
TEST_TIME 111 8737
TEST_TIME 112 8702
TEST_TIME 113 8690
TEST_TIME 114 8637
TEST_TIME 115 8618
TEST_TIME 116 8575
TEST_TIME 117 8544
TEST_TIME 118 8598
TEST_TIME 119 8574
TEST_TIME 120 8509

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-09-2021 04:38:48 to 28-09-2021 04:38:52.

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 1437074 1437074 0 0.00 0 22913 8 0 0
doc_list_biud 1437072 1437072 0 0.00 0 24828 32 0 0
sp_add_invoice_to_stock 5064 4975 89 1.75 33 264191 21647 0 89
sp_cancel_adding_invoice 4910 4910 0 0.00 4 71240 1038 0 0
sp_cancel_client_order 1674 1674 0 0.00 5 11106 402 0 0
sp_cancel_customer_reserve 485936 485936 0 0.00 1 24834 73 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 9957 100 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 3939 83 0 0
sp_cancel_supplier_invoice 5438 5425 13 0.23 1 16916 1060 0 13
sp_cancel_supplier_order 347 347 0 0.00 8 1022123 129709 0 0
sp_cancel_write_off 9040 9040 0 0.00 1 9850 126 0 0
sp_client_order 20263 20263 0 0.00 5 26610 411 0 0
sp_customer_reserve 452438 452438 0 0.00 0 68885 216 0 0
sp_fill_shopping_cart 30996 30996 0 0.00 1 23681 58 0 0
sp_fill_shopping_cart_clo_res 452430 452430 0 0.00 0 15364 17 0 0
sp_get_clo_for_invoice 4967 4967 0 0.00 0 23067 373 0 0
sp_kill_cost_storno 15069 15069 0 0.00 0 9951 69 0 0
sp_kill_qstorno_ret_qs2qd 986684 986684 0 0.00 0 24823 40 0 0
sp_kill_qty_storno 522049 522049 0 0.00 0 24827 87 0 0
sp_lock_selected_doc 20294 20294 0 0.00 0 9036 5 0 0
sp_make_cost_storno 27624 27624 0 0.00 0 18359 63 0 0
sp_multiply_rows_for_pdistr 27624 27624 0 0.00 0 8194 37 0 0
sp_multiply_rows_for_qdistr 20263 20263 0 0.00 0 12784 186 0 0
sp_pay_from_customer 7116 7116 0 0.00 4 15565 117 0 0
sp_pay_to_supplier 5791 5791 0 0.00 5 13203 146 0 0
sp_payment_common 12907 12907 0 0.00 1 9069 22 0 0
sp_qd_handle_on_cancel_clo 1674 1674 0 0.00 0 11062 372 0 0
sp_qd_handle_on_invoice_upd_sts 9876 9876 0 0.00 0 21801 428 0 0
sp_qd_handle_on_reserve_upd_sts 9751 9751 0 0.00 0 23797 82 0 0
sp_reserve_write_off 9751 9751 0 0.00 7 24089 229 0 0
sp_supplier_invoice 5423 5423 0 0.00 7 116129 4483 0 0
sp_supplier_order 5328 5328 0 0.00 5 44109 1608 0 0
srv_aggregate_perf_data 103 103 0 0.00 2651 336367 20838 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1989 107196 43019 0 0
srv_find_qd_qs_mism 972560 972560 0 0.00 0 22913 7 0 0
srv_make_invnt_saldo 373 373 0 0.00 44 107170 5174 0 0
srv_make_money_saldo 771 771 0 0.00 1 20574 629 0 0
srv_recalc_idx_stat 4 4 0 0.00 172268 541861 376921 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4975 4975 0 0.00 0 24879 208 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 8697 148 0 0
t$perf-norm:sp_cancel_client_order 1674 1674 0 0.00 0 7010 24 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 1209 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 2264 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 118 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 2494 25 0 0
t$perf-norm:sp_cancel_supplier_order 347 347 0 0.00 0 22833 1937 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 2154 12 0 0
t$perf-norm:sp_client_order 20263 20263 0 0.00 0 21555 27 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 2155 9 0 0
t$perf-norm:sp_pay_from_customer 7116 7116 0 0.00 0 3859 10 0 0
t$perf-norm:sp_pay_to_supplier 5791 5791 0 0.00 0 13344 14 0 0
t$perf-norm:sp_reserve_write_off 9751 9751 0 0.00 0 12165 17 0 0
t$perf-norm:sp_supplier_invoice 5423 5423 0 0.00 0 26965 30 0 0
t$perf-norm:sp_supplier_order 5328 5328 0 0.00 0 21578 30 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 163 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 472 10 0 0
t$perf-norm:srv_make_invnt_saldo 373 373 0 0.00 0 514 7 0 0
t$perf-norm:srv_make_money_saldo 771 771 0 0.00 0 2184 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10110 9932 178 1.76 0 4892 20 0 178
v_all_customers 37282 37282 0 0.00 0 19 1 0 0
v_all_suppliers 29404 29404 0 0.00 0 94 1 0 0
v_all_wares 40522 40522 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 3054 15 0 0
v_cancel_client_order 3348 3348 0 0.00 0 3982 7 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 1545 5 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 1150 6 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 409 5 0 0
v_cancel_supplier_invoice 764 738 26 3.40 0 962 14 0 26
v_cancel_supplier_order 694 694 0 0.00 0 1885 9 0 0
v_cancel_write_off 1964 1964 0 0.00 0 3012 17 0 0
v_max_id_clo_ord 5328 5328 0 0.00 0 3856 4 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 3449 10 0 0
v_max_id_ord_sup 5407 5407 0 0.00 0 12550 9 0 0
v_max_non_paid_invoice 1824 1824 0 0.00 0 460 3 0 0
v_max_non_paid_realizn 3810 3810 0 0.00 0 1637 4 0 0
v_min_id_clo_ord 5328 5328 0 0.00 0 4842 11 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 3548 20 0 0
v_min_id_ord_sup 5407 5407 0 0.00 0 7155 13 0 0
v_min_non_paid_invoice 5791 5791 0 0.00 0 9066 18 0 0
v_min_non_paid_realizn 7116 7116 0 0.00 0 6995 14 0 0
v_our_firm 9852 9852 0 0.00 0 15 1 0 0
v_reserve_write_off 19502 19502 0 0.00 0 4701 8 0 0
x_lock_depdocs_on_canc_invoice 9966 9966 0 0.00 0 18110 146 0 0
x_lock_depdocs_on_canc_sup_ord 347 347 0 0.00 0 3534 400 0 0
x_make_qstorno_1000_1200 5328 5328 0 0.00 2 44100 1496 0 0
x_make_qstorno_1200_2000 5423 5423 0 0.00 3 116114 4256 0 0
x_make_qstorno_2100_3300 452438 452438 0 0.00 0 68882 195 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-09-2021 04:38:53 to 28-09-2021 04:38:57.

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:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:11 29946 8192000000 9710385888 10004508672 781656880 8.710 100 84 167 2 809823792 7446800 20720112
02:09:48 22618 8192000000 9756812608 10060439552 828740048 9.187 100 92 184 1 863108928 12328272 22040608
02:11:18 23811 8192000000 9802434000 10119147520 861758944 9.518 100 81 162 0 904506368 24765072 17982352
02:12:48 16598 8192000000 9883151408 10217218048 920434720 10.100 100 80 160 2 991993776 54606816 16952240
02:14:09 9338 8192000000 9923349936 10269593600 964673440 10.535 100 78 156 0 1035634848 55036224 15925184
02:15:22 5409 8192000000 9963861536 10320687104 986389168 10.746 100 72 144 0 1088385904 87305248 14691488
02:16:30 3559 8192000000 9988654240 10350485504 1010684528 10.982 100 82 164 0 1104105344 76821760 16599056
02:17:36 2637 8192000000 10014227328 10379354112 1039017392 11.255 100 95 189 1 1121989616 62823376 20148848
02:18:41 2155 8192000000 10045674192 10413957120 1059304688 11.450 100 84 170 0 1149249856 72450048 17495120
02:19:45 3167 8192000000 10029223488 10406010880 1067024992 11.524 100 82 162 2 1146047984 61938096 17084896
02:20:48 6484 8192000000 9993591296 10395004928 1075821008 11.608 100 34 68 1 1116762320 33703584 7237728
02:21:58 5209 8192000000 9998852736 10392039424 1086877568 11.713 100 42 81 0 1112462480 16420224 9164688
02:23:04 4282 8192000000 10019147600 10410618880 1092471472 11.766 100 50 96 4 1135577488 33045728 10060288
02:24:11 8174 8192000000 10031929152 10447450112 1094499040 11.785 100 49 98 0 1133191104 27683440 11008624
02:25:19 10838 8192000000 10002677168 10416087040 1099023920 11.828 100 43 86 1 1120131488 11960624 9146944
02:26:32 8178 8192000000 10006194432 10400559104 1091482896 11.757 99 32 58 1 1114901904 17104656 6314352
02:27:40 61799 8192000000 10004013856 10391535616 1070010768 11.552 98 36 72 1 1099257712 21131120 8115824
02:29:44 5375 8192000000 9970220192 10366062592 1018446320 11.057 96 51 102 1 1039643472 9652768 11544384
02:30:51 8471 8192000000 9941712032 10313056256 986119328 10.744 95 33 64 2 1008934816 16869616 5945872
02:32:00 63132 8192000000 9919030240 10312617984 975671152 10.642 95 24 45 1 1002338896 20964448 5703296
02:34:04 5482 8192000000 9957112688 10343632896 1027897056 11.148 99 32 63 1 1053629456 19104368 6628032
02:35:10 7047 8192000000 9952227632 10352783360 1047751872 11.339 100 21 42 0 1065233440 12219216 5262352
02:36:18 6349 8192000000 9973991600 10370519040 1061404656 11.470 100 49 95 0 1080519264 8957280 10157328
02:37:26 25925 8192000000 9993845104 10387386368 1075769872 11.607 100 60 118 1 1099604448 10826512 13008064
02:39:01 19394 8192000000 10049664320 10444820480 1092399456 11.765 100 61 122 1 1150954128 46389328 12165344
02:40:26 17224 8192000000 10088787328 10493640704 1116113296 11.990 100 60 120 1 1196585280 68321088 12150896
02:41:48 45662 8192000000 10087863936 10519314432 1124527424 12.070 100 38 76 0 1221586528 88555584 8503520
02:43:39 26850 8192000000 10139036400 10550845440 1142843488 12.242 100 50 100 0 1242995248 89544272 10607488
02:45:08 13239 8192000000 10171088000 10580295680 1147756864 12.288 100 64 126 0 1276569440 114939776 13872800
02:46:26 2709 8192000000 10190754256 10601381888 1157311440 12.378 100 69 138 0 1290613168 118516880 14784848
02:47:33 7986 8192000000 10193606208 10596786176 1157912608 12.384 100 73 142 1 1289737792 115839056 15986128
02:48:46 4852 8192000000 10234075824 10643808256 1169210192 12.489 100 83 167 1 1338782976 151740592 17832192
02:49:56 3396 8192000000 10216715040 10618097664 1144865936 12.261 99 89 177 0 1315625280 151883728 18875616
02:51:03 4608 8192000000 10267142704 10666446848 1139903808 12.215 99 79 158 0 1365110960 207719152 17488000
02:52:12 3785 8192000000 10264842528 10663485440 1129174864 12.114 98 81 159 1 1361687920 215011568 17501488
02:53:22 1988 8192000000 10260790768 10663624704 1133440656 12.154 100 70 138 2 1362650736 212889968 16320112
02:54:33 9131 8192000000 10298224416 10749435904 1144614256 12.259 100 79 158 1 1422618640 259637296 18367088
02:55:50 2540 8192000000 10306803424 10706026496 1142956656 12.243 100 85 170 1 1407234256 244069904 20207696
02:56:59 2547 8192000000 10346374048 10760945664 1154392544 12.351 100 75 150 2 1449111056 277438352 17280160
02:58:09 3437 8192000000 10349303792 10752249856 1149025584 12.300 100 75 148 2 1448603232 281446384 18131264
02:59:20 9443 8192000000 10387748896 10813034496 1146533248 12.277 99 40 79 0 1473963184 316561312 10868624
03:00:37 3377 8192000000 10387135504 10810302464 1139679600 12.213 100 73 140 2 1479204800 323411568 16113632
03:01:46 5945 8192000000 10395078944 10833592320 1155772656 12.364 100 40 80 0 1496652256 331231168 9648432
03:03:18 13430 8192000000 10329687968 10793439232 1136135248 12.179 99 34 68 1 1459378432 314696544 8546640
03:04:45 14626 8192000000 10294484496 10725195776 1123965264 12.064 99 25 50 2 1404727088 274328560 6433264
03:06:15 10757 8192000000 10252986832 10710700032 1126231920 12.086 100 31 62 0 1347264160 212949840 8082400
03:07:29 48778 8192000000 10064045216 10491744256 1103998160 11.876 98 41 82 2 1151175856 38964592 8213104
03:09:29 100061 8192000000 10074972128 10504372224 1106145824 11.896 98 34 68 2 1145052608 31549456 7357328
03:12:13 60332 8192000000 10143288416 10603474944 1113112608 11.962 98 11 22 0 1234772000 119203248 2456144
03:14:20 12384 8192000000 10098466192 10535239680 1136063296 12.178 100 26 52 1 1200998736 59964096 4971344
03:15:33 70873 8192000000 10083575744 10522578944 1121306064 12.039 98 22 43 2 1167129200 41489520 4333616
03:18:05 61874 8192000000 10179939920 10627002368 1154192816 12.349 100 29 58 0 1257496496 96652512 6651168
03:20:52 101814 8192000000 10106371568 10588626944 1146406272 12.276 100 20 40 1 1229753504 78823264 4523968
03:23:38 69668 8192000000 10083246832 10521878528 1141420224 12.229 100 33 66 1 1198874080 50329616 7124240
03:25:51 66867 8192000000 10087456144 10546987008 1147294384 12.284 100 29 58 1 1206246256 52446048 6505824
03:28:07 69412 8192000000 10082433456 10558095360 1145725872 12.269 100 11 22 0 1215408096 67035632 2646592
03:30:20 50806 8192000000 10108178304 10573410304 1153975952 12.347 100 32 64 1 1230426192 69613728 6836512
03:32:12 41043 8192000000 10169025328 10629820416 1146396496 12.276 99 30 60 0 1251241536 98386864 6458176
03:34:17 34339 8192000000 10093770592 10572107776 1151496336 12.324 100 10 20 0 1197044064 43656896 1890832
03:35:52 56157 8192000000 10101679472 10554327040 1115723776 11.987 97 30 60 1 1162231392 39709456 6798160
03:37:56 82145 8192000000 10104709696 10580242432 1103770896 11.873 98 16 32 0 1196442592 89052832 3618864
03:40:19 101773 8192000000 10038035088 10503618560 1047434528 11.336 93 11 22 1 1095293200 45621408 2237264
03:43:01 21001 8192000000 10078898208 10519822336 1105946624 11.894 99 27 52 2 1157221360 46031536 5243200
03:44:23 56105 8192000000 10120231504 10578878464 1103572944 11.872 98 21 42 1 1175896576 67967680 4355952
03:46:19 84582 8192000000 10073325632 10512564224 1082201168 11.668 96 14 29 0 1125797120 40793024 2802928
03:48:45 107196 8192000000 10051271472 10534363136 1104330304 11.879 98 14 28 0 1143595552 36556512 2708736
03:51:32 28986 8192000000 10057718480 10500263936 1128298336 12.105 100 27 54 0 1158289536 24959696 5031504
03:53:06 26126 8192000000 10071440272 10522177536 1128746352 12.110 100 24 46 3 1177349888 42692496 5911040
03:54:57 65849 8192000000 10065608816 10539769856 1108100848 11.914 98 16 32 1 1183479504 71897040 3481616
03:57:03 74592 8192000000 10097629584 10573869056 1132384112 12.144 100 11 22 0 1211884928 77268224 2232592
03:59:49 54346 8192000000 10088303648 10544525312 1130836304 12.129 99 20 41 0 1176228320 41014272 4377744
04:01:48 76138 8192000000 10117478400 10558169088 1081189952 11.659 95 26 52 2 1147085104 60355488 5539664
04:04:08 41433 8192000000 10124961696 10590633984 1098653456 11.825 98 26 52 1 1209315680 104609952 6052272
04:05:58 38583 8192000000 10090506672 10520240128 1103158480 11.868 98 27 54 0 1154363296 45889376 5315440
04:07:38 65083 8192000000 10145310864 10660605952 1117014576 11.999 98 23 46 0 1231514272 109580624 4919072
04:09:46 93020 8192000000 10071569456 10566123520 1101995040 11.857 97 16 32 0 1163215056 58255056 2964960
04:13:36 67754 8192000000 10074920128 10544345088 1129156864 12.113 99 5 10 0 1164563440 34524400 882176
04:15:44 64576 8192000000 10135090496 10602999808 1144647472 12.259 100 17 34 1 1226828976 77779440 4402064
04:18:14 84968 8192000000 10094360624 10576314368 1157027856 12.375 100 20 40 0 1203823280 42920304 3875120
04:20:47 23552 8192000000 10093125872 10535116800 1125178128 12.076 98 27 54 1 1168096704 37620736 5297840
04:22:11 51146 8192000000 10085177760 10556645376 1137441680 12.191 100 31 62 1 1209051040 65312176 6297184
04:24:03 25414 8192000000 10140255200 10576883712 1144599888 12.259 100 50 99 0 1210225136 55805440 9819808
04:25:29 33068 8192000000 10081350432 10530074624 1084680272 11.692 95 40 79 1 1146888896 53990624 8218000
04:27:04 63779 8192000000 10105295680 10610147328 1135300048 12.171 100 25 50 0 1214293056 73965984 5027024
04:29:09 69234 8192000000 10087329168 10546601984 1098731136 11.826 97 27 55 1 1155789088 51743472 5314480
04:31:19 73955 8192000000 10066003776 10548969472 1110665200 11.939 98 8 16 0 1175555136 63044288 1845648
04:33:43 81513 8192000000 10109303248 10571456512 1124761216 12.072 99 20 40 1 1200424224 71238608 4424400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-09-2021 04:39:15 to 28-09-2021 04:39:17.

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          231780
MON$OLDEST_ACTIVE               233072
MON$OLDEST_SNAPSHOT             233072
MON$NEXT_TRANSACTION            233072
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                       2057184
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 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 ---
:169: 
:170: mscs-test-161.budzdorov.ru    Tue Sep 28 04:37:08 2021
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:172: 
:173: 
:174: mscs-test-161.budzdorov.ru    Tue Sep 28 04:37:09 2021
:175:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:176: 
:177: 
:178: mscs-test-161.budzdorov.ru    Tue Sep 28 04:37:23 2021
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:180: 
:181: 
:182: mscs-test-161.budzdorov.ru    Tue Sep 28 04:37:23 2021
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:184: 
--- end of diff output ---

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

28.09.21 04:39:18. 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-28 04:39:20 No severe PSQL-related problems occured
28.09.21 04:39:22. End of report.