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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16115956 kB
MemAvailable 30841216 kB
Buffers 0 kB
Cached 15570460 kB
SwapCached 4788 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-10-28 13:37:09
DTS_END                         2021-10-28 13:37:09.3030
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/36902
TRN_ID                          224064
ATT_ID                          629
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: 8595.78 26292 1031494
customer order: creation 160.79 453 19295
customer order: refuse 13.73 387 1648
order to supplier: creation 41.99 1673 5039
order to supplier: removal 3.27 126665 392
invoice (draft): creation 42.88 3941 5145
invoice (draft): removal 49.49 1117 5939
invoice accept: apply 38.86 18676 4663
invoice accept: cancel 43.99 1020 5279
customer reserve: creation 3581.78 182 429814
customer reserve: removal 4343.61 74 521233
realization accept: apply 77.48 217 9298
realization accept: cancel 77.64 132 9317
payment from customer: creation 55.46 147 6655
payment from customer: removal 5.22 107 626
payment to supplier: creation 45.72 194 5486
payment to supplier: removal 3.45 101 414
service: total inventory turnovers 3.84 3562 461
service: total monetary turnovers 5.63 632 675
service: aggregate perf. data 0.93 15402 111
service: refresh index statistics 0.03 351152 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2021 13:38:19 to 28-10-2021 13:38:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5463
TEST_TIME 2 3393
TEST_TIME 3 2707
TEST_TIME 4 3330
TEST_TIME 5 3508
TEST_TIME 6 3826
TEST_TIME 7 3817
TEST_TIME 8 4146
TEST_TIME 9 4154
TEST_TIME 10 4109
TEST_TIME 11 4195
TEST_TIME 12 4462
TEST_TIME 13 4796
TEST_TIME 14 5189
TEST_TIME 15 5306
TEST_TIME 16 6044
TEST_TIME 17 6314
TEST_TIME 18 6764
TEST_TIME 19 7393
TEST_TIME 20 7340
TEST_TIME 21 7338
TEST_TIME 22 7193
TEST_TIME 23 8468
TEST_TIME 24 8729
TEST_TIME 25 8932
TEST_TIME 26 9215
TEST_TIME 27 9391
TEST_TIME 28 10205
TEST_TIME 29 10560
TEST_TIME 30 10241
TEST_TIME 31 10357
TEST_TIME 32 10251
TEST_TIME 33 10050
TEST_TIME 34 9924
TEST_TIME 35 9833
TEST_TIME 36 9648
TEST_TIME 37 9587
TEST_TIME 38 9430
TEST_TIME 39 9426
TEST_TIME 40 9350
TEST_TIME 41 9276
TEST_TIME 42 9189
TEST_TIME 43 9080
TEST_TIME 44 9004
TEST_TIME 45 8953
TEST_TIME 46 8892
TEST_TIME 47 8823
TEST_TIME 48 8838
TEST_TIME 49 8793
TEST_TIME 50 8894
TEST_TIME 51 8873
TEST_TIME 52 8910
TEST_TIME 53 8965
TEST_TIME 54 8990
TEST_TIME 55 8972
TEST_TIME 56 9002
TEST_TIME 57 8942
TEST_TIME 58 8928
TEST_TIME 59 9027
TEST_TIME 60 9014
TEST_TIME 61 9113
TEST_TIME 62 9106
TEST_TIME 63 9043
TEST_TIME 64 8911
TEST_TIME 65 8851
TEST_TIME 66 8837
TEST_TIME 67 8789
TEST_TIME 68 8754
TEST_TIME 69 8721
TEST_TIME 70 8675
TEST_TIME 71 8660
TEST_TIME 72 8662
TEST_TIME 73 8611
TEST_TIME 74 8603
TEST_TIME 75 8610
TEST_TIME 76 8578
TEST_TIME 77 8690
TEST_TIME 78 8722
TEST_TIME 79 8717
TEST_TIME 80 8689
TEST_TIME 81 8769
TEST_TIME 82 8735
TEST_TIME 83 8801
TEST_TIME 84 8764
TEST_TIME 85 8670
TEST_TIME 86 8680
TEST_TIME 87 8731
TEST_TIME 88 8674
TEST_TIME 89 8655
TEST_TIME 90 8636
TEST_TIME 91 8648
TEST_TIME 92 8665
TEST_TIME 93 8689
TEST_TIME 94 8697
TEST_TIME 95 8692
TEST_TIME 96 8684
TEST_TIME 97 8790
TEST_TIME 98 8757
TEST_TIME 99 8821
TEST_TIME 100 8845
TEST_TIME 101 8867
TEST_TIME 102 8879
TEST_TIME 103 8927
TEST_TIME 104 8899
TEST_TIME 105 8868
TEST_TIME 106 8814
TEST_TIME 107 8835
TEST_TIME 108 8828
TEST_TIME 109 8788
TEST_TIME 110 8774
TEST_TIME 111 8751
TEST_TIME 112 8723
TEST_TIME 113 8695
TEST_TIME 114 8706
TEST_TIME 115 8660
TEST_TIME 116 8627
TEST_TIME 117 8642
TEST_TIME 118 8670
TEST_TIME 119 8670
TEST_TIME 120 8601

