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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15703420 kB
MemAvailable 30737836 kB
Buffers 0 kB
Cached 16030472 kB
SwapCached 3828 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-09 10:37:07
DTS_END                         2021-10-09 10:37:07.4490
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/47714
TRN_ID                          238544
ATT_ID                          655
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: 9449.05 20309 1133886
customer order: creation 176.67 424 21200
customer order: refuse 14.53 354 1743
order to supplier: creation 45.73 1335 5487
order to supplier: removal 3.64 117141 437
invoice (draft): creation 46.81 3167 5617
invoice (draft): removal 53.62 920 6434
invoice accept: apply 41.93 18572 5031
invoice accept: cancel 49.05 936 5886
customer reserve: creation 3913.35 181 469602
customer reserve: removal 4798.29 71 575795
realization accept: apply 83.41 188 10009
realization accept: cancel 93.86 125 11263
payment from customer: creation 59.72 121 7166
payment from customer: removal 5.66 98 679
payment to supplier: creation 48.73 154 5847
payment to supplier: removal 3.63 83 436
service: total inventory turnovers 3.89 3664 467
service: total monetary turnovers 5.68 554 681
service: aggregate perf. data 0.84 16756 101
service: refresh index statistics 0.04 241334 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-10-2021 10:39:31 to 09-10-2021 10:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3773
TEST_TIME 2 3786
TEST_TIME 3 3313
TEST_TIME 4 3308
TEST_TIME 5 3635
TEST_TIME 6 4118
TEST_TIME 7 5122
TEST_TIME 8 5705
TEST_TIME 9 5988
TEST_TIME 10 6112
TEST_TIME 11 6594
TEST_TIME 12 6694
TEST_TIME 13 6888
TEST_TIME 14 7185
TEST_TIME 15 7799
TEST_TIME 16 8030
TEST_TIME 17 8387
TEST_TIME 18 8290
TEST_TIME 19 8403
TEST_TIME 20 8868
TEST_TIME 21 8791
TEST_TIME 22 8775
TEST_TIME 23 9144
TEST_TIME 24 9199
TEST_TIME 25 9181
TEST_TIME 26 9353
TEST_TIME 27 9707
TEST_TIME 28 11024
TEST_TIME 29 11470
TEST_TIME 30 11591
TEST_TIME 31 11562
TEST_TIME 32 11512
TEST_TIME 33 11326
TEST_TIME 34 11298
TEST_TIME 35 11070
TEST_TIME 36 10883
TEST_TIME 37 10604
TEST_TIME 38 10466
TEST_TIME 39 10438
TEST_TIME 40 10393
TEST_TIME 41 10351
TEST_TIME 42 10380
TEST_TIME 43 10303
TEST_TIME 44 10289
TEST_TIME 45 10145
TEST_TIME 46 10142
TEST_TIME 47 10021
TEST_TIME 48 10142
TEST_TIME 49 10239
TEST_TIME 50 10195
TEST_TIME 51 10133
TEST_TIME 52 10222
TEST_TIME 53 10183
TEST_TIME 54 10150
TEST_TIME 55 10097
TEST_TIME 56 10106
TEST_TIME 57 10188
TEST_TIME 58 10152
TEST_TIME 59 9997
TEST_TIME 60 9971
TEST_TIME 61 9880
TEST_TIME 62 9846
TEST_TIME 63 9840
TEST_TIME 64 9754
TEST_TIME 65 9753
TEST_TIME 66 9702
TEST_TIME 67 9687
TEST_TIME 68 9667
TEST_TIME 69 9656
TEST_TIME 70 9698
TEST_TIME 71 9624
TEST_TIME 72 9645
TEST_TIME 73 9682
TEST_TIME 74 9733
TEST_TIME 75 9986
TEST_TIME 76 9981
TEST_TIME 77 10038
TEST_TIME 78 9961
TEST_TIME 79 9883
TEST_TIME 80 9824
TEST_TIME 81 9800
TEST_TIME 82 9782
TEST_TIME 83 9746
TEST_TIME 84 9671
TEST_TIME 85 9602
TEST_TIME 86 9633
TEST_TIME 87 9640
TEST_TIME 88 9629
TEST_TIME 89 9641
TEST_TIME 90 9593
TEST_TIME 91 9652
TEST_TIME 92 9660
TEST_TIME 93 9706
TEST_TIME 94 9714
TEST_TIME 95 9716
TEST_TIME 96 9759
TEST_TIME 97 9812
TEST_TIME 98 9781
TEST_TIME 99 9726
TEST_TIME 100 9671
TEST_TIME 101 9625
TEST_TIME 102 9620
TEST_TIME 103 9590
TEST_TIME 104 9601
TEST_TIME 105 9567
TEST_TIME 106 9555
TEST_TIME 107 9531
TEST_TIME 108 9508
TEST_TIME 109 9531
TEST_TIME 110 9528
TEST_TIME 111 9548
TEST_TIME 112 9539
TEST_TIME 113 9555
TEST_TIME 114 9626
TEST_TIME 115 9618
TEST_TIME 116 9668
TEST_TIME 117 9590
TEST_TIME 118 9559
TEST_TIME 119 9518
TEST_TIME 120 9451

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-10-2021 10:39:34 to 09-10-2021 10:39:38.

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 1568491 1568491 0 0.00 0 19686 7 0 0
doc_list_biud 1568491 1568491 0 0.00 0 69210 32 0 0
sp_add_invoice_to_stock 5152 5031 121 2.34 43 180046 18573 0 121
sp_cancel_adding_invoice 5886 5886 0 0.00 5 100189 937 0 0
sp_cancel_client_order 1743 1743 0 0.00 5 18616 354 0 0
sp_cancel_customer_reserve 575795 575795 0 0.00 1 26866 72 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 6909 98 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 4322 84 0 0
sp_cancel_supplier_invoice 6445 6434 11 0.17 2 69220 920 0 11
sp_cancel_supplier_order 437 437 0 0.00 8 1161777 117141 0 0
sp_cancel_write_off 11263 11263 0 0.00 1 15815 125 0 0
sp_client_order 21200 21200 0 0.00 4 21910 424 0 0
sp_customer_reserve 469602 469602 0 0.00 0 93897 182 0 0
sp_fill_shopping_cart 32295 32295 0 0.00 1 14601 41 0 0
sp_fill_shopping_cart_clo_res 469600 469600 0 0.00 0 17949 17 0 0
sp_get_clo_for_invoice 5029 5029 0 0.00 0 21711 372 0 0
sp_kill_cost_storno 18264 18264 0 0.00 0 9429 60 0 0
sp_kill_qstorno_ret_qs2qd 1169724 1169724 0 0.00 0 69208 38 0 0
sp_kill_qty_storno 616596 616596 0 0.00 0 69210 81 0 0
sp_lock_selected_doc 20894 20894 0 0.00 0 7721 7 0 0
sp_make_cost_storno 28051 28051 0 0.00 0 13556 61 0 0
sp_multiply_rows_for_pdistr 28051 28051 0 0.00 0 19672 33 0 0
sp_multiply_rows_for_qdistr 21200 21200 0 0.00 0 17802 233 0 0
sp_pay_from_customer 7166 7166 0 0.00 4 14563 121 0 0
sp_pay_to_supplier 5847 5847 0 0.00 5 19690 154 0 0
sp_payment_common 13013 13013 0 0.00 1 8724 27 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 18602 316 0 0
sp_qd_handle_on_invoice_upd_sts 10915 10915 0 0.00 0 36209 352 0 0
sp_qd_handle_on_reserve_upd_sts 10009 10009 0 0.00 0 23308 61 0 0
sp_reserve_write_off 10009 10009 0 0.00 6 23429 189 0 0
sp_supplier_invoice 5617 5617 0 0.00 8 80929 3167 0 0
sp_supplier_order 5487 5487 0 0.00 5 75012 1335 0 0
srv_aggregate_perf_data 101 101 0 0.00 3058 182020 16757 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1335 150473 44161 0 0
srv_find_qd_qs_mism 1085485 1085485 0 0.00 0 9346 6 0 0
srv_make_invnt_saldo 467 467 0 0.00 27 84558 3665 0 0
srv_make_money_saldo 681 681 0 0.00 0 12389 555 0 0
srv_recalc_idx_stat 5 5 0 0.00 139213 392870 241334 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5031 5031 0 0.00 0 15558 181 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 1 2219 81 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 2408 9 0 0
t$perf-norm:sp_cancel_customer_reserve 749 749 0 0.00 0 8549 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 3880 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 319 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 1192 22 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 21279 1422 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 6000 15 0 0
t$perf-norm:sp_client_order 21200 21200 0 0.00 0 8517 17 0 0
t$perf-norm:sp_customer_reserve 1003 1003 0 0.00 0 2261 6 0 0
t$perf-norm:sp_pay_from_customer 7166 7166 0 0.00 0 6184 13 0 0
t$perf-norm:sp_pay_to_supplier 5847 5847 0 0.00 0 6072 9 0 0
t$perf-norm:sp_reserve_write_off 10009 10009 0 0.00 0 7005 11 0 0
t$perf-norm:sp_supplier_invoice 5618 5618 0 0.00 0 8077 19 0 0
t$perf-norm:sp_supplier_order 5487 5487 0 0.00 0 3317 13 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 294 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 422 11 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 474 6 0 0
t$perf-norm:srv_make_money_saldo 681 681 0 0.00 0 4627 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 121 26 0 0
v_add_invoice_to_stock 10300 10058 242 2.34 0 7486 17 0 242
v_all_customers 39158 39158 0 0.00 0 23 1 0 0
v_all_suppliers 30968 30968 0 0.00 0 38 1 0 0
v_all_wares 42400 42400 0 0.00 0 181 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 1859 9 0 0
v_cancel_client_order 3486 3486 0 0.00 0 2853 5 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 4440 12 0 0
v_cancel_customer_reserve 1498 1498 0 0.00 0 1211 6 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 3469 9 0 0
v_cancel_supplier_invoice 764 742 22 2.87 0 1078 15 0 22
v_cancel_supplier_order 874 874 0 0.00 0 1212 7 0 0
v_cancel_write_off 2040 2040 0 0.00 0 1160 13 0 0
v_max_id_clo_ord 5486 5486 0 0.00 0 1104 2 0 0
v_max_id_clo_res 1003 1003 0 0.00 0 4693 16 0 0
v_max_id_ord_sup 5609 5609 0 0.00 0 8330 9 0 0
v_max_non_paid_invoice 1458 1458 0 0.00 0 126 2 0 0
v_max_non_paid_realizn 3538 3538 0 0.00 0 2709 3 0 0
v_min_id_clo_ord 5486 5486 0 0.00 0 8868 9 0 0
v_min_id_clo_res 1003 1003 0 0.00 0 6105 18 0 0
v_min_id_ord_sup 5609 5609 0 0.00 0 4971 9 0 0
v_min_non_paid_invoice 5847 5847 0 0.00 0 8714 23 0 0
v_min_non_paid_realizn 7166 7166 0 0.00 0 7916 17 0 0
v_our_firm 10498 10498 0 0.00 0 16 1 0 0
v_reserve_write_off 20016 20016 0 0.00 0 3955 7 0 0
x_lock_depdocs_on_canc_invoice 11949 11949 0 0.00 0 16022 118 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 5071 299 0 0
x_make_qstorno_1000_1200 5487 5487 0 0.00 2 75004 1254 0 0
x_make_qstorno_1200_2000 5617 5617 0 0.00 4 77135 3017 0 0
x_make_qstorno_2100_3300 469602 469602 0 0.00 0 93895 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-10-2021 10:39:39 to 09-10-2021 10:39:43.

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
08:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:12 30392 8192000000 9735332128 10038661120 809934928 8.997 100 86 172 0 838486288 7887952 20663408
08:09:46 30145 8192000000 9781114592 10096693248 857439840 9.475 100 85 170 0 889850896 12780848 19630208
08:11:24 19440 8192000000 9834158192 10160197632 895459152 9.853 100 84 168 0 940805568 25920736 19425680
08:12:57 17600 8192000000 9911661824 10251091968 945024896 10.342 100 84 166 1 1013772352 51561488 17185968
08:14:17 8161 8192000000 9956780352 10306899968 976642256 10.651 100 78 156 1 1065589952 72965104 15982592
08:15:28 3280 8192000000 9980648448 10341330944 1010745072 10.983 100 71 142 0 1091302304 66540256 14016976
08:16:33 3100 8192000000 10012914592 10377039872 1027644272 11.146 100 86 172 1 1120219168 75541872 17033024
08:17:37 1580 8192000000 10027812832 10393214976 1054148032 11.400 100 96 189 2 1131104848 57385280 19571536
08:18:41 2358 8192000000 10057790864 10436898816 1066366496 11.517 100 67 134 3 1172584832 92940800 13277536
08:19:46 38510 8192000000 9975762384 10367655936 1073281840 11.583 100 33 66 1 1101431216 20327424 7821952
08:21:26 2416 8192000000 10009565840 10403561472 1079634384 11.644 100 65 129 3 1123388208 30019216 13734608
08:22:34 3194 8192000000 9999276736 10395152384 1090303552 11.746 100 56 111 0 1114957584 13251712 11402320
08:23:38 4175 8192000000 10000078784 10398801920 1095854128 11.798 100 49 102 1 1120486000 12932832 11699040
08:24:44 57263 8192000000 10007300672 10418876416 1103304624 11.869 100 39 78 1 1123963664 11427536 9231504
08:26:42 6053 8192000000 10000664400 10401828864 1073491408 11.585 98 47 93 1 1097321488 13622224 10207856
08:27:49 7250 8192000000 9995414112 10395701248 1071721344 11.569 99 52 104 1 1105418352 21821408 11875600
08:29:01 3925 8192000000 9957280912 10332594176 1026793936 11.138 98 62 125 1 1057742352 17171440 13776976
08:30:12 10454 8192000000 9946410576 10328285184 985532208 10.738 96 38 75 0 1029636720 36059248 8045264
08:31:32 5433 8192000000 9927554352 10304741376 985768720 10.740 97 35 68 1 1023897040 30563360 7564960
08:32:38 9188 8192000000 9960630224 10352328704 1029296592 11.162 99 27 54 0 1055775664 19948144 6530928
08:33:49 71474 8192000000 9980509440 10384412672 1048782336 11.349 100 41 82 1 1075591184 17513472 9295376
08:36:03 7437 8192000000 9990911712 10392776704 1065519936 11.509 100 42 84 0 1106894800 31067552 10307312
08:37:14 19444 8192000000 10009033456 10398613504 1087327552 11.717 100 46 92 1 1111135904 14207856 9600496
08:38:48 28693 8192000000 10055665184 10459529216 1105332080 11.888 100 52 101 0 1162819376 47357968 10129328
08:40:25 69658 8192000000 10088528528 10499178496 1125542736 12.079 100 37 74 0 1206343088 73098704 7701648
08:42:37 12854 8192000000 10154281776 10566074368 1137218896 12.189 100 57 115 0 1266851024 117675936 11956192
08:43:53 7087 8192000000 10202904896 10606194688 1142185904 12.236 100 68 134 3 1301159168 144421968 14551296
08:45:24 8911 8192000000 10195001856 10629754880 1159458160 12.398 100 58 114 0 1300443968 128365552 12620256
08:46:36 5084 8192000000 10230384464 10644852736 1173655696 12.531 100 65 128 0 1331809568 143880576 14273296
08:47:47 10717 8192000000 10269584320 10680705024 1170930256 12.506 100 62 123 1 1366265040 181688784 13646000
08:49:01 7117 8192000000 10262515184 10685063168 1169398800 12.491 100 72 143 0 1374119408 189128032 15592576
08:50:16 5332 8192000000 10305197344 10711543808 1161022768 12.413 99 71 142 4 1401601632 225053600 15525264
08:51:24 2942 8192000000 10304950384 10709835776 1152633712 12.334 100 80 158 1 1407697152 236921792 18141648
08:52:31 2769 8192000000 10275315776 10709692416 1145654608 12.269 99 73 146 1 1366707504 204079680 16973216
08:53:37 1334 8192000000 10303474096 10717609984 1145608832 12.268 100 78 154 1 1401971344 238841984 17520528
08:54:47 4055 8192000000 10308283008 10728595456 1153680480 12.344 100 69 138 0 1408791024 238968736 16141808
08:55:53 4377 8192000000 10329527024 10736668672 1160248752 12.406 100 74 148 1 1426229296 247926352 18054192
08:57:01 3512 8192000000 10345395760 10757644288 1154126816 12.348 100 75 150 0 1450172288 277367888 18677584
08:58:07 2557 8192000000 10333664416 10740125696 1148011344 12.291 100 70 137 2 1435384912 271022064 16351504
08:59:15 8224 8192000000 10419051248 10831536128 1156546896 12.371 100 51 100 2 1505701824 336634800 12520128
09:00:40 7863 8192000000 10429151312 10848423936 1141878784 12.233 100 40 79 1 1522821696 370630160 10312752
09:02:06 8710 8192000000 10447054032 10900946944 1146995568 12.281 100 30 60 0 1546822144 391983712 7842864
09:03:16 29562 8192000000 10495586192 10983854080 1141089536 12.226 100 30 60 1 1617255232 467909472 8256224
09:04:55 19382 8192000000 10425417120 10990071808 1113022176 11.961 100 19 38 0 1531134512 412463696 5648640
09:06:24 67324 8192000000 10073128768 10540654592 1073288640 11.583 96 8 16 0 1127438304 52772144 1377520
09:08:33 150445 8192000000 10043717200 10465710080 1045865936 11.321 94 13 26 0 1074394528 25942400 2586192
09:12:06 42287 8192000000 10089848112 10522640384 1128366784 12.106 100 31 61 2 1177655168 42918464 6369920
09:13:49 77252 8192000000 10091279600 10527592448 1130312736 12.124 99 36 73 1 1180737728 43399824 7025168
09:16:08 29439 8192000000 10094768912 10577362944 1135355120 12.172 99 11 22 0 1193277280 55559680 2362480
09:17:42 35551 8192000000 10157589104 10612031488 1150574928 12.315 100 40 79 0 1253762240 94889056 8298256
09:19:18 37913 8192000000 10122154608 10621104128 1152696848 12.335 100 7 14 0 1251673040 97282368 1693824
09:21:20 20892 8192000000 10090186080 10603429888 1145267792 12.265 100 9 18 0 1202200768 55053136 1879840
09:23:15 125973 8192000000 10148469280 10649071616 1147607312 12.287 99 12 24 0 1246402656 96245392 2549952
09:26:22 74494 8192000000 10086385136 10564939776 1148465856 12.295 99 10 20 1 1185149328 34606448 2077024
09:28:38 84581 8192000000 10092494560 10592419840 1132564464 12.146 97 21 42 2 1192587728 55523888 4499376
09:31:11 51844 8192000000 10139256032 10603368448 1170811824 12.504 100 43 86 0 1233413488 54081840 8519824
09:33:17 76581 8192000000 10100356864 10571157504 1144083008 12.254 98 19 38 0 1208131344 60291280 3757056
09:35:35 22276 8192000000 10096619024 10534035456 1160553072 12.408 99 40 79 2 1187586592 18803952 8229568
09:37:03 46645 8192000000 10080045040 10528923648 1143665424 12.250 98 28 56 1 1179341200 30127024 5548752
09:38:53 51598 8192000000 10115605376 10597158912 1142256240 12.237 98 13 26 0 1218644128 73607584 2780304
09:41:39 50101 8192000000 10099581344 10559733760 1142974272 12.244 99 11 22 0 1181068784 36320480 1774032
09:44:11 39954 8192000000 10093281024 10533867520 1106237376 11.897 97 26 52 1 1162600048 50810368 5552304
09:45:51 31167 8192000000 10132668592 10585464832 1134019648 12.159 100 32 64 0 1223353856 82322832 7011376
09:47:23 62724 8192000000 10157743216 10596368384 1126742592 12.091 99 17 34 0 1232092192 101460960 3888640
09:49:26 48420 8192000000 10194178880 10662014976 1117091088 12.000 97 29 58 0 1296371744 172545120 6735536
09:51:20 64180 8192000000 10170985520 10609897472 1079983328 11.647 96 25 50 2 1249639856 164026400 5630128
09:53:33 33810 8192000000 10075579728 10530828288 1090128864 11.744 98 23 46 1 1141512688 46715616 4668208
09:55:10 44276 8192000000 10052709024 10473943040 1090059200 11.743 97 39 78 1 1118248144 19666928 8522016
09:56:55 101651 8192000000 10064827056 10503876608 1071745632 11.569 96 38 77 0 1123398720 43359200 8293888
09:59:38 64343 8192000000 10052828624 10508976128 1086218192 11.707 98 11 22 0 1126825904 37953056 2654656
10:02:28 70100 8192000000 10092339104 10572681216 1107900560 11.913 100 11 22 0 1192144944 81642672 2601712
10:05:29 63598 8192000000 10067414960 10548858880 1114142160 11.972 100 16 32 0 1168987296 51397632 3447504
10:07:48 26063 8192000000 10136085952 10576748544 1130951616 12.130 100 36 72 2 1206568272 68476560 7140096
10:09:41 82095 8192000000 10093122720 10548699136 1136352096 12.181 100 20 40 0 1180606928 40259616 3995216
10:12:26 51116 8192000000 10130951920 10635194368 1145921184 12.271 99 30 60 1 1221621184 69790768 5909232
10:14:28 85440 8192000000 10109211952 10574487552 1101493984 11.852 96 25 50 1 1189329904 82654576 5181344
10:17:00 109044 8192000000 10125265872 10605780992 1147066240 12.282 100 22 44 1 1211212992 59152048 4994704
10:19:49 98165 8192000000 10132888736 10639511552 1142221248 12.236 99 9 18 0 1220403280 76236512 1945520
10:22:29 56649 8192000000 10069269680 10525298688 1127067584 12.094 98 10 21 0 1171517520 42500016 1949920
10:24:27 55917 8192000000 10122975040 10577338368 1146938368 12.281 99 13 26 1 1205704064 55831504 2934192
10:26:23 33841 8192000000 10095005840 10541797376 1112423664 11.955 96 23 47 0 1159351056 41408512 5518880
10:27:58 45923 8192000000 10080005296 10551451648 1076158304 11.611 95 19 38 1 1153598464 73134736 4305424
10:29:53 75687 8192000000 10129049712 10584330240 1099269760 11.831 97 19 38 0 1190135392 86831200 4034432
10:32:22 43258 8192000000 10092686320 10547408896 1090808592 11.750 96 21 42 1 1118893392 23992640 4092160
10:34:13 95888 8192000000 10047801312 10489454592 1104484576 11.880 99 17 34 0 1147958640 39587216 3886848
10:36:52 27538 8192000000 9210705456 9515913216 352380432 4.124 32 4 6 0 373658432 20632992 645008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-10-2021 10:39:44 to 09-10-2021 10:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-10-2021 10:40:00 to 09-10-2021 10:40:02.

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          238801
MON$OLDEST_ACTIVE               238802
MON$OLDEST_SNAPSHOT             238802
MON$NEXT_TRANSACTION            238802
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                       2065248
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             804
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2624 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2624 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2624 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2624 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 ---
:78: 
:79: mscs-test-161.budzdorov.ru    Sat Oct  9 10:37:30 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:81: 
--- end of diff output ---

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

09.10.21 10:40:03. 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-09 10:40:05 No severe PSQL-related problems occured
09.10.21 10:40:06. End of report.