Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.01.2021 10:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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 4794.00
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 16657656 kB
MemAvailable 31192680 kB
Buffers 0 kB
Cached 14919432 kB
SwapCached 4868 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.0
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-01-23 10:26:52
DTS_END                         2021-01-23 10:26:52.7110
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/49312
TRN_ID                          226111
ATT_ID                          586
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: 8763.79 20154 1051655
customer order: creation 161.97 425 19436
customer order: refuse 13.76 337 1651
order to supplier: creation 42.61 1239 5113
order to supplier: removal 3.52 117810 422
invoice (draft): creation 42.06 3557 5047
invoice (draft): removal 50.45 855 6054
invoice accept: apply 38.53 19604 4623
invoice accept: cancel 45.30 881 5436
customer reserve: creation 3593.33 191 431200
customer reserve: removal 4499.24 75 539909
realization accept: apply 77.67 174 9320
realization accept: cancel 75.91 119 9109
payment from customer: creation 54.94 111 6593
payment from customer: removal 5.24 92 629
payment to supplier: creation 45.61 136 5473
payment to supplier: removal 2.94 75 353
service: total inventory turnovers 3.88 3132 465
service: total monetary turnovers 5.97 404 716
service: aggregate perf. data 0.84 12046 101
service: refresh index statistics 0.04 241817 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-01-2021 10:28:22 to 23-01-2021 10:28:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1868
TEST_TIME 2 2691
TEST_TIME 3 2827
TEST_TIME 4 3486
TEST_TIME 5 3369
TEST_TIME 6 3672
TEST_TIME 7 4337
TEST_TIME 8 4663
TEST_TIME 9 4734
TEST_TIME 10 5285
TEST_TIME 11 5468
TEST_TIME 12 5762
TEST_TIME 13 6189
TEST_TIME 14 6489
TEST_TIME 15 6588
TEST_TIME 16 6851
TEST_TIME 17 7095
TEST_TIME 18 7226
TEST_TIME 19 8081
TEST_TIME 20 8361
TEST_TIME 21 8428
TEST_TIME 22 8579
TEST_TIME 23 8454
TEST_TIME 24 8639
TEST_TIME 25 9059
TEST_TIME 26 9240
TEST_TIME 27 10547
TEST_TIME 28 10988
TEST_TIME 29 10686
TEST_TIME 30 10425
TEST_TIME 31 10174
TEST_TIME 32 10286
TEST_TIME 33 10212
TEST_TIME 34 10030
TEST_TIME 35 10095
TEST_TIME 36 10034
TEST_TIME 37 9880
TEST_TIME 38 9814
TEST_TIME 39 9641
TEST_TIME 40 9496
TEST_TIME 41 9517
TEST_TIME 42 9449
TEST_TIME 43 9449
TEST_TIME 44 9321
TEST_TIME 45 9443
TEST_TIME 46 9378
TEST_TIME 47 9557
TEST_TIME 48 9596
TEST_TIME 49 9516
TEST_TIME 50 9424
TEST_TIME 51 9527
TEST_TIME 52 9503
TEST_TIME 53 9558
TEST_TIME 54 9435
TEST_TIME 55 9284
TEST_TIME 56 9280
TEST_TIME 57 9164
TEST_TIME 58 9164
TEST_TIME 59 9103
TEST_TIME 60 9049
TEST_TIME 61 9031
TEST_TIME 62 8988
TEST_TIME 63 8990
TEST_TIME 64 9029
TEST_TIME 65 9093
TEST_TIME 66 9257
TEST_TIME 67 9216
TEST_TIME 68 9219
TEST_TIME 69 9302
TEST_TIME 70 9314
TEST_TIME 71 9348
TEST_TIME 72 9256
TEST_TIME 73 9179
TEST_TIME 74 9151
TEST_TIME 75 9074
TEST_TIME 76 9030
TEST_TIME 77 8977
TEST_TIME 78 8975
TEST_TIME 79 8940
TEST_TIME 80 8969
TEST_TIME 81 8981
TEST_TIME 82 8985
TEST_TIME 83 8970
TEST_TIME 84 8955
TEST_TIME 85 8995
TEST_TIME 86 8994
TEST_TIME 87 8941
TEST_TIME 88 8984
TEST_TIME 89 8962
TEST_TIME 90 8880
TEST_TIME 91 8844
TEST_TIME 92 8780
TEST_TIME 93 8760
TEST_TIME 94 8727
TEST_TIME 95 8700
TEST_TIME 96 8740
TEST_TIME 97 8759
TEST_TIME 98 8791
TEST_TIME 99 8771
TEST_TIME 100 8775
TEST_TIME 101 8789
TEST_TIME 102 8795
TEST_TIME 103 8800
TEST_TIME 104 8799
TEST_TIME 105 8880
TEST_TIME 106 8873
TEST_TIME 107 8795
TEST_TIME 108 8738
TEST_TIME 109 8782
TEST_TIME 110 8740
TEST_TIME 111 8721
TEST_TIME 112 8736
TEST_TIME 113 8717
TEST_TIME 114 8737
TEST_TIME 115 8698
TEST_TIME 116 8700
TEST_TIME 117 8676
TEST_TIME 118 8644
TEST_TIME 119 8668
TEST_TIME 120 8765

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-01-2021 10:28:26 to 23-01-2021 10:28:29.

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 1452210 1452210 0 0.00 0 52407 7 0 0
doc_list_biud 1452210 1452210 0 0.00 0 63074 34 0 0
sp_add_invoice_to_stock 4777 4623 154 3.22 38 191181 19604 0 154
sp_cancel_adding_invoice 5436 5436 0 0.00 4 109307 882 0 0
sp_cancel_client_order 1651 1651 0 0.00 5 28857 338 0 0
sp_cancel_customer_reserve 539909 539909 0 0.00 1 63077 75 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 3 7011 92 0 0
sp_cancel_pay_to_supplier 353 353 0 0.00 3 5338 75 0 0
sp_cancel_supplier_invoice 6062 6054 8 0.13 2 23475 855 0 8
sp_cancel_supplier_order 422 422 0 0.00 12 1115531 117811 0 0
sp_cancel_write_off 9109 9109 0 0.00 1 57167 120 0 0
sp_client_order 19436 19436 0 0.00 4 39541 425 0 0
sp_customer_reserve 431200 431200 0 0.00 0 87903 191 0 0
sp_fill_shopping_cart 29581 29581 0 0.00 1 24909 40 0 0
sp_fill_shopping_cart_clo_res 431199 431199 0 0.00 0 23782 24 0 0
sp_get_clo_for_invoice 4622 4622 0 0.00 0 37692 473 0 0
sp_kill_cost_storno 15527 15527 0 0.00 0 7307 61 0 0
sp_kill_qstorno_ret_qs2qd 1095403 1095403 0 0.00 0 63072 40 0 0
sp_kill_qty_storno 576523 576523 0 0.00 0 63074 85 0 0
sp_lock_selected_doc 19381 19381 0 0.00 0 8604 6 0 0
sp_make_cost_storno 26008 26008 0 0.00 0 19718 57 0 0
sp_multiply_rows_for_pdistr 26008 26008 0 0.00 0 14103 33 0 0
sp_multiply_rows_for_qdistr 19436 19436 0 0.00 0 39141 262 0 0
sp_pay_from_customer 6593 6593 0 0.00 4 15028 112 0 0
sp_pay_to_supplier 5473 5473 0 0.00 5 13343 136 0 0
sp_payment_common 12066 12066 0 0.00 1 14138 26 0 0
sp_qd_handle_on_cancel_clo 1651 1651 0 0.00 0 28847 305 0 0
sp_qd_handle_on_invoice_upd_sts 10058 10058 0 0.00 0 55364 399 0 0
sp_qd_handle_on_reserve_upd_sts 9320 9320 0 0.00 0 8342 51 0 0
sp_reserve_write_off 9320 9320 0 0.00 7 15384 174 0 0
sp_supplier_invoice 5047 5047 0 0.00 6 107230 3557 0 0
sp_supplier_order 5113 5113 0 0.00 6 23024 1239 0 0
srv_aggregate_perf_data 101 101 0 0.00 2214 106536 12047 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1464 185429 53785 0 0
srv_find_qd_qs_mism 1007850 1007850 0 0.00 0 52406 6 0 0
srv_make_invnt_saldo 465 465 0 0.00 60 92117 3132 0 0
srv_make_money_saldo 716 716 0 0.00 1 13439 405 0 0
srv_recalc_idx_stat 5 5 0 0.00 111764 424233 241817 0 0
t$perf-abend:sp_add_invoice_to_stock 154 154 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 4624 4624 0 0.00 0 11575 157 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 0 4799 121 0 0
t$perf-norm:sp_cancel_client_order 1651 1651 0 0.00 0 723 9 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 1999 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 1188 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 353 353 0 0.00 0 2423 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 1476 16 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 13392 1329 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 724 5 0 0
t$perf-norm:sp_client_order 19436 19436 0 0.00 0 5062 15 0 0
t$perf-norm:sp_customer_reserve 938 938 0 0.00 0 1722 7 0 0
t$perf-norm:sp_pay_from_customer 6593 6593 0 0.00 0 8661 9 0 0
t$perf-norm:sp_pay_to_supplier 5473 5473 0 0.00 0 3514 7 0 0
t$perf-norm:sp_reserve_write_off 9320 9320 0 0.00 0 3911 8 0 0
t$perf-norm:sp_supplier_invoice 5047 5047 0 0.00 0 6192 16 0 0
t$perf-norm:sp_supplier_order 5113 5113 0 0.00 0 8345 15 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 439 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 2436 42 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 244 4 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 1235 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9552 9244 308 3.22 0 4814 19 0 308
v_all_customers 35468 35468 0 0.00 0 106 1 0 0
v_all_suppliers 28431 28431 0 0.00 0 17 1 0 0
v_all_wares 38870 38870 0 0.00 0 124 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 1925 11 0 0
v_cancel_client_order 3302 3302 0 0.00 0 3625 7 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 1559 6 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 1476 5 0 0
v_cancel_payment_to_supplier 706 706 0 0.00 0 239 3 0 0
v_cancel_supplier_invoice 826 810 16 1.93 0 3820 26 0 16
v_cancel_supplier_order 844 844 0 0.00 0 2196 7 0 0
v_cancel_write_off 1872 1872 0 0.00 0 1831 12 0 0
v_max_id_clo_ord 5110 5110 0 0.00 0 729 1 0 0
v_max_id_clo_res 938 938 0 0.00 0 3170 20 0 0
v_max_id_ord_sup 5036 5036 0 0.00 0 8156 6 0 0
v_max_non_paid_invoice 1403 1403 0 0.00 0 424 2 0 0
v_max_non_paid_realizn 3425 3425 0 0.00 0 2651 3 0 0
v_min_id_clo_ord 5110 5110 0 0.00 0 716 3 0 0
v_min_id_clo_res 938 938 0 0.00 0 4243 10 0 0
v_min_id_ord_sup 5036 5036 0 0.00 0 8044 12 0 0
v_min_non_paid_invoice 5473 5473 0 0.00 0 6035 16 0 0
v_min_non_paid_realizn 6593 6593 0 0.00 0 14127 22 0 0
v_our_firm 9738 9738 0 0.00 0 14 1 0 0
v_reserve_write_off 18640 18640 0 0.00 0 3983 5 0 0
x_lock_depdocs_on_canc_invoice 11085 11085 0 0.00 0 23271 102 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 8526 322 0 0
x_make_qstorno_1000_1200 5113 5113 0 0.00 2 23016 1174 0 0
x_make_qstorno_1200_2000 5047 5047 0 0.00 3 103918 3397 0 0
x_make_qstorno_2100_3300 431200 431200 0 0.00 0 87889 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-01-2021 10:28:30 to 23-01-2021 10:28:34.

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
07:56:56 7 8192000000 8798403616 8809598976 4445904 0.054 2 1 2 0 4545296 63616 35776
07:57:57 29015 8192000000 9728322976 10022273024 799190832 8.888 100 87 174 0 828416992 8128080 21098080
07:59:31 6205 8192000000 9764415632 10072055808 844961920 9.350 100 86 172 0 877741360 12290608 20488832
08:00:41 20963 8192000000 9803930736 10118778880 868519392 9.585 100 91 182 0 907822080 18135488 21167200
08:02:17 16462 8192000000 9864333248 10194104320 910237664 10.000 100 71 142 0 972177120 46657344 15282112
08:03:37 10938 8192000000 9923787424 10264653824 957212736 10.462 100 78 156 0 1038377024 64698656 16465632
08:04:49 8655 8192000000 9969755552 10325172224 990872416 10.790 100 75 150 1 1092926704 86955152 15099136
08:06:00 2599 8192000000 10012411856 10371170304 1026173408 11.132 100 89 177 1 1120736368 76414880 18148080
08:07:04 1641 8192000000 10021875376 10388291584 1049848768 11.359 100 86 169 2 1128707024 62315120 16543136
08:08:07 1800 8192000000 10042970320 10412261376 1062313680 11.479 100 92 186 1 1147860240 66364704 19181856
08:09:10 13809 8192000000 10011264192 10386350080 1069570448 11.548 100 68 137 2 1117095616 32940496 14584672
08:10:25 4562 8192000000 10008084592 10396291072 1079837056 11.646 100 42 85 0 1122142320 33399440 8905824
08:11:30 6201 8192000000 9993666576 10390663168 1086979568 11.714 100 42 81 1 1114129488 17612112 9537808
08:12:37 56918 8192000000 10010854256 10415194112 1091941872 11.761 100 36 72 2 1117815072 18200256 7672944
08:14:37 6053 8192000000 10019808496 10430283776 1095883248 11.799 100 31 61 2 1140134944 37239488 7012208
08:15:46 6524 8192000000 10005215200 10405638144 1095808912 11.798 100 34 66 0 1122983648 20521984 6652752
08:17:00 8504 8192000000 9982678016 10375786496 1060463936 11.461 98 34 68 2 1084050720 15567120 8019664
08:18:10 40278 8192000000 9964537776 10368331776 1034604368 11.213 96 33 64 2 1078017024 36103392 7309264
08:19:53 6618 8192000000 9946877936 10327912448 1019818992 11.070 98 32 63 0 1050915536 24307712 6788832
08:21:02 5222 8192000000 9945960368 10339840000 1025294000 11.123 100 38 77 1 1067017984 32884384 8839600
08:22:08 6687 8192000000 9933105664 10320912384 1012058336 10.995 98 48 94 2 1041102352 18228288 10815728
08:23:16 23387 8192000000 9952544496 10350600192 1027799136 11.147 99 24 48 2 1059309248 26551856 4958256
08:24:41 5713 8192000000 9955823792 10355789824 1039586368 11.261 99 59 117 2 1070011808 17203936 13221504
08:25:49 8989 8192000000 9967441136 10372890624 1061881152 11.474 100 33 69 1 1084698736 15394528 7423056
08:26:59 28628 8192000000 9996279712 10394083328 1085396816 11.699 100 32 61 1 1109974032 18034464 6542752
08:28:38 110035 8192000000 10090095728 10501271552 1108230448 11.916 100 56 107 1 1202223840 82057888 11935504
08:31:52 10196 8192000000 10116955136 10523496448 1125602960 12.080 100 48 96 0 1220406592 84550480 10253152
08:33:09 17716 8192000000 10175104144 10594021376 1138814080 12.204 100 42 83 3 1282292880 134149008 9329792
08:34:32 4371 8192000000 10169603104 10574360576 1149436416 12.304 100 54 107 0 1268942000 107769216 11736368
08:35:38 5651 8192000000 10196215360 10604494848 1154678160 12.353 100 63 123 3 1304122000 134801392 14642448
08:36:47 12441 8192000000 10256837648 10665758720 1164406896 12.445 100 62 124 1 1354105520 175578176 14120448
08:38:04 6137 8192000000 10292991104 10702635008 1179282880 12.584 100 71 142 1 1398220352 203263648 15673824
08:39:14 6343 8192000000 10284940816 10695553024 1158960032 12.394 99 72 144 2 1380188976 205392640 15836304
08:40:24 4032 8192000000 10296168624 10700947456 1160518656 12.408 99 80 156 3 1385876224 207301664 18055904
08:41:33 2844 8192000000 10313829600 10712576000 1168067200 12.479 100 77 154 1 1412475136 226615888 17792048
08:42:39 1463 8192000000 10350653680 10754027520 1176885520 12.561 100 85 168 1 1447066064 251595792 18584752
08:43:45 3398 8192000000 10395445632 10812907520 1178843440 12.579 100 80 159 0 1500706208 303105040 18757728
08:44:52 1730 8192000000 10429815456 10838970368 1168751488 12.485 100 83 167 0 1525924992 337025696 20147808
08:45:58 12337 8192000000 10347924640 10808922112 1165141024 12.451 99 49 98 0 1460991424 283999248 11851152
08:47:18 14494 8192000000 10343420208 10797244416 1162570640 12.427 99 30 60 1 1430411856 260035392 7805824
08:48:38 9894 8192000000 10394710560 10858921984 1175900064 12.552 100 40 80 1 1505774192 319630160 10243968
08:49:58 10979 8192000000 10443891824 10889216000 1159332320 12.397 99 37 74 0 1544505280 374891920 10281040
08:51:44 16654 8192000000 10426586480 10946891776 1144361328 12.257 99 24 48 2 1516583280 365899104 6322848
08:53:04 36833 8192000000 10180294064 10665553920 1093625376 11.777 98 15 30 1 1350279840 252437424 4217040
08:54:42 50440 8192000000 10083016192 10502668288 1089351696 11.736 97 35 69 1 1129999872 32727152 7921024
08:56:35 185429 8192000000 10144456400 10665160704 1108545200 11.919 98 29 58 0 1249756272 134544208 6666864
09:00:43 72246 8192000000 10071498096 10570289152 1113655616 11.967 99 9 18 0 1159551408 44596880 1298912
09:03:08 48668 8192000000 10051886608 10517995520 1108402064 11.917 99 18 36 0 1145934096 33729952 3802080
09:04:57 81112 8192000000 10067817760 10518978560 1126717872 12.090 100 10 20 2 1165935408 37168896 2048640
09:07:27 86214 8192000000 10083198880 10550702080 1070247312 11.554 95 7 14 0 1120334112 48865984 1220816
09:09:55 40347 8192000000 10115596672 10540552192 1120765232 12.034 99 40 80 1 1177846784 48634640 8446912
09:11:37 56165 8192000000 10132340704 10588987392 1140901680 12.224 100 25 50 0 1210177792 63846432 5429680
09:13:34 87898 8192000000 10072492768 10557067264 1116422192 11.993 98 14 28 0 1201574336 82314144 2838000
09:16:16 20922 8192000000 10103497104 10531651584 1135996144 12.178 99 38 75 2 1183228688 39905008 7327536
09:17:42 53898 8192000000 10094763488 10543489024 1122671120 12.052 98 24 49 1 1157904368 29870432 5362816
09:19:36 102256 8192000000 10092553584 10575396864 1146695184 12.278 100 24 48 1 1198809552 46971760 5142608
09:22:53 53373 8192000000 10110176400 10603458560 1148303424 12.294 100 8 16 0 1205638832 55662848 1672560
09:24:48 50307 8192000000 10081862208 10570301440 1138057264 12.197 99 8 16 1 1179735904 40141232 1537408
09:26:40 45671 8192000000 10090513712 10549403648 1103136848 11.867 95 27 54 1 1168821024 60291632 5392544
09:28:31 41401 8192000000 10135983824 10582474752 1096795328 11.807 95 27 54 0 1196234512 93278352 6160832
09:30:13 50427 8192000000 10208421328 10669576192 1152017392 12.328 100 14 28 1 1295944976 140168384 3759200
09:32:04 43773 8192000000 10105343248 10572627968 1131029456 12.131 99 17 34 2 1160505200 25830272 3645472
09:34:01 38594 8192000000 10153413408 10614104064 1154955280 12.356 100 38 76 1 1217297024 54833056 7508688
09:35:48 136011 8192000000 10074381904 10549039104 1093261632 11.774 95 11 22 1 1142078848 47077632 1739584
09:39:05 119251 8192000000 10062103664 10524516352 1098901664 11.827 97 31 62 1 1187169664 82106496 6161504
09:42:12 59146 8192000000 10050322992 10545516544 1111116240 11.943 99 10 20 1 1173785424 60139024 2530160
09:44:34 131489 8192000000 10083383088 10559795200 1031550112 11.183 92 14 28 0 1105256736 70407824 3298800
09:47:46 121870 8192000000 10054275952 10482475008 1063499648 11.490 95 15 30 1 1111191520 44695728 2996144
09:50:48 41707 8192000000 10110334608 10560208896 1108953792 11.923 99 38 76 0 1195176368 78372096 7850480
09:52:35 65200 8192000000 10076398608 10502434816 1110452416 11.937 99 34 65 1 1150616272 33997888 6165968
09:54:41 103554 8192000000 10068723568 10529865728 1073959664 11.590 96 19 38 1 1104024528 26567824 3497040
09:57:53 44125 8192000000 10023300400 10474127360 1103500096 11.871 99 5 10 0 1130344400 26244448 599856
09:59:37 50303 8192000000 10067663472 10528931840 1116221520 11.991 99 5 10 0 1144083216 26813520 1048176
10:01:30 93059 8192000000 10111975360 10600857600 1098042480 11.819 96 15 29 0 1208907488 107491344 3373664
10:04:03 62516 8192000000 10076065248 10528002048 1107386112 11.908 98 19 38 0 1150126944 38820976 3919856
10:06:07 29798 8192000000 10096891712 10531254272 1113752544 11.968 98 28 56 2 1162156368 42662640 5741184
10:07:47 115002 8192000000 10062995456 10527105024 1095538304 11.795 97 20 40 0 1146580032 46965776 4075952
10:10:42 65241 8192000000 10094132176 10588487680 1142864128 12.242 100 10 20 0 1186594672 41404144 2326400
10:12:49 137573 8192000000 10101360384 10574581760 1126426816 12.088 99 16 32 0 1202058112 72167904 3463392
10:16:16 40539 8192000000 10096663664 10630926336 1129526144 12.117 99 4 8 0 1204188880 73742192 920544
10:17:57 106865 8192000000 10083791168 10537758720 1098362640 11.822 96 18 36 0 1136730592 34337808 4030144
10:20:46 45902 8192000000 10122208768 10556776448 1142290064 12.237 100 33 63 1 1207583056 58061104 7231888
10:22:34 70412 8192000000 10163741280 10607550464 1107239376 11.906 96 26 52 1 1220447200 107858240 5349584
10:24:54 62140 8192000000 10253907600 10715615232 1144030816 12.253 100 29 58 1 1341281744 190379360 6871568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-01-2021 10:28:36 to 23-01-2021 10:28:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 154 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 23-01-2021 10:28:52 to 23-01-2021 10:28:54.

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          218751
MON$OLDEST_ACTIVE               227217
MON$OLDEST_SNAPSHOT             227217
MON$NEXT_TRANSACTION            227217
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                       2088096
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             784
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2337 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2337 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2337 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2337 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Sat Jan 23 10:27:13 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Sat Jan 23 10:27:35 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

23.01.21 10:28:55. 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-01-23 10:28:57 No severe PSQL-related problems occured
23.01.21 10:28:58. End of report.