Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.2021 13: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 16657000 kB
MemAvailable 31267032 kB
Buffers 0 kB
Cached 14889480 kB
SwapCached 4864 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-06 13:26:53
DTS_END                         2021-01-06 13:26:53.7850
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/45316
TRN_ID                          240842
ATT_ID                          622
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: 8860.19 21548 1063223
customer order: creation 180.86 424 21703
customer order: refuse 15.90 305 1908
order to supplier: creation 47.11 1393 5653
order to supplier: removal 3.33 112223 399
invoice (draft): creation 47.81 3342 5737
invoice (draft): removal 48.74 922 5849
invoice accept: apply 42.81 17574 5137
invoice accept: cancel 43.65 933 5238
customer reserve: creation 3925.81 173 471097
customer reserve: removal 4211.91 71 505429
realization accept: apply 87.24 169 10469
realization accept: cancel 72.91 119 8749
payment from customer: creation 61.81 113 7417
payment from customer: removal 5.50 73 660
payment to supplier: creation 49.59 121 5951
payment to supplier: removal 3.56 66 427
service: total inventory turnovers 4.01 3250 481
service: total monetary turnovers 6.67 440 800
service: aggregate perf. data 0.96 12483 115
service: refresh index statistics 0.03 276773 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-01-2021 13:27:45 to 06-01-2021 13:27:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3956
TEST_TIME 2 3858
TEST_TIME 3 4293
TEST_TIME 4 4878
TEST_TIME 5 4982
TEST_TIME 6 4672
TEST_TIME 7 4983
TEST_TIME 8 4994
TEST_TIME 9 5417
TEST_TIME 10 5893
TEST_TIME 11 6215
TEST_TIME 12 6115
TEST_TIME 13 6266
TEST_TIME 14 6666
TEST_TIME 15 7021
TEST_TIME 16 7042
TEST_TIME 17 7633
TEST_TIME 18 7628
TEST_TIME 19 7640
TEST_TIME 20 8008
TEST_TIME 21 8187
TEST_TIME 22 9265
TEST_TIME 23 9443
TEST_TIME 24 9315
TEST_TIME 25 9885
TEST_TIME 26 10610
TEST_TIME 27 10482
TEST_TIME 28 10389
TEST_TIME 29 10504
TEST_TIME 30 10421
TEST_TIME 31 10327
TEST_TIME 32 10169
TEST_TIME 33 9965
TEST_TIME 34 9870
TEST_TIME 35 9812
TEST_TIME 36 9700
TEST_TIME 37 9717
TEST_TIME 38 9612
TEST_TIME 39 9537
TEST_TIME 40 9389
TEST_TIME 41 9301
TEST_TIME 42 9246
TEST_TIME 43 9534
TEST_TIME 44 9616
TEST_TIME 45 9626
TEST_TIME 46 9567
TEST_TIME 47 9500
TEST_TIME 48 9556
TEST_TIME 49 9554
TEST_TIME 50 9620
TEST_TIME 51 9599
TEST_TIME 52 9635
TEST_TIME 53 9599
TEST_TIME 54 9646
TEST_TIME 55 9583
TEST_TIME 56 9417
TEST_TIME 57 9314
TEST_TIME 58 9285
TEST_TIME 59 9256
TEST_TIME 60 9183
TEST_TIME 61 9092
TEST_TIME 62 9078
TEST_TIME 63 9020
TEST_TIME 64 9076
TEST_TIME 65 9010
TEST_TIME 66 9079
TEST_TIME 67 9178
TEST_TIME 68 9299
TEST_TIME 69 9267
TEST_TIME 70 9258
TEST_TIME 71 9289
TEST_TIME 72 9250
TEST_TIME 73 9309
TEST_TIME 74 9326
TEST_TIME 75 9269
TEST_TIME 76 9161
TEST_TIME 77 9125
TEST_TIME 78 9066
TEST_TIME 79 9002
TEST_TIME 80 8972
TEST_TIME 81 8937
TEST_TIME 82 8951
TEST_TIME 83 8943
TEST_TIME 84 8994
TEST_TIME 85 9001
TEST_TIME 86 8984
TEST_TIME 87 9019
TEST_TIME 88 9067
TEST_TIME 89 9109
TEST_TIME 90 9139
TEST_TIME 91 9103
TEST_TIME 92 9021
TEST_TIME 93 8937
TEST_TIME 94 8904
TEST_TIME 95 8957
TEST_TIME 96 8921
TEST_TIME 97 8887
TEST_TIME 98 8857
TEST_TIME 99 8842
TEST_TIME 100 8861
TEST_TIME 101 8886
TEST_TIME 102 8858
TEST_TIME 103 8862
TEST_TIME 104 8899
TEST_TIME 105 9059
TEST_TIME 106 9087
TEST_TIME 107 9054
TEST_TIME 108 9056
TEST_TIME 109 8991
TEST_TIME 110 8971
TEST_TIME 111 8920
TEST_TIME 112 8885
TEST_TIME 113 8839
TEST_TIME 114 8900
TEST_TIME 115 8866
TEST_TIME 116 8877
TEST_TIME 117 8875
TEST_TIME 118 8856
TEST_TIME 119 8886
TEST_TIME 120 8861

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-01-2021 13:27:49 to 06-01-2021 13:27: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 1499349 1499349 0 0.00 0 25999 7 0 0
doc_list_biud 1499349 1499349 0 0.00 0 86059 31 0 0
sp_add_invoice_to_stock 5238 5137 101 1.92 26 197196 17574 0 101
sp_cancel_adding_invoice 5238 5238 0 0.00 4 64179 933 0 0
sp_cancel_client_order 1908 1908 0 0.00 5 13299 305 0 0
sp_cancel_customer_reserve 505429 505429 0 0.00 1 86060 72 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 3500 74 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 1996 66 0 0
sp_cancel_supplier_invoice 5859 5849 10 0.17 2 24624 923 0 10
sp_cancel_supplier_order 399 399 0 0.00 8 1140767 112224 0 0
sp_cancel_write_off 8749 8749 0 0.00 1 9525 119 0 0
sp_client_order 21703 21703 0 0.00 4 13817 425 0 0
sp_customer_reserve 471097 471097 0 0.00 0 58193 173 0 0
sp_fill_shopping_cart 33081 33081 0 0.00 1 24378 42 0 0
sp_fill_shopping_cart_clo_res 471095 471095 0 0.00 0 32866 17 0 0
sp_get_clo_for_invoice 5135 5135 0 0.00 0 50651 427 0 0
sp_kill_cost_storno 15074 15074 0 0.00 0 9519 54 0 0
sp_kill_qstorno_ret_qs2qd 1025855 1025855 0 0.00 0 86056 39 0 0
sp_kill_qty_storno 543176 543176 0 0.00 0 86059 83 0 0
sp_lock_selected_doc 21612 21612 0 0.00 0 9457 6 0 0
sp_make_cost_storno 28972 28972 0 0.00 0 11663 49 0 0
sp_multiply_rows_for_pdistr 28972 28972 0 0.00 0 8474 30 0 0
sp_multiply_rows_for_qdistr 21703 21703 0 0.00 0 13047 251 0 0
sp_pay_from_customer 7417 7417 0 0.00 4 13311 114 0 0
sp_pay_to_supplier 5951 5951 0 0.00 5 12999 121 0 0
sp_payment_common 13368 13368 0 0.00 1 8461 19 0 0
sp_qd_handle_on_cancel_clo 1908 1908 0 0.00 0 13291 275 0 0
sp_qd_handle_on_invoice_upd_sts 10373 10373 0 0.00 0 28862 384 0 0
sp_qd_handle_on_reserve_upd_sts 10469 10469 0 0.00 0 21729 52 0 0
sp_reserve_write_off 10469 10469 0 0.00 5 21789 170 0 0
sp_supplier_invoice 5737 5737 0 0.00 6 88480 3343 0 0
sp_supplier_order 5653 5653 0 0.00 7 33359 1393 0 0
srv_aggregate_perf_data 115 115 0 0.00 2894 94059 12483 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1292 136648 45865 0 0
srv_find_qd_qs_mism 1014041 1014041 0 0.00 0 25999 6 0 0
srv_make_invnt_saldo 481 481 0 0.00 35 84996 3250 0 0
srv_make_money_saldo 800 800 0 0.00 1 12380 440 0 0
srv_recalc_idx_stat 4 4 0 0.00 63558 431829 276774 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5137 5137 0 0.00 0 9307 156 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 1 5033 92 0 0
t$perf-norm:sp_cancel_client_order 1908 1908 0 0.00 0 2511 11 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 6247 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 25950 45 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 1444 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 5744 23 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 30666 1394 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 2562 9 0 0
t$perf-norm:sp_client_order 21703 21703 0 0.00 0 27566 19 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 1061 5 0 0
t$perf-norm:sp_pay_from_customer 7417 7417 0 0.00 0 6883 10 0 0
t$perf-norm:sp_pay_to_supplier 5951 5951 0 0.00 0 5372 8 0 0
t$perf-norm:sp_reserve_write_off 10469 10469 0 0.00 0 4092 7 0 0
t$perf-norm:sp_supplier_invoice 5737 5737 0 0.00 0 7821 20 0 0
t$perf-norm:sp_supplier_order 5653 5653 0 0.00 0 4839 16 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 308 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 759 21 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 1933 12 0 0
t$perf-norm:srv_make_money_saldo 800 800 0 0.00 0 363 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10472 10270 202 1.92 0 5874 18 0 202
v_all_customers 39642 39642 0 0.00 0 74 1 0 0
v_all_suppliers 31082 31082 0 0.00 0 98 1 0 0
v_all_wares 43406 43406 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 1134 11 0 0
v_cancel_client_order 3816 3816 0 0.00 0 4760 7 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 3474 9 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 0 1042 4 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 1977 10 0 0
v_cancel_supplier_invoice 852 832 20 2.34 0 542 12 0 20
v_cancel_supplier_order 798 798 0 0.00 0 8084 14 0 0
v_cancel_write_off 2060 2060 0 0.00 0 2114 11 0 0
v_max_id_clo_ord 5650 5650 0 0.00 0 487 1 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 3904 24 0 0
v_max_id_ord_sup 5728 5728 0 0.00 0 4584 4 0 0
v_max_non_paid_invoice 1788 1788 0 0.00 0 339 2 0 0
v_max_non_paid_realizn 3903 3903 0 0.00 0 6522 5 0 0
v_min_id_clo_ord 5650 5650 0 0.00 0 4418 7 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 10071 17 0 0
v_min_id_ord_sup 5728 5728 0 0.00 0 8441 7 0 0
v_min_non_paid_invoice 5951 5951 0 0.00 0 8455 13 0 0
v_min_non_paid_realizn 7417 7417 0 0.00 0 5451 11 0 0
v_our_firm 10792 10792 0 0.00 0 67 1 0 0
v_reserve_write_off 20938 20938 0 0.00 0 4727 7 0 0
x_lock_depdocs_on_canc_invoice 10671 10671 0 0.00 0 11045 118 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 7614 287 0 0
x_make_qstorno_1000_1200 5653 5653 0 0.00 2 33348 1321 0 0
x_make_qstorno_1200_2000 5737 5737 0 0.00 3 88422 3173 0 0
x_make_qstorno_2100_3300 471097 471097 0 0.00 0 49853 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2021 13:27:53 to 06-01-2021 13:27: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
10:56:57 7 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:57:58 22754 8192000000 9711283360 10003431424 785763696 8.752 100 78 156 0 811873504 7098768 19011040
10:59:32 17215 8192000000 9749865216 10051059712 822547392 9.124 100 88 176 1 856760800 12934464 21278944
11:01:09 12171 8192000000 9812023200 10126839808 864246912 9.543 100 81 162 1 916727120 33584752 18895456
11:02:26 11230 8192000000 9859805840 10192982016 916666688 10.063 100 76 152 0 973050832 39820448 16563696
11:03:39 4477 8192000000 9932020624 10271625216 963099536 10.519 100 84 168 4 1037481392 57243680 17138176
11:04:45 6157 8192000000 9966432000 10319245312 995015776 10.830 100 79 158 1 1078946352 67563680 16366896
11:05:53 3397 8192000000 9988078832 10348146688 1025184912 11.122 100 86 172 2 1100751024 58795472 16770640
11:06:58 2112 8192000000 10034077216 10398048256 1049232688 11.353 100 94 189 1 1148234640 79820816 19181136
11:08:01 1360 8192000000 10048608736 10418147328 1062903168 11.484 100 86 172 0 1150144544 69918016 17323360
11:09:04 52003 8192000000 9980147872 10366607360 1070378304 11.556 100 45 91 1 1115599760 35347680 9873776
11:10:57 8947 8192000000 9976258096 10375090176 1077304064 11.622 100 30 60 2 1125820464 41976624 6539776
11:12:06 9394 8192000000 9994083328 10403659776 1083681104 11.683 100 20 40 2 1118719024 31084992 3952928
11:13:23 8606 8192000000 10021231056 10422824960 1093169440 11.773 100 33 68 3 1124464064 23915328 7379296
11:14:32 48120 8192000000 10022095344 10414604288 1101225424 11.849 100 46 94 2 1129221536 18057152 9938960
11:16:21 6477 8192000000 10023960320 10425671680 1098042224 11.819 100 38 72 4 1148318208 41390624 8885360
11:17:31 7990 8192000000 9979762784 10380296192 1047525440 11.337 97 27 54 3 1082093344 28708096 5859808
11:18:40 6688 8192000000 9979472016 10376278016 1047030192 11.332 98 28 54 1 1086797216 34261040 5505984
11:19:47 42897 8192000000 9875673232 10248978432 939258096 10.286 91 51 102 1 957349888 6265520 11826272
11:21:31 7228 8192000000 9911273824 10299654144 963418432 10.522 95 41 83 1 992446944 20111312 8917200
11:22:42 8435 8192000000 9934125040 10322448384 1020603232 11.078 100 28 56 1 1059178096 32215504 6359360
11:23:53 5181 8192000000 9949198464 10333159424 1041232112 11.277 100 44 90 2 1058627232 7433712 9961408
11:25:00 42027 8192000000 9952571200 10346737664 1047774528 11.339 100 22 44 1 1071620752 19177024 4669200
11:26:42 2980 8192000000 9994641344 10382839808 1073947872 11.590 100 52 104 1 1109174640 24537552 10689216
11:27:49 6613 8192000000 10027359872 10419724288 1088353152 11.727 100 56 112 1 1137195200 36907776 11934272
11:29:01 16510 8192000000 10071225280 10469851136 1100568016 11.843 100 60 120 1 1179267072 65960080 12738976
11:30:25 17479 8192000000 10091742784 10493992960 1116460656 11.994 100 53 103 4 1202149648 74398368 11290624
11:32:02 41208 8192000000 10113867120 10520424448 1129265200 12.114 100 40 79 1 1220547968 82867424 8415344
11:33:48 12733 8192000000 10143264000 10552045568 1138948672 12.206 100 48 96 2 1247481184 98506704 10025808
11:35:04 13388 8192000000 10179259280 10593914880 1145453120 12.267 100 47 94 3 1289862720 133409008 11000592
11:36:22 5732 8192000000 10220555536 10623053824 1159926096 12.403 100 76 152 2 1321916032 145689376 16300560
11:37:32 5702 8192000000 10188371808 10600542208 1159847280 12.402 100 52 104 1 1296358656 125056448 11454928
11:38:45 3176 8192000000 10262719216 10666733568 1164050768 12.441 100 73 145 0 1369234064 188269216 16914080
11:39:54 4273 8192000000 10286024272 10688925696 1158900128 12.393 100 69 138 0 1384445120 209984320 15560672
11:41:01 2174 8192000000 10293414928 10695929856 1167401152 12.473 100 80 158 3 1390120208 204070992 18648064
11:42:07 3024 8192000000 10329699008 10730995712 1171506352 12.511 100 79 159 2 1427300128 238208336 17585440
11:43:14 1276 8192000000 10363517456 10765119488 1155181296 12.358 100 86 167 2 1467334256 291990880 20162080
11:44:19 12472 8192000000 10320355792 10743918592 1122506912 12.051 98 34 68 1 1413777376 282835472 8434992
11:45:43 4831 8192000000 10395225920 10801061888 1121498400 12.041 99 63 124 1 1482188784 345286224 15404160
11:46:52 8926 8192000000 10418678624 10861596672 1112667360 11.958 98 47 94 1 1521461616 396366288 12427968
11:48:09 5709 8192000000 10420017248 10857828352 1132399792 12.144 100 55 110 1 1532367168 386597888 13369488
11:49:20 38083 8192000000 10249354000 10716680192 1129142864 12.113 100 20 40 1 1382118400 247735712 5239824
11:51:27 38455 8192000000 10151127056 10631864320 1095622016 11.796 99 12 24 0 1273839216 174889520 3327680
11:53:06 63730 8192000000 10026522784 10456637440 1049372016 11.355 95 7 14 0 1100310704 49648048 1290640
11:55:14 93756 8192000000 10080555104 10535075840 1075078720 11.601 97 12 23 2 1142228656 64035392 3114544
11:57:57 53103 8192000000 10096545472 10542542848 1109123024 11.924 99 31 62 1 1189682800 74393024 6166752
12:00:38 40826 8192000000 10050695264 10482339840 1111270720 11.944 99 31 60 3 1157828736 40601984 5956032
12:02:20 26289 8192000000 10090512368 10512306176 1110212304 11.934 98 44 84 2 1168929184 50208576 8508304
12:03:47 49919 8192000000 10115860112 10551250944 1135520000 12.173 100 18 37 0 1189991808 50575040 3896768
12:05:43 78383 8192000000 10195980416 10643279872 1139913824 12.215 99 20 40 0 1266632512 122052144 4666544
12:08:20 51892 8192000000 10190507264 10666655744 1144411184 12.257 100 31 63 1 1311843248 160027792 7404272
12:10:14 40494 8192000000 10111152240 10607017984 1146441024 12.276 100 8 16 0 1232159920 83767088 1951808
12:11:59 19672 8192000000 10088702384 10521567232 1148682192 12.297 100 45 90 1 1176479056 18162048 9634816
12:13:31 136647 8192000000 10108030000 10572189696 1096930704 11.809 95 14 28 0 1140560208 41252224 2377280
12:16:48 109326 8192000000 10091620080 10564861952 1125430832 12.078 98 14 28 1 1160323088 32571104 2321152
12:19:49 79991 8192000000 10093984736 10572341248 1123562496 12.061 98 11 22 0 1173852224 48095616 2194112
12:23:18 55457 8192000000 10039701232 10473930752 1059716048 11.454 93 36 71 2 1089506512 22528336 7262128
12:25:14 56843 8192000000 10041398352 10501550080 1116883472 11.998 99 11 22 0 1153704320 34817136 2003712
12:27:11 36890 8192000000 10031252288 10481172480 1097147008 11.811 98 7 14 0 1130374352 31903024 1324320
12:28:48 53167 8192000000 10113175264 10549706752 1105947920 11.894 98 18 36 1 1174733952 64990368 3795664
12:30:58 75331 8192000000 10140949696 10606641152 1106748848 11.902 97 22 44 0 1204319440 92276976 5293616
12:33:38 90442 8192000000 10044573408 10519105536 1044512096 11.308 93 17 34 2 1147032032 97757024 4762912
12:36:39 47520 8192000000 10073461696 10526449664 1101153264 11.849 98 40 80 1 1179652192 70555408 7943520
12:38:36 25164 8192000000 10092555376 10512273408 1100072736 11.838 99 52 104 1 1175165376 64591792 10500848
12:40:02 50535 8192000000 10026211408 10466938880 1073045024 11.581 98 29 58 1 1139616464 60880720 5690720
12:42:12 72018 8192000000 10045806656 10507157504 1105801120 11.893 100 14 28 1 1141453968 33087792 2565056
12:44:30 56650 8192000000 10047916480 10487435264 1100662592 11.844 99 7 14 0 1138235504 36045424 1527488
12:47:04 63028 8192000000 10085759952 10520375296 1096876416 11.808 98 25 49 2 1151337568 48483216 5977936
12:49:12 54393 8192000000 10102169408 10553331712 1103921344 11.875 98 24 48 2 1193216528 83522128 5773056
12:51:07 35577 8192000000 10087035312 10524868608 1112307936 11.954 99 32 64 1 1170291904 51013104 6970864
12:52:48 50817 8192000000 10077711296 10514632704 1118959280 12.017 99 22 44 0 1204286192 80570224 4756688
12:54:46 72333 8192000000 10052423216 10488545280 1103014320 11.866 98 22 45 1 1151296496 42734320 5547856
12:56:59 47691 8192000000 10080736512 10509770752 1142520960 12.239 100 37 74 0 1183482720 33628032 7333728
12:59:02 119469 8192000000 10097066704 10594979840 1136132032 12.179 99 11 22 1 1212534320 74074192 2328096
13:02:07 72939 8192000000 10081982240 10541219840 1099878048 11.836 96 10 20 0 1122368656 20582432 1908176
13:04:20 44734 8192000000 10149427312 10579869696 1144954448 12.262 99 28 55 2 1219684816 68086912 6643456
13:06:16 52471 8192000000 10170410416 10609172480 1136567776 12.183 98 27 54 1 1246415168 103317488 6529904
13:08:23 46831 8192000000 10201687472 10645331968 1133264128 12.152 98 38 76 4 1275047568 133145040 8638400
13:10:10 72052 8192000000 10105258000 10702675968 1151767232 12.326 100 9 18 1 1296041680 142228320 2046128
13:12:29 61517 8192000000 10071844320 10496389120 1056811728 11.426 92 19 38 1 1095416688 34938128 3666832
13:14:31 48947 8192000000 10079081840 10513858560 1101374192 11.851 97 46 90 4 1164188560 53389328 9425040
13:16:34 64350 8192000000 10033620656 10504019968 1098062848 11.819 97 15 30 2 1136678192 35131680 3483664
13:18:46 62796 8192000000 10113142560 10578726912 1117790080 12.006 99 16 32 0 1172964768 51792608 3382080
13:21:44 48459 8192000000 10073546320 10528358400 1077104928 11.620 96 21 40 2 1130857264 49361952 4390384
13:23:33 95902 8192000000 10066008512 10500268032 1052101600 11.381 93 18 36 3 1093086672 37779840 3205232
13:26:12 23403 8192000000 10064990576 10491351040 1116113824 11.990 100 27 52 1 1149879584 29314704 4451056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-01-2021 13:27:59 to 06-01-2021 13:28:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-01-2021 13:28:15 to 06-01-2021 13:28: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          238833
MON$OLDEST_ACTIVE               241623
MON$OLDEST_SNAPSHOT             241623
MON$NEXT_TRANSACTION            241623
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                       2090944
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             812
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2317 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2317 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2317 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.2317 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 ---
:60: 
:61: mscs-test-161.budzdorov.ru    Wed Jan  6 13:27:00 2021
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Wed Jan  6 13:27:00 2021
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:67: 
:68: 
:69: mscs-test-161.budzdorov.ru    Wed Jan  6 13:27:00 2021
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Wed Jan  6 13:27:00 2021
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Wed Jan  6 13:27:00 2021
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
--- end of diff output ---

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

06.01.21 13:28: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-01-06 13:28:20 No severe PSQL-related problems occured
06.01.21 13:28:22. End of report.