Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.12.2021 13:38
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16591364 kB
MemAvailable 31556984 kB
Buffers 0 kB
Cached 15124844 kB
SwapCached 0 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-12-02 13:37:08
DTS_END                         2021-12-02 13:37:08.2340
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/43658
TRN_ID                          235747
ATT_ID                          672
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: 9047.13 22166 1085655
customer order: creation 167.62 358 20114
customer order: refuse 14.13 325 1695
order to supplier: creation 46.24 1331 5549
order to supplier: removal 3.49 108253 419
invoice (draft): creation 44.64 3291 5357
invoice (draft): removal 51.07 968 6128
invoice accept: apply 41.33 17548 4959
invoice accept: cancel 46.83 858 5619
customer reserve: creation 3797.75 172 455730
customer reserve: removal 4543.14 64 545177
realization accept: apply 82.11 188 9853
realization accept: cancel 84.23 118 10107
payment from customer: creation 57.76 125 6931
payment from customer: removal 5.27 83 632
payment to supplier: creation 47.58 151 5710
payment to supplier: removal 3.35 70 402
service: total inventory turnovers 3.89 3654 467
service: total monetary turnovers 5.88 587 705
service: aggregate perf. data 0.81 14425 97
service: refresh index statistics 0.03 290757 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-12-2021 13:38:47 to 02-12-2021 13:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5765
TEST_TIME 2 4180
TEST_TIME 3 4912
TEST_TIME 4 4772
TEST_TIME 5 5608
TEST_TIME 6 5995
TEST_TIME 7 6071
TEST_TIME 8 6519
TEST_TIME 9 6300
TEST_TIME 10 6165
TEST_TIME 11 6280
TEST_TIME 12 6438
TEST_TIME 13 6750
TEST_TIME 14 6706
TEST_TIME 15 7091
TEST_TIME 16 7502
TEST_TIME 17 7552
TEST_TIME 18 7965
TEST_TIME 19 8006
TEST_TIME 20 7969
TEST_TIME 21 8254
TEST_TIME 22 8316
TEST_TIME 23 8582
TEST_TIME 24 8638
TEST_TIME 25 8637
TEST_TIME 26 8516
TEST_TIME 27 8609
TEST_TIME 28 9593
TEST_TIME 29 10880
TEST_TIME 30 10842
TEST_TIME 31 10613
TEST_TIME 32 10524
TEST_TIME 33 10228
TEST_TIME 34 10144
TEST_TIME 35 10061
TEST_TIME 36 10010
TEST_TIME 37 9910
TEST_TIME 38 9893
TEST_TIME 39 9904
TEST_TIME 40 9876
TEST_TIME 41 10067
TEST_TIME 42 9979
TEST_TIME 43 9943
TEST_TIME 44 10123
TEST_TIME 45 10076
TEST_TIME 46 9965
TEST_TIME 47 10129
TEST_TIME 48 10101
TEST_TIME 49 9989
TEST_TIME 50 9791
TEST_TIME 51 9695
TEST_TIME 52 9649
TEST_TIME 53 9631
TEST_TIME 54 9568
TEST_TIME 55 9509
TEST_TIME 56 9413
TEST_TIME 57 9349
TEST_TIME 58 9285
TEST_TIME 59 9176
TEST_TIME 60 9337
TEST_TIME 61 9387
TEST_TIME 62 9360
TEST_TIME 63 9417
TEST_TIME 64 9457
TEST_TIME 65 9407
TEST_TIME 66 9517
TEST_TIME 67 9523
TEST_TIME 68 9498
TEST_TIME 69 9587
TEST_TIME 70 9588
TEST_TIME 71 9602
TEST_TIME 72 9686
TEST_TIME 73 9688
TEST_TIME 74 9583
TEST_TIME 75 9522
TEST_TIME 76 9525
TEST_TIME 77 9512
TEST_TIME 78 9494
TEST_TIME 79 9462
TEST_TIME 80 9410
TEST_TIME 81 9327
TEST_TIME 82 9307
TEST_TIME 83 9326
TEST_TIME 84 9288
TEST_TIME 85 9280
TEST_TIME 86 9280
TEST_TIME 87 9271
TEST_TIME 88 9371
TEST_TIME 89 9440
TEST_TIME 90 9448
TEST_TIME 91 9419
TEST_TIME 92 9469
TEST_TIME 93 9550
TEST_TIME 94 9495
TEST_TIME 95 9420
TEST_TIME 96 9385
TEST_TIME 97 9356
TEST_TIME 98 9307
TEST_TIME 99 9284
TEST_TIME 100 9242
TEST_TIME 101 9188
TEST_TIME 102 9170
TEST_TIME 103 9138
TEST_TIME 104 9174
TEST_TIME 105 9140
TEST_TIME 106 9179
TEST_TIME 107 9184
TEST_TIME 108 9185
TEST_TIME 109 9177
TEST_TIME 110 9205
TEST_TIME 111 9209
TEST_TIME 112 9213
TEST_TIME 113 9259
TEST_TIME 114 9259
TEST_TIME 115 9219
TEST_TIME 116 9178
TEST_TIME 117 9179
TEST_TIME 118 9131
TEST_TIME 119 9120
TEST_TIME 120 9050

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-12-2021 13:38:51 to 02-12-2021 13:38:54.

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 1506440 1506440 0 0.00 0 32672 6 0 0
doc_list_biud 1506440 1506440 0 0.00 0 59149 30 0 0
sp_add_invoice_to_stock 5118 4959 159 3.10 32 164308 17549 0 159
sp_cancel_adding_invoice 5619 5619 0 0.00 5 82167 858 0 0
sp_cancel_client_order 1695 1695 0 0.00 4 9318 325 0 0
sp_cancel_customer_reserve 545177 545177 0 0.00 1 22960 65 0 0
sp_cancel_pay_from_customer 632 632 0 0.00 3 10547 83 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 2571 71 0 0
sp_cancel_supplier_invoice 6140 6128 12 0.19 2 20155 968 0 12
sp_cancel_supplier_order 419 419 0 0.00 5 1150691 108254 0 0
sp_cancel_write_off 10107 10107 0 0.00 1 13331 118 0 0
sp_client_order 20114 20114 0 0.00 4 25472 359 0 0
sp_customer_reserve 455730 455730 0 0.00 0 76307 173 0 0
sp_fill_shopping_cart 31016 31016 0 0.00 1 19628 49 0 0
sp_fill_shopping_cart_clo_res 455728 455728 0 0.00 0 21259 17 0 0
sp_get_clo_for_invoice 4953 4953 0 0.00 0 30298 401 0 0
sp_kill_cost_storno 16760 16760 0 0.00 0 13312 61 0 0
sp_kill_qstorno_ret_qs2qd 1107008 1107008 0 0.00 0 22952 35 0 0
sp_kill_qty_storno 583951 583951 0 0.00 0 59149 76 0 0
sp_lock_selected_doc 20407 20407 0 0.00 0 16534 6 0 0
sp_make_cost_storno 27447 27447 0 0.00 0 25531 67 0 0
sp_multiply_rows_for_pdistr 27447 27447 0 0.00 0 12882 32 0 0
sp_multiply_rows_for_qdistr 20114 20114 0 0.00 0 25392 175 0 0
sp_pay_from_customer 6931 6931 0 0.00 4 32825 125 0 0
sp_pay_to_supplier 5710 5710 0 0.00 5 33199 152 0 0
sp_payment_common 12641 12641 0 0.00 1 13244 26 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 9309 298 0 0
sp_qd_handle_on_invoice_upd_sts 10572 10572 0 0.00 0 59149 360 0 0
sp_qd_handle_on_reserve_upd_sts 9853 9853 0 0.00 0 17372 47 0 0
sp_reserve_write_off 9853 9853 0 0.00 7 32830 189 0 0
sp_supplier_invoice 5357 5357 0 0.00 7 83960 3291 0 0
sp_supplier_order 5549 5549 0 0.00 6 38057 1332 0 0
srv_aggregate_perf_data 97 97 0 0.00 3159 137841 14425 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1311 185083 51318 0 0
srv_find_qd_qs_mism 1038361 1038361 0 0.00 0 15374 5 0 0
srv_make_invnt_saldo 467 467 0 0.00 40 84257 3654 0 0
srv_make_money_saldo 705 705 0 0.00 1 17314 588 0 0
srv_recalc_idx_stat 4 4 0 0.00 215765 404788 290758 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 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 4959 4959 0 0.00 0 26698 202 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 5871 137 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 6129 19 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 1575 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 632 632 0 0.00 0 821 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 6718 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 3736 31 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 30222 1725 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 9102 13 0 0
t$perf-norm:sp_client_order 20114 20114 0 0.00 0 13278 22 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 2669 6 0 0
t$perf-norm:sp_pay_from_customer 6931 6931 0 0.00 0 8624 11 0 0
t$perf-norm:sp_pay_to_supplier 5710 5710 0 0.00 0 11066 15 0 0
t$perf-norm:sp_reserve_write_off 9853 9853 0 0.00 0 12474 13 0 0
t$perf-norm:sp_supplier_invoice 5357 5357 0 0.00 0 13122 18 0 0
t$perf-norm:sp_supplier_order 5549 5549 0 0.00 0 12993 21 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 355 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 96 3 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 1732 11 0 0
t$perf-norm:srv_make_money_saldo 705 705 0 0.00 0 1084 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 5 0 0
v_add_invoice_to_stock 10224 9906 318 3.11 0 5696 19 0 318
v_all_customers 36676 36676 0 0.00 0 25 1 0 0
v_all_suppliers 29980 29980 0 0.00 0 25 1 0 0
v_all_wares 40228 40228 0 0.00 0 41 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1064 7 0 0
v_cancel_client_order 3390 3390 0 0.00 0 4532 7 0 0
v_cancel_customer_prepayment 1264 1264 0 0.00 0 6058 11 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 10051 11 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 1979 6 0 0
v_cancel_supplier_invoice 808 784 24 2.97 0 5853 24 0 24
v_cancel_supplier_order 838 838 0 0.00 0 315 3 0 0
v_cancel_write_off 1942 1942 0 0.00 0 10097 18 0 0
v_max_id_clo_ord 5548 5548 0 0.00 0 518 1 0 0
v_max_id_clo_res 996 996 0 0.00 0 1863 11 0 0
v_max_id_ord_sup 5353 5353 0 0.00 0 7837 6 0 0
v_max_non_paid_invoice 1621 1621 0 0.00 0 5710 5 0 0
v_max_non_paid_realizn 3635 3635 0 0.00 0 1002 2 0 0
v_min_id_clo_ord 5548 5548 0 0.00 0 19539 10 0 0
v_min_id_clo_res 996 996 0 0.00 0 8701 19 0 0
v_min_id_ord_sup 5353 5353 0 0.00 0 5860 6 0 0
v_min_non_paid_invoice 5710 5710 0 0.00 0 13056 17 0 0
v_min_non_paid_realizn 6931 6931 0 0.00 0 13240 21 0 0
v_our_firm 10144 10144 0 0.00 0 34 1 0 0
v_reserve_write_off 19706 19706 0 0.00 0 8837 7 0 0
x_lock_depdocs_on_canc_invoice 11355 11355 0 0.00 0 17881 111 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 10804 331 0 0
x_make_qstorno_1000_1200 5549 5549 0 0.00 2 38049 1249 0 0
x_make_qstorno_1200_2000 5357 5357 0 0.00 3 83911 3094 0 0
x_make_qstorno_2100_3300 455730 455730 0 0.00 0 74699 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-12-2021 13:38:55 to 02-12-2021 13:38:59.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 36258 8192000000 9744873712 10046922752 817586624 9.074 100 90 180 1 847247104 7844720 21815760
11:10:06 28585 8192000000 9783134128 10094927872 850684752 9.407 100 91 182 0 888465808 16275920 21505136
11:11:47 12169 8192000000 9824089664 10149617664 882592816 9.725 100 85 170 0 937630480 36181088 18856576
11:13:07 12007 8192000000 9889686544 10227793920 927386240 10.169 100 82 164 1 1001071248 55805696 17879312
11:14:22 4813 8192000000 9952072112 10304675840 973874704 10.625 100 86 170 1 1072206752 81329680 17002368
11:15:30 3331 8192000000 9982012160 10339426304 1011210624 10.987 100 94 189 1 1096103264 66542992 18349648
11:16:35 1547 8192000000 10017538720 10380075008 1035738384 11.224 100 90 180 1 1128528784 74504224 18286176
11:17:38 1520 8192000000 10027572256 10397614080 1063853056 11.493 100 92 182 1 1135671488 52928592 18889840
11:18:40 26527 8192000000 9989194128 10379341824 1068418224 11.537 100 32 64 0 1108294064 32782464 7093376
11:20:08 6815 8192000000 10006771680 10417451008 1078876016 11.637 100 29 58 0 1132825120 48044400 5904704
11:21:16 6141 8192000000 10015246080 10408468480 1089089104 11.734 100 36 70 2 1118144784 21487600 7568080
11:22:24 68539 8192000000 10008328992 10423447552 1092259568 11.764 100 38 75 1 1121026592 19934192 8832832
11:24:33 6513 8192000000 10010452608 10415730688 1097909472 11.818 100 58 112 4 1130718816 21050144 11759200
11:25:42 9936 8192000000 10001690832 10412064768 1087722192 11.721 99 42 81 0 1117379216 20347312 9309712
11:26:54 8800 8192000000 9980076416 10367946752 1046882928 11.331 97 16 30 2 1079156720 28750608 3523184
11:28:03 45053 8192000000 9981619312 10362875904 1052271968 11.382 98 58 116 1 1076387280 12057312 12058000
11:29:48 4119 8192000000 9948995536 10321317888 970577760 10.592 92 46 91 1 1001045040 20018704 10448576
11:30:54 4798 8192000000 9935337472 10312093696 1014816768 11.022 99 45 90 1 1042850256 17499728 10533760
11:32:00 4876 8192000000 9947452496 10335490048 1039856896 11.263 100 52 104 0 1060207120 8547824 11802400
11:33:05 72862 8192000000 9975890448 10386038784 1049643136 11.357 100 37 74 1 1076739648 19309088 7787424
11:35:18 4250 8192000000 9987752592 10375938048 1063229200 11.487 100 67 133 3 1089570832 11079184 15262448
11:36:22 4340 8192000000 9985668192 10371018752 1072526224 11.576 100 48 98 3 1096856640 12088368 12242048
11:37:33 10515 8192000000 10015331104 10408636416 1098181296 11.820 100 38 75 0 1116948752 11215920 7551536
11:38:55 95815 8192000000 10067961376 10484568064 1120394896 12.031 100 43 86 0 1198477584 68770640 9312048
11:41:32 9168 8192000000 10088554208 10496655360 1131644128 12.137 100 48 96 1 1199243248 56786000 10813120
11:42:54 23111 8192000000 10118172688 10524962816 1148863680 12.299 100 62 123 2 1220682512 58312560 13506272
11:44:21 3926 8192000000 10178494224 10583568384 1157616016 12.381 100 66 132 0 1282380416 111102288 13662112
11:45:31 10438 8192000000 10177981600 10591506432 1155148672 12.358 100 45 90 1 1283744624 119156992 9438960
11:46:48 8837 8192000000 10167408256 10584743936 1163826144 12.439 100 44 88 2 1272992480 99656576 9509760
11:48:01 4483 8192000000 10192713936 10619654144 1167132960 12.470 100 61 120 3 1315306320 134063136 14110224
11:49:10 2322 8192000000 10258557344 10661842944 1158503904 12.389 100 73 146 1 1358693296 184101264 16088128
11:50:23 3652 8192000000 10268557984 10678403072 1157205760 12.377 100 77 150 2 1376080912 200944256 17930896
11:51:34 2573 8192000000 10301816080 10697838592 1155428288 12.360 100 78 153 3 1397829488 224692688 17708512
11:52:41 1308 8192000000 10331679840 10725330944 1143717040 12.250 99 85 168 0 1425014016 261487600 19809376
11:53:46 3782 8192000000 10288746304 10715717632 1147519488 12.286 100 54 108 2 1387637200 226753008 13364704
11:54:57 2855 8192000000 10327609408 10742202368 1146785856 12.279 99 73 145 1 1438969024 274811952 17371216
11:56:04 3990 8192000000 10326179552 10733957120 1165261216 12.453 100 54 106 0 1428806416 250398176 13147024
11:57:13 7148 8192000000 10366102080 10766622720 1138319344 12.200 98 64 128 1 1450328080 296362816 15645920
11:58:27 7288 8192000000 10385794960 10800701440 1133540512 12.155 99 42 83 1 1474638288 330350640 10747136
11:59:45 13287 8192000000 10374182160 10796265472 1155193072 12.358 100 50 99 1 1477462416 309846480 12422864
12:01:02 21098 8192000000 10392838416 10830651392 1118170480 12.010 98 29 58 0 1475306208 349307168 7828560
12:02:24 14562 8192000000 10406779120 10862149632 1123844704 12.063 99 27 54 0 1490943984 359729872 7369408
12:03:41 22302 8192000000 10500077392 10937991168 1135693120 12.175 100 28 56 2 1588016992 444538160 7785712
12:05:18 106747 8192000000 10058270720 10575917056 1084558304 11.691 97 15 30 2 1313580816 225454480 3568032
12:08:07 18517 8192000000 10076857776 10493034496 1135629344 12.174 100 55 109 3 1172994272 25673648 11691280
12:09:26 72668 8192000000 10110012816 10571792384 1133292096 12.152 99 14 29 0 1206771440 70229120 3250224
12:12:05 44117 8192000000 10095658112 10573901824 1117262448 12.001 99 13 26 1 1200317952 80305776 2749728
12:13:49 67075 8192000000 10144667760 10594992128 1125014768 12.074 99 28 57 1 1224937488 94117232 5805488
12:16:16 122165 8192000000 10129758416 10615431168 1107199056 11.906 98 21 43 1 1239776512 127639296 4938160
12:19:20 25298 8192000000 10121654944 10551349248 1131082560 12.132 99 31 62 1 1183754064 46393424 6278080
12:20:48 113481 8192000000 10111135216 10636894208 1115832688 11.988 98 19 38 0 1181062480 61522624 3707168
12:23:50 43530 8192000000 10088595408 10597568512 1131848016 12.139 99 24 48 0 1231601664 94159696 5593952
12:25:36 185081 8192000000 10067607616 10558644224 1116737648 11.996 98 9 18 0 1162525184 43995024 1792512
12:29:42 51617 8192000000 10078030384 10548195328 1132957136 12.149 99 27 54 1 1203448016 65306560 5184320
12:31:40 52523 8192000000 10078332144 10513653760 1111414512 11.946 97 13 28 0 1141057536 26565680 3077344
12:33:34 108017 8192000000 10134775168 10589925376 1115928384 11.989 97 14 28 1 1171713024 52653216 3131424
12:36:22 94723 8192000000 10130566496 10657300480 1130259856 12.124 99 22 44 0 1265295120 130163296 4871968
12:38:59 45040 8192000000 10063016016 10503979008 1114621952 11.976 98 19 38 0 1146039280 27840240 3577088
12:40:49 64116 8192000000 10071912992 10520363008 1091331712 11.755 96 15 30 0 1146225792 51991824 2902256
12:43:00 67250 8192000000 10084956544 10504548352 1122894224 12.054 99 41 82 1 1196090128 64577200 8618704
12:45:08 71678 8192000000 10125364656 10586132480 1130659376 12.128 99 19 38 0 1213436976 78914720 3862880
12:47:20 66006 8192000000 10094450448 10531536896 1080043328 11.648 96 16 32 1 1149502320 65915776 3543216
12:49:31 72610 8192000000 10094742128 10542432256 1072875952 11.580 95 13 26 2 1140420640 64827376 2717312
12:51:44 107254 8192000000 10077161168 10530799616 1123623120 12.061 100 31 62 1 1188752736 58571792 6557824
12:54:31 65842 8192000000 10069852864 10546208768 1081983392 11.666 97 9 15 2 1148449760 64206544 2259824
12:56:59 91996 8192000000 10029316864 10473676800 1075052752 11.600 97 5 10 0 1100276656 24247808 976096
12:59:34 83585 8192000000 10053809040 10519011328 1086240464 11.707 97 16 32 1 1157146528 67345104 3560960
13:02:01 26047 8192000000 10111308480 10545201152 1124832304 12.073 100 32 64 0 1219057680 87499024 6726352
13:03:29 52545 8192000000 10140121808 10577612800 1090965328 11.752 96 22 44 0 1184584320 88831040 4787952
13:05:29 93680 8192000000 10083631104 10540654592 1095496032 11.795 97 8 16 0 1157313296 60311648 1505616
13:08:03 17138 8192000000 10080396832 10501476352 1111327184 11.945 98 46 92 3 1148113536 27284912 9501440
13:09:24 71131 8192000000 10078618432 10566991872 1072986432 11.581 94 11 22 2 1165347792 90176624 2184736
13:11:36 99936 8192000000 10064863184 10488598528 1111427456 11.946 98 30 60 0 1143863664 26327664 6108544
13:14:16 68473 8192000000 10052629680 10509496320 1119642448 12.024 99 7 14 1 1151631792 30312320 1677024
13:16:29 85340 8192000000 10069596992 10539114496 1128345744 12.106 99 17 34 0 1158050944 25739152 3966048
13:18:55 60883 8192000000 10054159008 10496348160 1124942272 12.074 99 7 14 0 1154716368 28061008 1713088
13:20:59 53806 8192000000 10070382240 10519285760 1113988720 11.970 98 23 47 1 1170417376 51694112 4734544
13:22:56 83203 8192000000 10073130928 10550734848 1142048944 12.235 100 23 46 0 1208348544 61590832 4708768
13:25:19 91189 8192000000 10106748384 10566766592 1127459808 12.097 98 23 46 2 1201358736 68900928 4998000
13:27:51 37931 8192000000 10092175856 10525868032 1121280816 12.039 99 37 72 1 1176280448 47765392 7234240
13:29:36 51551 8192000000 10079355904 10546565120 1108404000 11.917 98 12 24 0 1180570032 69855920 2310112
13:31:30 83409 8192000000 10066710976 10516221952 1104296000 11.878 99 30 61 0 1145966624 35212352 6458272
13:34:17 42961 8192000000 10033026752 10494193664 1102205840 11.859 99 10 20 1 1129424224 25262304 1956080
13:36:01 48965 8192000000 10028616032 10491895808 1086830752 11.713 97 11 22 0 1129300464 40323952 2145760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-12-2021 13:39:01 to 02-12-2021 13:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 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 02-12-2021 13:39:16 to 02-12-2021 13:39:18.

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          235746
MON$OLDEST_ACTIVE               237041
MON$OLDEST_SNAPSHOT             237041
MON$NEXT_TRANSACTION            237041
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                       2054624
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.2673 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2673 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2673 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2673 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 ---
--- end of diff output ---

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

02.12.21 13:39:19. 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-12-02 13:39:22 No severe PSQL-related problems occured
02.12.21 13:39:23. End of report.