Performance per minute, chart


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

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 1427884 1427884 0 0.00 0 34599 7 0 0
doc_list_biud 1427884 1427884 0 0.00 0 45351 35 0 0
sp_add_invoice_to_stock 4780 4663 117 2.44 28 168255 18677 0 117
sp_cancel_adding_invoice 5279 5279 0 0.00 4 88296 1020 0 0
sp_cancel_client_order 1648 1648 0 0.00 6 29128 387 0 0
sp_cancel_customer_reserve 521233 521233 0 0.00 1 37215 74 0 0
sp_cancel_pay_from_customer 626 626 0 0.00 3 6425 108 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 4887 102 0 0
sp_cancel_supplier_invoice 5951 5939 12 0.20 2 42588 1118 0 12
sp_cancel_supplier_order 392 392 0 0.00 6 1302431 126665 0 0
sp_cancel_write_off 9317 9317 0 0.00 1 18901 133 0 0
sp_client_order 19295 19295 0 0.00 4 39707 454 0 0
sp_customer_reserve 429814 429814 0 0.00 0 55964 182 0 0
sp_fill_shopping_cart 29474 29474 0 0.00 1 21574 56 0 0
sp_fill_shopping_cart_clo_res 429803 429803 0 0.00 0 15433 18 0 0
sp_get_clo_for_invoice 4652 4652 0 0.00 0 34194 440 0 0
sp_kill_cost_storno 15636 15636 0 0.00 0 17690 75 0 0
sp_kill_qstorno_ret_qs2qd 1058114 1058114 0 0.00 0 45343 41 0 0
sp_kill_qty_storno 557757 557757 0 0.00 0 45344 88 0 0
sp_lock_selected_doc 19414 19414 0 0.00 0 7794 7 0 0
sp_make_cost_storno 26090 26090 0 0.00 0 34245 79 0 0
sp_multiply_rows_for_pdistr 26090 26090 0 0.00 0 26717 44 0 0
sp_multiply_rows_for_qdistr 19295 19295 0 0.00 0 39656 228 0 0
sp_pay_from_customer 6655 6655 0 0.00 4 26732 148 0 0
sp_pay_to_supplier 5486 5486 0 0.00 5 26792 195 0 0
sp_payment_common 12141 12141 0 0.00 1 16673 30 0 0
sp_qd_handle_on_cancel_clo 1648 1648 0 0.00 0 29120 348 0 0
sp_qd_handle_on_invoice_upd_sts 9930 9930 0 0.00 0 32842 431 0 0
sp_qd_handle_on_reserve_upd_sts 9298 9298 0 0.00 0 18365 60 0 0
sp_reserve_write_off 9298 9298 0 0.00 7 20322 217 0 0
sp_supplier_invoice 5145 5145 0 0.00 6 96058 3942 0 0
sp_supplier_order 5039 5039 0 0.00 5 61748 1674 0 0
srv_aggregate_perf_data 111 111 0 0.00 2636 246798 15403 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1233 154678 45814 0 0
srv_find_qd_qs_mism 986238 986238 0 0.00 0 20225 5 0 0
srv_make_invnt_saldo 461 461 0 0.00 48 160653 3563 0 0
srv_make_money_saldo 675 675 0 0.00 1 22483 632 0 0
srv_recalc_idx_stat 4 4 0 0.00 339126 358794 351153 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4663 4663 0 0.00 0 13370 202 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 5992 136 0 0
t$perf-norm:sp_cancel_client_order 1648 1648 0 0.00 0 6717 14 0 0
t$perf-norm:sp_cancel_customer_reserve 619 619 0 0.00 0 3511 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 626 626 0 0.00 0 547 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 667 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 1222 20 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 29002 1727 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 13315 29 0 0
t$perf-norm:sp_client_order 19295 19295 0 0.00 0 22515 25 0 0
t$perf-norm:sp_customer_reserve 940 940 0 0.00 0 3095 6 0 0
t$perf-norm:sp_pay_from_customer 6655 6655 0 0.00 0 10112 15 0 0
t$perf-norm:sp_pay_to_supplier 5486 5486 0 0.00 0 23700 16 0 0
t$perf-norm:sp_reserve_write_off 9298 9298 0 0.00 0 15126 14 0 0
t$perf-norm:sp_supplier_invoice 5145 5145 0 0.00 0 10606 28 0 0
t$perf-norm:sp_supplier_order 5039 5039 0 0.00 0 4499 16 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 909 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 41 1 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 12403 32 0 0
t$perf-norm:srv_make_money_saldo 675 675 0 0.00 0 9723 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 354 90 0 0
v_add_invoice_to_stock 9536 9302 234 2.45 0 11354 18 0 234
v_all_customers 35414 35414 0 0.00 0 21 1 0 0
v_all_suppliers 28086 28086 0 0.00 0 25 1 0 0
v_all_wares 38590 38590 0 0.00 0 76 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 959 7 0 0
v_cancel_client_order 3296 3296 0 0.00 0 3299 7 0 0
v_cancel_customer_prepayment 1252 1252 0 0.00 0 1824 7 0 0
v_cancel_customer_reserve 1238 1238 0 0.00 0 2477 6 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 2584 10 0 0
v_cancel_supplier_invoice 800 776 24 3.00 0 1426 15 0 24
v_cancel_supplier_order 784 784 0 0.00 0 1931 8 0 0
v_cancel_write_off 1964 1964 0 0.00 0 1395 14 0 0
v_max_id_clo_ord 5039 5039 0 0.00 0 1196 2 0 0
v_max_id_clo_res 940 940 0 0.00 0 3642 11 0 0
v_max_id_ord_sup 5140 5140 0 0.00 0 15172 6 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 9667 13 0 0
v_max_non_paid_realizn 3426 3426 0 0.00 0 16661 8 0 0
v_min_id_clo_ord 5039 5039 0 0.00 0 17988 16 0 0
v_min_id_clo_res 940 940 0 0.00 0 11249 22 0 0
v_min_id_ord_sup 5140 5140 0 0.00 0 6762 9 0 0
v_min_non_paid_invoice 5486 5486 0 0.00 0 5562 17 0 0
v_min_non_paid_realizn 6655 6655 0 0.00 0 16667 21 0 0
v_our_firm 9634 9634 0 0.00 0 19 1 0 0
v_reserve_write_off 18596 18596 0 0.00 0 6394 6 0 0
x_lock_depdocs_on_canc_invoice 10830 10830 0 0.00 0 26408 130 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 16239 438 0 0
x_make_qstorno_1000_1200 5039 5039 0 0.00 2 61736 1575 0 0
x_make_qstorno_1200_2000 5145 5145 0 0.00 3 95437 3726 0 0
x_make_qstorno_2100_3300 429814 429814 0 0.00 0 49594 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2021 13:38:27 to 28-10-2021 13:38:31.

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
11:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 31652 8192000000 9724563152 10019340288 799045632 8.887 100 84 168 0 827106720 7276480 20784608
11:09:51 11657 8192000000 9753894160 10057818112 828855232 9.188 100 84 168 0 860968800 11958912 20154656
11:11:06 22525 8192000000 9796346048 10109538304 864137488 9.542 100 86 172 1 901510560 17641840 19731232
11:12:35 14220 8192000000 9847886528 10175000576 899868592 9.897 100 83 166 3 959836256 41759184 18208480
11:13:53 11443 8192000000 9902286544 10246422528 938599424 10.279 100 81 160 0 1015244560 59709536 16935600
11:15:09 5352 8192000000 9927630208 10276995072 975157872 10.637 100 82 163 2 1048213296 56149520 16905904
11:16:16 4832 8192000000 9992463072 10350170112 1006574304 10.942 100 89 178 1 1111674368 86721056 18379008
11:17:23 3785 8192000000 10035991776 10398973952 1033738144 11.204 100 93 187 1 1142293728 89547632 19007952
11:18:28 1667 8192000000 10027025008 10396913664 1052889200 11.388 100 87 174 0 1145001952 73423872 18688880
11:19:32 1383 8192000000 10040865760 10412511232 1072151088 11.573 100 89 178 0 1154118176 63739104 18227984
11:20:34 6667 8192000000 10008646384 10404933632 1078208448 11.630 100 37 74 0 1120839200 35054448 7576304
11:21:41 7362 8192000000 9989265040 10390007808 1084598016 11.691 100 40 77 2 1115765360 23509984 7657360
11:22:51 7023 8192000000 10003132032 10407510016 1088954752 11.733 100 33 64 0 1121256768 25453040 6848976
11:24:02 22861 8192000000 10008437904 10403299328 1091394400 11.756 100 56 111 1 1120600032 15923008 13282624
11:25:27 3897 8192000000 10009670352 10409836544 1098641856 11.825 100 51 103 2 1127233392 16292240 12299296
11:26:32 8816 8192000000 10030447872 10441756672 1107626800 11.910 100 37 75 0 1142160960 26410256 8123904
11:27:44 54750 8192000000 10010290976 10423275520 1048095552 11.342 95 34 64 1 1079115344 23795376 7224416
11:29:40 2904 8192000000 9997996528 10390024192 1064889200 11.503 98 57 115 2 1100082416 21522528 13670688
11:30:46 5650 8192000000 9938547664 10332524544 1007639360 10.953 97 20 38 2 1033173376 21244192 4289824
11:31:54 9950 8192000000 9948666304 10332913664 1005799008 10.935 97 28 56 0 1033806448 21907104 6100336
11:33:05 33683 8192000000 9922779952 10303303680 973258128 10.618 94 36 72 2 994575536 12808304 8509104
11:34:39 5446 8192000000 9958403184 10344009728 1038246592 11.248 100 47 93 2 1069260272 20564128 10449552
11:35:49 8327 8192000000 9972501344 10378338304 1058718272 11.444 100 29 58 0 1088831776 24063280 6050224
11:37:01 7613 8192000000 9988360880 10385633280 1075502528 11.605 100 36 71 1 1110600080 26954304 8143248
11:38:15 84806 8192000000 10047370688 10456371200 1103504000 11.871 100 35 70 3 1156295712 45177936 7613776
11:41:01 58125 8192000000 10119367840 10533339136 1120732032 12.034 100 36 72 2 1224978560 96649696 7596832
11:43:01 28258 8192000000 10187948240 10595155968 1147518112 12.286 100 49 96 2 1279842144 121938768 10385264
11:44:34 8743 8192000000 10217598272 10618953728 1162454880 12.426 100 60 120 0 1322117392 146778048 12884464
11:45:46 8980 8192000000 10270015504 10680168448 1167014384 12.469 100 57 113 1 1378691696 199231840 12445472
11:47:05 8857 8192000000 10305079264 10710708224 1178913280 12.580 100 50 100 0 1410607632 219949104 11745248
11:48:20 10532 8192000000 10331957120 10746966016 1189875584 12.682 100 55 109 0 1438982112 235752640 13353888
11:49:39 6388 8192000000 10405694672 10818752512 1195114944 12.731 100 79 156 1 1503894208 291248336 17530928
11:50:50 3753 8192000000 10454474864 10865201152 1199283440 12.770 100 76 151 0 1558181392 341550848 17347104
11:52:00 3138 8192000000 10448965568 10864377856 1185341936 12.640 100 79 159 1 1552309696 348638272 18329488
11:53:09 2508 8192000000 10466767104 10888478720 1179803472 12.588 100 93 186 0 1573481472 371839552 21838448
11:54:15 2582 8192000000 10449903168 10872631296 1169285616 12.490 100 77 154 1 1549940448 362528368 18126464
11:55:24 3390 8192000000 10405096704 10819043328 1156736912 12.373 99 67 129 4 1504701120 333433648 14530560
11:56:31 1226 8192000000 10456701248 10858008576 1162887024 12.430 100 78 155 1 1550907232 370343360 17676848
11:57:39 8241 8192000000 10409884800 10825904128 1145014048 12.263 99 48 96 2 1505166512 348292192 11860272
11:58:53 8218 8192000000 10437457808 10862247936 1123491888 12.060 99 51 102 1 1535132000 398220368 13419744
12:00:07 7542 8192000000 10501311536 10926768128 1136984688 12.187 100 62 125 0 1607333184 454857248 15491248
12:01:23 10670 8192000000 10374949088 10839822336 1156797584 12.373 100 47 94 0 1468042384 300122416 11122384
12:02:45 12714 8192000000 10347034864 10803412992 1123284640 12.058 100 33 66 1 1449428560 317790688 8353232
12:04:09 19410 8192000000 10338893712 10786648064 1119728000 12.024 100 29 58 1 1430321984 303295008 7298976
12:05:39 9477 8192000000 10201810640 10669056000 1108403056 11.917 99 39 78 1 1324940880 207413456 9124368
12:06:56 54338 8192000000 10081881376 10508300288 1110649616 11.939 100 53 107 3 1178541600 55488016 12403968
12:08:52 17075 8192000000 10052284976 10478219264 1123005840 12.055 100 37 74 0 1158279120 27162992 8110288
12:10:23 99418 8192000000 10068329648 10530177024 1134150608 12.160 100 12 23 1 1165797568 29387744 2259216
12:13:09 48411 8192000000 10085530368 10547847168 1122866592 12.054 99 11 22 0 1172101952 47006320 2229040
12:14:58 102105 8192000000 10098593968 10574942208 1131078464 12.132 99 32 65 0 1208942960 70885232 6979264
12:18:07 44986 8192000000 10070421744 10502729728 1132251520 12.143 99 51 100 1 1162342704 19405760 10685424
12:19:57 54878 8192000000 10095090432 10554732544 1142529712 12.239 100 17 35 0 1204792864 58462912 3800240
12:21:52 41919 8192000000 10137879312 10582044672 1152249808 12.331 100 21 42 1 1232466240 75620880 4595552
12:23:35 52157 8192000000 10158575344 10613485568 1136930912 12.187 98 16 31 0 1254117744 114106480 3080352
12:25:58 63615 8192000000 10100516608 10600722432 1144908208 12.262 100 16 32 0 1268378144 119575952 3893984
12:28:07 59265 8192000000 10093924848 10603438080 1114788480 11.978 97 10 20 1 1172051936 55418432 1845024
12:30:17 35771 8192000000 10064928480 10512875520 1127451920 12.097 99 36 72 0 1175724096 40887552 7384624
12:31:55 39645 8192000000 10092399264 10539016192 1124701664 12.071 98 23 46 1 1164938704 35603264 4633776
12:33:41 75310 8192000000 10120914384 10557980672 1124951136 12.074 98 22 44 0 1164990800 34729216 5310448
12:35:56 54983 8192000000 10090200800 10567315456 1123828480 12.063 98 31 62 2 1199797536 69560208 6408848
12:37:53 99824 8192000000 10099417136 10562252800 1118153376 12.010 97 22 44 0 1179147504 56828368 4165760
12:40:33 102544 8192000000 10069559216 10500968448 1118736880 12.015 98 25 47 1 1163938704 40727344 4474480
12:43:20 12236 8192000000 10060714336 10522718208 1132253360 12.143 100 32 64 0 1161057712 21793296 7011056
12:44:40 54483 8192000000 10076817488 10552000512 1143679792 12.250 100 4 8 0 1177171696 32573776 918128
12:46:35 37089 8192000000 10069712832 10531631104 1117952496 12.008 99 20 41 1 1163002784 40566992 4483296
12:48:13 46318 8192000000 10028633424 10495172608 1091991696 11.762 98 10 19 1 1140546160 46547136 2007328
12:50:01 33278 8192000000 10058697712 10481602560 1112531488 11.956 98 31 62 1 1144080624 24685040 6864096
12:51:35 54551 8192000000 10103800944 10541637632 1068775696 11.540 95 21 42 0 1148346544 74955488 4615360
12:53:47 64441 8192000000 10048620064 10495569920 1071286624 11.564 96 17 34 0 1167672064 92369424 4016016
12:55:54 52187 8192000000 10041411824 10488483840 1080142288 11.649 96 22 44 0 1117371296 31773296 5455712
12:57:48 45364 8192000000 10099401360 10555252736 1112436592 11.955 99 30 60 0 1199781456 81348704 5996160
12:59:44 49334 8192000000 10093330736 10585141248 1123560384 12.061 100 27 54 0 1222151104 92891872 5698848
13:01:39 154677 8192000000 10113823440 10587652096 1141896976 12.233 100 21 42 0 1220849536 74270944 4681616
13:05:14 38327 8192000000 10059780544 10592378880 1121828112 12.044 99 2 4 0 1170915920 48726512 361296
13:07:12 53652 8192000000 10090876944 10537361408 1133750256 12.157 100 17 33 1 1184837952 47415376 3672320
13:09:07 79158 8192000000 10064671904 10544295936 1133288240 12.152 99 14 28 0 1195201024 59128528 2784256
13:11:29 73751 8192000000 10134363872 10587414528 1127459632 12.097 98 26 53 1 1175993200 42505024 6028544
13:13:43 77428 8192000000 10084860624 10535596032 1133524224 12.155 98 11 22 1 1217953856 81890192 2539440
13:16:03 61505 8192000000 10113172992 10580357120 1146081968 12.273 100 34 68 1 1231115488 77773936 7259584
13:18:09 84866 8192000000 10087456496 10523709440 1108870240 11.922 96 19 38 0 1155452944 42651328 3931376
13:20:35 107565 8192000000 10088480032 10584834048 1130087568 12.122 99 18 36 2 1253900912 119690288 4123056
13:23:34 38719 8192000000 10129013424 10560921600 1153998320 12.347 100 43 86 1 1211091600 48385824 8707456
13:25:13 58191 8192000000 10056388096 10530975744 1127033872 12.093 98 4 8 0 1157817264 30053520 729872
13:27:46 75287 8192000000 10084728144 10544844800 1142305584 12.237 99 18 36 0 1182954144 36713872 3934688
13:30:01 50210 8192000000 10090051216 10544271360 1133343136 12.153 99 19 38 0 1191559984 54340032 3876816
13:32:02 72151 8192000000 10064365600 10526568448 1129699664 12.119 99 9 18 0 1150370496 18946832 1724000
13:34:24 72423 8192000000 10055780576 10522791936 1105295568 11.888 97 16 32 0 1165758976 57469136 2994272
13:36:40 30981 8192000000 9568832960 9930301440 647741648 7.327 58 15 29 1 682595520 31563776 3290096

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-10-2021 13:38:33 to 28-10-2021 13:38:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-10-2021 13:38:49 to 28-10-2021 13:38:51.

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          224063
MON$OLDEST_ACTIVE               225357
MON$OLDEST_SNAPSHOT             225357
MON$NEXT_TRANSACTION            225357
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                       2032672
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2646 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2646 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2646 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2646 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Thu Oct 28 13:37:14 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Thu Oct 28 13:37:15 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Thu Oct 28 13:37:19 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

28.10.21 13:38:52. 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-10-28 13:38:54 No severe PSQL-related problems occured
28.10.21 13:38:55. End of report.