Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.06.2021 04:28
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 16383192 kB
MemAvailable 31412852 kB
Buffers 20 kB
Cached 15235040 kB
SwapCached 16 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-06-13 04:27:00
DTS_END                         2021-06-13 04:27:00.5660
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/54642
TRN_ID                          227317
ATT_ID                          613
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: 8951.33 23066 1074159
customer order: creation 166.93 356 20031
customer order: refuse 13.70 370 1644
order to supplier: creation 44.73 1449 5368
order to supplier: removal 3.24 130933 389
invoice (draft): creation 44.27 3806 5312
invoice (draft): removal 51.56 1087 6187
invoice accept: apply 40.48 17330 4857
invoice accept: cancel 45.98 910 5518
customer reserve: creation 3724.88 167 446986
customer reserve: removal 4528.85 72 543462
realization accept: apply 81.14 191 9737
realization accept: cancel 83.93 117 10071
payment from customer: creation 55.59 126 6671
payment from customer: removal 5.58 81 670
payment to supplier: creation 46.69 154 5603
payment to supplier: removal 3.28 74 394
service: total inventory turnovers 3.68 3702 441
service: total monetary turnovers 5.97 664 716
service: aggregate perf. data 0.80 20097 96
service: refresh index statistics 0.05 279641 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-06-2021 04:28:52 to 13-06-2021 04:28:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7065
TEST_TIME 2 8780
TEST_TIME 3 8643
TEST_TIME 4 8052
TEST_TIME 5 7567
TEST_TIME 6 7067
TEST_TIME 7 6666
TEST_TIME 8 6491
TEST_TIME 9 6618
TEST_TIME 10 6452
TEST_TIME 11 6225
TEST_TIME 12 6300
TEST_TIME 13 6663
TEST_TIME 14 6629
TEST_TIME 15 7024
TEST_TIME 16 7360
TEST_TIME 17 7360
TEST_TIME 18 7758
TEST_TIME 19 7878
TEST_TIME 20 8112
TEST_TIME 21 8378
TEST_TIME 22 8708
TEST_TIME 23 8724
TEST_TIME 24 8603
TEST_TIME 25 8621
TEST_TIME 26 8475
TEST_TIME 27 8966
TEST_TIME 28 9130
TEST_TIME 29 9385
TEST_TIME 30 10211
TEST_TIME 31 11206
TEST_TIME 32 10862
TEST_TIME 33 10631
TEST_TIME 34 11072
TEST_TIME 35 11090
TEST_TIME 36 11006
TEST_TIME 37 10818
TEST_TIME 38 10655
TEST_TIME 39 10519
TEST_TIME 40 10630
TEST_TIME 41 10485
TEST_TIME 42 10364
TEST_TIME 43 10397
TEST_TIME 44 10302
TEST_TIME 45 10569
TEST_TIME 46 10545
TEST_TIME 47 10502
TEST_TIME 48 10394
TEST_TIME 49 10249
TEST_TIME 50 10062
TEST_TIME 51 9899
TEST_TIME 52 9943
TEST_TIME 53 9844
TEST_TIME 54 9792
TEST_TIME 55 9738
TEST_TIME 56 9791
TEST_TIME 57 9756
TEST_TIME 58 9703
TEST_TIME 59 9649
TEST_TIME 60 9595
TEST_TIME 61 9463
TEST_TIME 62 9370
TEST_TIME 63 9373
TEST_TIME 64 9333
TEST_TIME 65 9302
TEST_TIME 66 9340
TEST_TIME 67 9295
TEST_TIME 68 9415
TEST_TIME 69 9462
TEST_TIME 70 9493
TEST_TIME 71 9461
TEST_TIME 72 9465
TEST_TIME 73 9447
TEST_TIME 74 9464
TEST_TIME 75 9480
TEST_TIME 76 9481
TEST_TIME 77 9526
TEST_TIME 78 9436
TEST_TIME 79 9376
TEST_TIME 80 9300
TEST_TIME 81 9288
TEST_TIME 82 9254
TEST_TIME 83 9223
TEST_TIME 84 9164
TEST_TIME 85 9122
TEST_TIME 86 9206
TEST_TIME 87 9160
TEST_TIME 88 9175
TEST_TIME 89 9185
TEST_TIME 90 9164
TEST_TIME 91 9157
TEST_TIME 92 9304
TEST_TIME 93 9302
TEST_TIME 94 9306
TEST_TIME 95 9356
TEST_TIME 96 9336
TEST_TIME 97 9267
TEST_TIME 98 9245
TEST_TIME 99 9220
TEST_TIME 100 9165
TEST_TIME 101 9164
TEST_TIME 102 9186
TEST_TIME 103 9190
TEST_TIME 104 9171
TEST_TIME 105 9127
TEST_TIME 106 9187
TEST_TIME 107 9151
TEST_TIME 108 9188
TEST_TIME 109 9184
TEST_TIME 110 9207
TEST_TIME 111 9218
TEST_TIME 112 9177
TEST_TIME 113 9275
TEST_TIME 114 9206
TEST_TIME 115 9135
TEST_TIME 116 9092
TEST_TIME 117 9046
TEST_TIME 118 9036
TEST_TIME 119 9030
TEST_TIME 120 8955

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-06-2021 04:28:56 to 13-06-2021 04:28:59.

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 1487466 1487466 0 0.00 0 39710 6 0 0
doc_list_biud 1487465 1487465 0 0.00 0 99977 34 0 0
sp_add_invoice_to_stock 4986 4857 129 2.58 45 205027 17330 0 129
sp_cancel_adding_invoice 5518 5518 0 0.00 5 70842 910 0 0
sp_cancel_client_order 1644 1644 0 0.00 4 20573 370 0 0
sp_cancel_customer_reserve 543462 543462 0 0.00 1 39271 73 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 4154 82 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 1572 75 0 0
sp_cancel_supplier_invoice 6199 6187 12 0.19 2 100027 1088 0 12
sp_cancel_supplier_order 389 389 0 0.00 9 1419433 130934 0 0
sp_cancel_write_off 10071 10071 0 0.00 1 13171 117 0 0
sp_client_order 20031 20031 0 0.00 4 37838 356 0 0
sp_customer_reserve 446986 446986 0 0.00 0 109951 168 0 0
sp_fill_shopping_cart 30697 30697 0 0.00 1 49157 45 0 0
sp_fill_shopping_cart_clo_res 446980 446980 0 0.00 0 27923 14 0 0
sp_get_clo_for_invoice 4849 4849 0 0.00 0 45896 436 0 0
sp_kill_cost_storno 16653 16653 0 0.00 0 8965 69 0 0
sp_kill_qstorno_ret_qs2qd 1103571 1103571 0 0.00 0 99975 40 0 0
sp_kill_qty_storno 581857 581857 0 0.00 0 99976 85 0 0
sp_lock_selected_doc 20090 20090 0 0.00 0 8104 5 0 0
sp_make_cost_storno 26860 26860 0 0.00 0 21580 72 0 0
sp_multiply_rows_for_pdistr 26860 26860 0 0.00 0 9075 34 0 0
sp_multiply_rows_for_qdistr 20031 20031 0 0.00 0 29630 170 0 0
sp_pay_from_customer 6671 6671 0 0.00 4 21320 126 0 0
sp_pay_to_supplier 5603 5603 0 0.00 5 26285 155 0 0
sp_payment_common 12274 12274 0 0.00 1 16093 29 0 0
sp_qd_handle_on_cancel_clo 1644 1644 0 0.00 0 20565 345 0 0
sp_qd_handle_on_invoice_upd_sts 10367 10367 0 0.00 0 42706 420 0 0
sp_qd_handle_on_reserve_upd_sts 9737 9737 0 0.00 0 14592 58 0 0
sp_reserve_write_off 9737 9737 0 0.00 6 21322 192 0 0
sp_supplier_invoice 5312 5312 0 0.00 6 95030 3806 0 0
sp_supplier_order 5368 5368 0 0.00 5 53520 1449 0 0
srv_aggregate_perf_data 96 96 0 0.00 2882 156358 20097 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1272 199785 51063 0 0
srv_find_qd_qs_mism 1028002 1028002 0 0.00 0 39708 5 0 0
srv_make_invnt_saldo 441 441 0 0.00 16 86117 3703 0 0
srv_make_money_saldo 716 716 0 0.00 1 11175 664 0 0
srv_recalc_idx_stat 6 6 0 0.00 63332 486218 279642 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 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 4857 4857 0 0.00 0 13789 161 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 5310 110 0 0
t$perf-norm:sp_cancel_client_order 1644 1644 0 0.00 0 5698 15 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 4992 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 4272 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 431 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 1159 18 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 21588 1772 0 0
t$perf-norm:sp_cancel_write_off 924 924 0 0.00 0 1568 10 0 0
t$perf-norm:sp_client_order 20031 20031 0 0.00 0 15878 20 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 11204 22 0 0
t$perf-norm:sp_pay_from_customer 6671 6671 0 0.00 0 4616 7 0 0
t$perf-norm:sp_pay_to_supplier 5603 5603 0 0.00 0 5936 11 0 0
t$perf-norm:sp_reserve_write_off 9737 9737 0 0.00 0 6842 11 0 0
t$perf-norm:sp_supplier_invoice 5312 5312 0 0.00 0 5798 18 0 0
t$perf-norm:sp_supplier_order 5368 5368 0 0.00 0 5921 20 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 2920 39 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 91 4 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 775 9 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 2977 9 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9956 9698 258 2.59 0 8046 18 0 258
v_all_customers 36124 36124 0 0.00 0 14 1 0 0
v_all_suppliers 29346 29346 0 0.00 0 21 1 0 0
v_all_wares 40062 40062 0 0.00 0 197 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 1086 8 0 0
v_cancel_client_order 3288 3288 0 0.00 0 1977 5 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 3985 9 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 835 4 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 522 4 0 0
v_cancel_supplier_invoice 884 860 24 2.71 0 480 13 0 24
v_cancel_supplier_order 778 778 0 0.00 0 1625 6 0 0
v_cancel_write_off 1848 1848 0 0.00 0 1952 14 0 0
v_max_id_clo_ord 5368 5368 0 0.00 0 267 1 0 0
v_max_id_clo_res 934 934 0 0.00 0 2236 12 0 0
v_max_id_ord_sup 5298 5298 0 0.00 0 2573 4 0 0
v_max_non_paid_invoice 1596 1596 0 0.00 0 764 3 0 0
v_max_non_paid_realizn 3645 3645 0 0.00 0 8065 7 0 0
v_min_id_clo_ord 5368 5368 0 0.00 0 10459 7 0 0
v_min_id_clo_res 934 934 0 0.00 0 1948 8 0 0
v_min_id_ord_sup 5298 5298 0 0.00 0 19900 20 0 0
v_min_non_paid_invoice 5603 5603 0 0.00 0 8959 20 0 0
v_min_non_paid_realizn 6671 6671 0 0.00 0 16083 20 0 0
v_our_firm 9990 9990 0 0.00 0 15 1 0 0
v_reserve_write_off 19474 19474 0 0.00 0 3001 4 0 0
x_lock_depdocs_on_canc_invoice 11275 11275 0 0.00 0 36439 116 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 9708 382 0 0
x_make_qstorno_1000_1200 5368 5368 0 0.00 2 53495 1362 0 0
x_make_qstorno_1200_2000 5312 5312 0 0.00 3 95022 3633 0 0
x_make_qstorno_2100_3300 446986 446986 0 0.00 0 109949 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-06-2021 04:29:00 to 13-06-2021 04:29:04.

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
01:57:04 5 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
01:58:04 32316 8192000000 9724251840 10024435712 802239984 8.919 100 92 184 0 832123200 7662704 22220512
01:59:45 12354 8192000000 9763596592 10066296832 835962608 9.259 100 91 182 0 870473872 12718672 21792592
02:01:05 16123 8192000000 9800167264 10116780032 863222128 9.532 100 84 168 0 904930160 22401440 19306592
02:02:30 13449 8192000000 9855596912 10191556608 909992976 9.997 100 74 148 0 973793248 47607792 16192480
02:03:49 8817 8192000000 9919786752 10263986176 959934800 10.488 100 81 162 0 1029956320 53253728 16767792
02:05:01 5006 8192000000 9956800560 10306678784 987989424 10.762 100 81 162 0 1069396576 65234048 16173104
02:06:10 3056 8192000000 9990578112 10352422912 1021632688 11.088 100 86 170 0 1104229424 65394976 17201760
02:07:16 2237 8192000000 10007435152 10372165632 1041842128 11.282 100 88 173 1 1128383200 68915568 17625504
02:08:21 2728 8192000000 10038734928 10407251968 1059621296 11.453 100 93 185 0 1136904256 58074064 19208896
02:09:25 1692 8192000000 10052248736 10427228160 1073084720 11.582 100 98 193 1 1170341296 77211216 20045360
02:10:28 14154 8192000000 10004122864 10400739328 1076537904 11.614 100 58 115 1 1121088272 32516800 12033568
02:11:48 7662 8192000000 9995541936 10410897408 1080733040 11.654 100 23 46 1 1128245696 43196560 4316096
02:12:56 7123 8192000000 9991342304 10394923008 1088869952 11.732 100 27 51 3 1124383552 29300208 6213392
02:14:04 8041 8192000000 9996207696 10409865216 1091169072 11.754 100 28 56 0 1113527680 16294624 6063984
02:15:13 11838 8192000000 9999229168 10406166528 1086348304 11.708 99 25 50 2 1112980240 21589072 5042864
02:16:26 54156 8192000000 10016209056 10410434560 1088737808 11.731 99 59 121 2 1126164816 24371056 13055952
02:18:21 9188 8192000000 9990768528 10395193344 1053434720 11.394 97 30 62 0 1082554896 21089792 8030384
02:19:30 7005 8192000000 9971536592 10367078400 1045524208 11.318 98 31 62 1 1081570592 29270448 6775936
02:20:40 6002 8192000000 9950429136 10332680192 1019577984 11.068 97 40 80 0 1043947136 15115616 9253536
02:21:47 55759 8192000000 9937915232 10330488832 989772432 10.779 95 46 90 3 1017837456 16632384 11432640
02:23:53 9956 8192000000 9955671584 10355314688 1038580368 11.251 99 39 78 1 1066784448 19169904 9034176
02:25:06 8862 8192000000 9987852784 10384023552 1047850656 11.340 100 40 80 0 1089626032 32798800 8976576
02:26:17 14106 8192000000 9967958208 10377715712 1061043760 11.466 100 19 37 0 1086076576 20984480 4048336
02:27:38 60917 8192000000 10026637152 10419122176 1088425824 11.728 100 62 122 1 1118366944 16533264 13407856
02:29:46 2092 8192000000 10086850208 10481627136 1112266368 11.954 100 78 154 2 1183189408 54998528 15924512
02:30:53 4567 8192000000 10081643488 10486607872 1123784640 12.063 100 73 144 2 1194922576 57164016 13973920
02:32:08 9124 8192000000 10115394080 10530615296 1138946176 12.206 100 58 116 3 1232055632 81041008 12068448
02:33:25 6325 8192000000 10132598400 10541256704 1147726608 12.288 100 56 108 1 1236683008 76211584 12744816
02:34:35 8145 8192000000 10203325824 10612396032 1152019536 12.328 100 67 134 0 1301511136 135144800 14346800
02:35:49 14160 8192000000 10221228112 10634436608 1164080192 12.441 100 46 90 2 1324831424 150230256 10520976
02:37:11 8734 8192000000 10253796128 10676838400 1172025696 12.516 100 46 91 2 1362847680 180417520 10404464
02:38:28 18574 8192000000 10295228336 10717908992 1179149168 12.582 100 60 120 2 1397094032 203426768 14518096
02:40:03 7296 8192000000 10325811040 10748133376 1176419952 12.557 100 68 136 0 1419989728 227495584 16074192
02:41:14 7007 8192000000 10331231936 10742755328 1173615392 12.531 100 80 158 1 1428017056 236814192 17587472
02:42:29 1453 8192000000 10366769008 10787295232 1173782272 12.532 100 84 168 0 1469053808 276971232 18300304
02:43:36 7032 8192000000 10362148176 10776580096 1162704864 12.429 100 70 140 2 1468781600 290272784 15803952
02:44:46 2494 8192000000 10392865296 10805260288 1164306976 12.444 100 83 165 4 1494537728 310658368 19572384
02:45:56 1270 8192000000 10375003184 10790633472 1148830752 12.299 99 88 176 1 1476062912 307025136 20207024
02:47:03 3563 8192000000 10414096592 10821595136 1163461440 12.436 100 84 169 1 1510963696 327911072 19591184
02:48:13 3671 8192000000 10416075056 10844434432 1168634912 12.484 100 68 136 0 1530887712 346482608 15770192
02:49:22 10962 8192000000 10372730976 10823266304 1166983408 12.469 100 37 74 1 1474215552 297438672 9793472
02:50:39 10262 8192000000 10455704848 10880004096 1182124704 12.610 100 49 98 1 1544924944 350670384 12129856
02:51:55 5264 8192000000 10506655808 10956361728 1175001632 12.544 100 42 82 2 1623003248 437233120 10768496
02:53:05 10106 8192000000 10555181504 10984824832 1151215456 12.321 99 40 77 2 1641498384 480324544 9958384
02:54:47 15146 8192000000 10535977248 11016040448 1149536096 12.305 100 29 58 2 1634711360 477071504 8103760
02:56:36 17672 8192000000 10429863600 10859343872 1103934480 11.875 98 36 68 1 1519589232 406828432 8826320
02:57:56 14672 8192000000 10211117264 10809372672 1126418144 12.088 99 26 50 1 1392573296 259997056 6158096
02:59:14 93933 8192000000 10208151328 10658766848 1133431616 12.154 99 27 52 0 1301696528 161993184 6271728
03:01:49 68021 8192000000 10098353408 10586267648 1130442192 12.126 99 5 10 0 1174767232 43571024 754016
03:03:58 87106 8192000000 10086682400 10524626944 1079598592 11.644 94 21 39 2 1116161616 32840752 3722272
03:06:27 84364 8192000000 10077987280 10583572480 1120212352 12.029 98 12 24 1 1202895664 80083280 2600032
03:08:52 41577 8192000000 10102813712 10543009792 1133766896 12.157 99 30 56 2 1183839328 43756896 6315536
03:10:35 93371 8192000000 10110618624 10654461952 1139172256 12.208 100 13 26 1 1247129824 105299760 2657808
03:13:11 199784 8192000000 10076320336 10510868480 1087001664 11.714 95 13 26 0 1125861120 36313472 2545984
03:17:38 145979 8192000000 10064800240 10542358528 1119635712 12.024 98 33 65 0 1166911344 41038400 6237232
03:21:07 64236 8192000000 10159577424 10625396736 1155323072 12.359 100 39 76 2 1265077008 101738016 8015920
03:23:12 66253 8192000000 10141768544 10655985664 1146561904 12.277 99 16 32 2 1227899360 77554576 3782880
03:26:43 66669 8192000000 10059911440 10563657728 1125342528 12.077 98 7 14 0 1182151648 55305904 1503216
03:29:02 68116 8192000000 10064830304 10560241664 1112602448 11.957 97 12 24 0 1154745248 39961264 2181536
03:31:14 81072 8192000000 10092556352 10567180288 1127266800 12.096 98 17 34 0 1186983680 55905072 3811808
03:33:36 47757 8192000000 10118270624 10570489856 1125546576 12.079 98 13 26 1 1193181520 65259296 2375648
03:35:28 33805 8192000000 10114840784 10596184064 1130680144 12.128 98 23 45 2 1200137296 64068576 5388576
03:37:03 69765 8192000000 10051582000 10498138112 1087575792 11.720 96 13 26 0 1163067568 72075296 3416480
03:39:15 24646 8192000000 10050688320 10474881024 1098686224 11.825 98 39 78 0 1130257104 23400432 8170448
03:40:47 10692 8192000000 10087881552 10519003136 1122417936 12.050 100 47 94 0 1182430368 50908016 9104416
03:41:58 59005 8192000000 10084945664 10514976768 1126278528 12.086 99 24 47 1 1153325488 22424912 4622048
03:44:02 72756 8192000000 10097612784 10565828608 1101912800 11.856 97 8 17 0 1140154112 36556976 1684336
03:46:48 84600 8192000000 10074078032 10562535424 1105329824 11.888 98 22 44 0 1174238208 64539952 4368432
03:49:13 44921 8192000000 10067838400 10587181056 1122318352 12.049 100 5 10 1 1174405920 50811040 1276528
03:50:58 62673 8192000000 10086164096 10550247424 1101679424 11.854 98 15 30 2 1157949520 53167296 3102800
03:53:01 62642 8192000000 10059175664 10522853376 1098325696 11.822 98 12 24 0 1166636320 65711712 2598912
03:55:08 98493 8192000000 10090097344 10567217152 1092445568 11.766 98 12 24 1 1160816928 65766992 2604368
03:57:47 71886 8192000000 10143856208 10623168512 1112475008 11.956 98 18 36 1 1222795536 106224848 4095680
04:00:00 77593 8192000000 10102937632 10555187200 1111469808 11.946 98 25 50 0 1149822032 33102688 5249536
04:02:17 126377 8192000000 10068164080 10531536896 1075303696 11.603 95 20 40 1 1117346624 38354880 3688048
04:06:02 71239 8192000000 10044686048 10534875136 1111500624 11.947 99 6 12 0 1142406656 30109200 796832
04:08:14 70175 8192000000 10101125808 10567602176 1127163456 12.095 100 14 28 1 1188695328 58478512 3053360
04:10:29 82096 8192000000 10078837536 10537439232 1125218544 12.076 99 14 27 2 1151872304 24101200 2552560
04:12:52 71630 8192000000 10104254752 10584211456 1133953632 12.159 99 17 34 1 1232406832 94721856 3731344
04:15:07 112764 8192000000 10085784400 10573570048 1103722752 11.873 97 5 10 0 1142807472 38219200 865520
04:18:00 94935 8192000000 10102218864 10526523392 1074564688 11.596 94 12 24 0 1101308976 24074400 2669888
04:20:43 127020 8192000000 10057759056 10504376320 1127479184 12.098 99 13 26 0 1168659472 38569328 2610960
04:23:51 45102 8192000000 10081530560 10540503040 1149297168 12.303 100 11 22 0 1192958240 41543264 2117808
04:25:43 81132 8192000000 9780338128 10219819008 845691936 9.357 75 6 12 0 880902752 33946704 1264112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-06-2021 04:29:06 to 13-06-2021 04:29:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 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 13-06-2021 04:29:22 to 13-06-2021 04:29:24.

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          227316
MON$OLDEST_ACTIVE               227791
MON$OLDEST_SNAPSHOT             227791
MON$NEXT_TRANSACTION            227791
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                       2136320
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2515 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2515 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2515 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2515 Firebird 4.0/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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Sun Jun 13 04:27:19 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Sun Jun 13 04:27:26 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

13.06.21 04:29:25. 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-06-13 04:29:27 No severe PSQL-related problems occured
13.06.21 04:29:28. End of report.