Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.06.2021 13: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 16397484 kB
MemAvailable 31341448 kB
Buffers 0 kB
Cached 15211904 kB
SwapCached 2408 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-20 13:27:00
DTS_END                         2021-06-20 13:27:00.3580
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/57578
TRN_ID                          236454
ATT_ID                          654
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: 9424.05 23620 1130886
customer order: creation 171.09 384 20531
customer order: refuse 14.72 316 1766
order to supplier: creation 46.18 1340 5541
order to supplier: removal 3.62 114200 434
invoice (draft): creation 47.39 3396 5687
invoice (draft): removal 54.15 889 6498
invoice accept: apply 41.75 15816 5010
invoice accept: cancel 48.63 850 5836
customer reserve: creation 3904.08 150 468489
customer reserve: removal 4793.27 68 575192
realization accept: apply 82.43 173 9892
realization accept: cancel 87.20 110 10464
payment from customer: creation 60.07 115 7208
payment from customer: removal 5.67 91 680
payment to supplier: creation 49.68 125 5961
payment to supplier: removal 3.37 76 404
service: total inventory turnovers 3.58 3931 430
service: total monetary turnovers 6.27 484 752
service: aggregate perf. data 0.89 16395 107
service: refresh index statistics 0.03 313498 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-06-2021 13:28:24 to 20-06-2021 13:28:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6758
TEST_TIME 2 6041
TEST_TIME 3 5746
TEST_TIME 4 5265
TEST_TIME 5 5111
TEST_TIME 6 5497
TEST_TIME 7 5756
TEST_TIME 8 5788
TEST_TIME 9 5975
TEST_TIME 10 6281
TEST_TIME 11 6491
TEST_TIME 12 6878
TEST_TIME 13 7440
TEST_TIME 14 7620
TEST_TIME 15 8322
TEST_TIME 16 8291
TEST_TIME 17 8393
TEST_TIME 18 8792
TEST_TIME 19 8567
TEST_TIME 20 8725
TEST_TIME 21 9438
TEST_TIME 22 9542
TEST_TIME 23 9268
TEST_TIME 24 9605
TEST_TIME 25 9928
TEST_TIME 26 10981
TEST_TIME 27 10978
TEST_TIME 28 12035
TEST_TIME 29 12015
TEST_TIME 30 11846
TEST_TIME 31 11914
TEST_TIME 32 11628
TEST_TIME 33 11445
TEST_TIME 34 11360
TEST_TIME 35 11194
TEST_TIME 36 10947
TEST_TIME 37 10949
TEST_TIME 38 10823
TEST_TIME 39 10681
TEST_TIME 40 10859
TEST_TIME 41 10866
TEST_TIME 42 10803
TEST_TIME 43 10668
TEST_TIME 44 10581
TEST_TIME 45 10506
TEST_TIME 46 10540
TEST_TIME 47 10507
TEST_TIME 48 10436
TEST_TIME 49 10456
TEST_TIME 50 10565
TEST_TIME 51 10436
TEST_TIME 52 10519
TEST_TIME 53 10514
TEST_TIME 54 10549
TEST_TIME 55 10500
TEST_TIME 56 10547
TEST_TIME 57 10550
TEST_TIME 58 10374
TEST_TIME 59 10240
TEST_TIME 60 10158
TEST_TIME 61 10111
TEST_TIME 62 10013
TEST_TIME 63 9948
TEST_TIME 64 9895
TEST_TIME 65 9859
TEST_TIME 66 9845
TEST_TIME 67 9852
TEST_TIME 68 9875
TEST_TIME 69 9905
TEST_TIME 70 9834
TEST_TIME 71 9796
TEST_TIME 72 9794
TEST_TIME 73 9962
TEST_TIME 74 9932
TEST_TIME 75 9915
TEST_TIME 76 9978
TEST_TIME 77 9906
TEST_TIME 78 9816
TEST_TIME 79 9782
TEST_TIME 80 9708
TEST_TIME 81 9643
TEST_TIME 82 9600
TEST_TIME 83 9554
TEST_TIME 84 9509
TEST_TIME 85 9542
TEST_TIME 86 9551
TEST_TIME 87 9533
TEST_TIME 88 9710
TEST_TIME 89 9736
TEST_TIME 90 9693
TEST_TIME 91 9679
TEST_TIME 92 9836
TEST_TIME 93 9837
TEST_TIME 94 9825
TEST_TIME 95 9752
TEST_TIME 96 9778
TEST_TIME 97 9721
TEST_TIME 98 9728
TEST_TIME 99 9713
TEST_TIME 100 9702
TEST_TIME 101 9746
TEST_TIME 102 9724
TEST_TIME 103 9713
TEST_TIME 104 9697
TEST_TIME 105 9758
TEST_TIME 106 9725
TEST_TIME 107 9732
TEST_TIME 108 9765
TEST_TIME 109 9716
TEST_TIME 110 9638
TEST_TIME 111 9597
TEST_TIME 112 9575
TEST_TIME 113 9548
TEST_TIME 114 9511
TEST_TIME 115 9538
TEST_TIME 116 9483
TEST_TIME 117 9457
TEST_TIME 118 9503
TEST_TIME 119 9503
TEST_TIME 120 9426

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-06-2021 13:28:28 to 20-06-2021 13:28:32.

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 1562352 1562352 0 0.00 0 14016 6 0 0
doc_list_biud 1562352 1562352 0 0.00 0 99510 32 0 0
sp_add_invoice_to_stock 5062 5010 52 1.02 21 145794 15817 0 52
sp_cancel_adding_invoice 5836 5836 0 0.00 3 73008 850 0 0
sp_cancel_client_order 1766 1766 0 0.00 5 9011 317 0 0
sp_cancel_customer_reserve 575192 575192 0 0.00 1 64681 69 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 7372 92 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 4039 76 0 0
sp_cancel_supplier_invoice 6506 6498 8 0.12 1 99515 889 0 8
sp_cancel_supplier_order 434 434 0 0.00 7 1222197 114201 0 0
sp_cancel_write_off 10464 10464 0 0.00 1 8082 111 0 0
sp_client_order 20531 20531 0 0.00 4 47313 385 0 0
sp_customer_reserve 468489 468489 0 0.00 0 49933 150 0 0
sp_fill_shopping_cart 31747 31747 0 0.00 1 21583 43 0 0
sp_fill_shopping_cart_clo_res 468484 468484 0 0.00 0 16523 16 0 0
sp_get_clo_for_invoice 5005 5005 0 0.00 0 29011 425 0 0
sp_kill_cost_storno 17384 17384 0 0.00 0 10290 56 0 0
sp_kill_qstorno_ret_qs2qd 1167780 1167780 0 0.00 0 99508 37 0 0
sp_kill_qty_storno 615087 615087 0 0.00 0 99510 79 0 0
sp_lock_selected_doc 20709 20709 0 0.00 0 9803 7 0 0
sp_make_cost_storno 28066 28066 0 0.00 0 13377 57 0 0
sp_multiply_rows_for_pdistr 28066 28066 0 0.00 0 10254 32 0 0
sp_multiply_rows_for_qdistr 20531 20531 0 0.00 0 12217 227 0 0
sp_pay_from_customer 7208 7208 0 0.00 4 10280 116 0 0
sp_pay_to_supplier 5961 5961 0 0.00 5 13905 126 0 0
sp_payment_common 13169 13169 0 0.00 1 9289 21 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 0 9002 293 0 0
sp_qd_handle_on_invoice_upd_sts 10841 10841 0 0.00 0 24450 391 0 0
sp_qd_handle_on_reserve_upd_sts 9892 9892 0 0.00 0 15827 49 0 0
sp_reserve_write_off 9892 9892 0 0.00 7 17600 174 0 0
sp_supplier_invoice 5687 5687 0 0.00 7 109262 3397 0 0
sp_supplier_order 5541 5541 0 0.00 5 85170 1340 0 0
srv_aggregate_perf_data 107 107 0 0.00 2814 166424 16396 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1983 189601 43484 0 0
srv_find_qd_qs_mism 1081598 1081598 0 0.00 0 8846 4 0 0
srv_make_invnt_saldo 430 430 0 0.00 55 93606 3932 0 0
srv_make_money_saldo 752 752 0 0.00 1 10868 484 0 0
srv_recalc_idx_stat 4 4 0 0.00 151581 385756 313499 0 0
t$perf-abend:sp_add_invoice_to_stock 52 52 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5010 5010 0 0.00 0 19032 173 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 5029 90 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 1367 12 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 555 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 3519 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 262 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 2493 24 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 22128 1459 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 6276 9 0 0
t$perf-norm:sp_client_order 20531 20531 0 0.00 0 13658 19 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 452 4 0 0
t$perf-norm:sp_pay_from_customer 7208 7208 0 0.00 0 10742 9 0 0
t$perf-norm:sp_pay_to_supplier 5961 5961 0 0.00 0 7474 8 0 0
t$perf-norm:sp_reserve_write_off 9892 9892 0 0.00 0 4439 7 0 0
t$perf-norm:sp_supplier_invoice 5687 5687 0 0.00 0 4123 15 0 0
t$perf-norm:sp_supplier_order 5541 5541 0 0.00 0 6840 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 159 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 83 3 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 3442 19 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 911 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10114 10010 104 1.02 0 4364 15 0 104
v_all_customers 37932 37932 0 0.00 0 16 1 0 0
v_all_suppliers 31262 31262 0 0.00 0 24 1 0 0
v_all_wares 41060 41060 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1054 8 0 0
v_cancel_client_order 3532 3532 0 0.00 0 2618 4 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 6329 8 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 1214 5 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 468 4 0 0
v_cancel_supplier_invoice 898 882 16 1.78 0 1241 14 0 16
v_cancel_supplier_order 868 868 0 0.00 0 3477 7 0 0
v_cancel_write_off 2016 2016 0 0.00 0 5061 17 0 0
v_max_id_clo_ord 5536 5536 0 0.00 0 2896 2 0 0
v_max_id_clo_res 994 994 0 0.00 0 3147 13 0 0
v_max_id_ord_sup 5680 5680 0 0.00 0 10425 8 0 0
v_max_non_paid_invoice 1546 1546 0 0.00 0 39 2 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 4369 4 0 0
v_min_id_clo_ord 5536 5536 0 0.00 0 5887 8 0 0
v_min_id_clo_res 994 994 0 0.00 0 1174 4 0 0
v_min_id_ord_sup 5680 5680 0 0.00 0 4855 6 0 0
v_min_non_paid_invoice 5961 5961 0 0.00 0 5446 12 0 0
v_min_non_paid_realizn 7208 7208 0 0.00 0 6734 15 0 0
v_our_firm 10252 10252 0 0.00 0 20 1 0 0
v_reserve_write_off 19782 19782 0 0.00 0 5870 6 0 0
x_lock_depdocs_on_canc_invoice 11893 11893 0 0.00 0 14890 115 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 11613 369 0 0
x_make_qstorno_1000_1200 5541 5541 0 0.00 2 85164 1257 0 0
x_make_qstorno_1200_2000 5687 5687 0 0.00 3 103201 3239 0 0
x_make_qstorno_2100_3300 468489 468489 0 0.00 0 49931 131 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-06-2021 13:28:33 to 20-06-2021 13:28:37.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:57:04 1 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
10:58:05 46152 8192000000 9740552528 10044837888 812706544 9.025 100 83 166 1 840127488 7555584 19865360
10:59:54 36922 8192000000 9789321760 10101121024 855707392 9.457 100 91 182 1 895778432 18852880 21218160
11:01:34 20786 8192000000 9840093440 10162020352 894433424 9.843 100 87 174 1 943317248 28759968 20123856
11:03:02 17548 8192000000 9901523392 10238939136 930252592 10.197 100 84 168 2 1006118640 57544000 18322048
11:04:29 8491 8192000000 9931955504 10285469696 977606912 10.661 100 72 144 0 1054743984 62686944 14450128
11:05:39 2948 8192000000 10004952368 10361651200 1010386880 10.979 100 84 168 0 1114779376 87717536 16674960
11:06:44 2848 8192000000 9991775760 10360520704 1044479632 11.308 100 77 154 1 1111315408 50730000 16105776
11:07:49 1830 8192000000 10038801888 10412765184 1067209232 11.525 100 85 167 2 1149418352 64731584 17477536
11:08:51 2227 8192000000 10013677488 10400804864 1077244624 11.621 100 54 108 2 1131701696 43031984 11425088
11:09:54 3578 8192000000 9999301136 10388500480 1085413616 11.699 100 46 90 4 1109863520 13850544 10599360
11:10:58 61796 8192000000 10003801520 10420162560 1092843920 11.770 100 28 56 1 1119767280 21153472 5769888
11:13:00 8551 8192000000 10018217248 10428628992 1097727824 11.816 100 44 89 0 1142482048 35211968 9542256
11:14:11 5383 8192000000 10049610032 10449043456 1107818016 11.912 100 53 105 6 1163555344 45172880 10564448
11:15:21 11957 8192000000 10017629648 10426638336 1107454480 11.908 100 27 54 1 1130182832 15875744 6852608
11:16:35 27250 8192000000 10029420800 10442473472 1099522608 11.833 99 34 68 1 1134760576 28126448 7111520
11:18:03 4419 8192000000 10006908784 10400710656 1078190096 11.630 99 46 92 1 1105500432 16622816 10687520
11:19:09 5181 8192000000 9950647264 10337685504 1007042672 10.947 96 50 98 1 1042009472 24109680 10857120
11:20:16 14235 8192000000 9928342400 10328502272 1004249488 10.920 97 48 96 1 1038463568 23172352 11041728
11:21:39 10720 8192000000 9924026880 10315382784 982041440 10.704 96 19 38 1 1007854288 21303344 4509504
11:22:50 5888 8192000000 9948934480 10333233152 1033171520 11.199 100 50 98 0 1053115280 8900000 11043760
11:23:58 6622 8192000000 9977900432 10373545984 1047574064 11.337 100 53 107 2 1081011616 21195536 12242016
11:25:06 43254 8192000000 9963375824 10366111744 1060197328 11.458 100 28 55 2 1083943920 17781296 5965296
11:26:51 6023 8192000000 9988780368 10387193856 1071675984 11.568 100 43 82 2 1095677728 15224960 8776784
11:28:02 39643 8192000000 10046526720 10449063936 1100644048 11.844 100 54 108 1 1167674688 55878784 11151856
11:29:54 14048 8192000000 10084268096 10490118144 1126158160 12.085 100 57 113 2 1203760320 65313136 12289024
11:31:16 35756 8192000000 10124709600 10549510144 1138266624 12.199 100 26 52 0 1234337200 90409312 5661264
11:33:04 26831 8192000000 10169492192 10609762304 1149359568 12.303 100 44 88 0 1291380576 131651200 10369808
11:34:34 9426 8192000000 10172994672 10609967104 1158676336 12.391 100 48 96 1 1297180224 126848256 11655632
11:35:46 11689 8192000000 10212557584 10629709824 1157753104 12.382 99 51 100 3 1317218432 148398016 11067312
11:37:01 2673 8192000000 10241449264 10654986240 1170331376 12.500 100 58 116 1 1349071648 165123792 13616480
11:38:09 3680 8192000000 10249241984 10665861120 1179157728 12.582 100 62 125 0 1358725312 165929360 13638224
11:39:16 2823 8192000000 10309484416 10719158272 1176816128 12.560 100 80 160 0 1412454048 217946448 17691472
11:40:24 2939 8192000000 10315682560 10727063552 1169524608 12.492 99 70 139 1 1406329968 220612336 16193024
11:41:32 2271 8192000000 10292974352 10701901824 1156556416 12.371 99 79 154 0 1389180976 215551472 17073088
11:42:38 8033 8192000000 10305711792 10748575744 1162178304 12.424 100 43 86 0 1429520752 256904128 10438320
11:43:50 3565 8192000000 10352592352 10765361152 1165208512 12.452 100 71 141 0 1455803632 274244176 16350944
11:45:01 1981 8192000000 10398973952 10815496192 1169134432 12.489 100 75 147 0 1498145744 311796160 17215152
11:46:11 6123 8192000000 10341968416 10756849664 1139538640 12.211 99 47 93 0 1435090016 283992208 11559168
11:47:24 3135 8192000000 10460682832 10878210048 1153508976 12.342 100 55 110 0 1558341824 391634688 13198160
11:48:34 6427 8192000000 10392374560 10811785216 1154902224 12.355 100 47 95 1 1493465248 326908432 11654592
11:49:44 11687 8192000000 10382329312 10824417280 1152542352 12.333 100 33 63 1 1484181088 323510608 8128128
11:51:00 17742 8192000000 10452345008 10931605504 1148433712 12.295 100 32 64 0 1565105184 407759792 8911680
11:52:20 2154 8192000000 10458331104 10877337600 1150222032 12.312 100 83 166 2 1548802224 379227760 19352432
11:53:25 26684 8192000000 10275411552 10776707072 1140769120 12.223 98 25 50 0 1392485888 245810816 5905952
11:54:58 23617 8192000000 10265886656 10818412544 1109276384 11.926 99 14 28 0 1366591856 253644032 3671440
11:57:14 25562 8192000000 10085706144 10516787200 1118220496 12.010 99 47 94 1 1162065728 34325296 9519936
11:58:42 53551 8192000000 10053989424 10472759296 1118498096 12.013 100 29 54 1 1149352848 25395920 5458832
12:00:51 189599 8192000000 10108304496 10545803264 1107510944 11.909 97 43 86 2 1163417968 46533440 9373584
12:05:07 71272 8192000000 10140266528 10613399552 1124491456 12.069 99 20 40 1 1239982432 110710832 4780144
12:07:25 33121 8192000000 10125399120 10632359936 1132736176 12.147 100 9 18 0 1246407296 111518208 2152912
12:09:00 46718 8192000000 10102588032 10604060672 1142370960 12.238 100 31 62 0 1230570000 81547712 6651328
12:11:25 44014 8192000000 10092099072 10576240640 1144628432 12.259 100 13 26 0 1209778880 62152736 2997712
12:14:09 55915 8192000000 10094266000 10549256192 1124683088 12.071 98 15 30 1 1168929184 41072496 3173600
12:16:12 63934 8192000000 10134864960 10600255488 1141582032 12.230 99 20 40 0 1236738464 90786080 4370352
12:18:19 59573 8192000000 10114337680 10580934656 1134609504 12.165 99 30 60 0 1200460160 59628496 6222160
12:20:22 118465 8192000000 10082742096 10579820544 1090320128 11.746 95 10 20 0 1155657408 63320576 2016704
12:23:25 83694 8192000000 10081003344 10571886592 1140128560 12.217 99 10 20 0 1194093936 51994448 1970928
12:26:07 54790 8192000000 10073558016 10528665600 1143675808 12.250 99 36 72 0 1167251760 15813088 7762864
12:28:28 36868 8192000000 10067980320 10544611328 1129258272 12.114 98 6 12 0 1155452544 25198432 995840
12:30:15 36818 8192000000 10026356768 10483503104 1069584880 11.548 94 5 10 1 1088966000 18510512 870608
12:31:52 39267 8192000000 10073578160 10510151680 1061114352 11.467 94 27 53 1 1118361856 52119376 5128128
12:33:32 34351 8192000000 10090081600 10543968256 1110956400 11.941 99 39 78 1 1178255088 58650880 8647808
12:35:07 52772 8192000000 10088851632 10538483712 1117240736 12.001 99 22 44 0 1177375248 55413344 4721168
12:37:13 33727 8192000000 10098019552 10529378304 1109498240 11.928 98 35 68 1 1161475408 44859232 7117936
12:38:47 27293 8192000000 10108905664 10549272576 1116231360 11.991 99 32 62 1 1216495712 93738384 6525968
12:40:18 78193 8192000000 10067818400 10570182656 1074767968 11.598 96 15 30 0 1147415920 69452352 3195600
12:42:37 53755 8192000000 10102386672 10536595456 1093982976 11.781 98 25 50 0 1147587552 48506512 5098064
12:44:33 121468 8192000000 10023192688 10477191168 1087961184 11.723 97 25 50 1 1124966704 32095536 4909984
12:47:35 48754 8192000000 10044561472 10492588032 1113273472 11.963 100 12 24 1 1147635632 32003216 2358944
12:50:39 59566 8192000000 10130561808 10577903616 1111135200 11.943 97 19 38 1 1191880368 76934496 3810672
12:52:40 49374 8192000000 10122789760 10561773568 1087013792 11.714 97 28 56 0 1216037216 121937488 7085936
12:54:32 63045 8192000000 10154976496 10682306560 1131414848 12.135 100 16 32 0 1248715184 113778448 3521888
12:57:06 35126 8192000000 10088536544 10525560832 1119966048 12.027 99 31 60 1 1173720208 47212272 6541888
12:58:43 65038 8192000000 10087488960 10611548160 1129563984 12.117 99 11 22 1 1250646592 118573280 2509328
13:00:49 75914 8192000000 10052716272 10489806848 1099600368 11.834 97 9 19 1 1125144992 23710496 1834128
13:03:07 59046 8192000000 10101316256 10611613696 1139408640 12.210 100 8 16 0 1214958256 73439056 2110560
13:05:06 74220 8192000000 10075384400 10546245632 1140834768 12.223 100 16 33 0 1184252368 40100064 3317536
13:07:21 38512 8192000000 10100875424 10561703936 1145274880 12.265 100 20 40 1 1203640800 54532336 3833584
13:09:06 70867 8192000000 10080021184 10521395200 1141952592 12.234 99 9 17 0 1182502464 38570432 1979440
13:11:20 40101 8192000000 10139150304 10599845888 1135212560 12.170 98 45 89 0 1196984992 53037088 8735344
13:13:04 80038 8192000000 10125620576 10590294016 1097437216 11.813 95 14 28 1 1128879136 28722400 2719520
13:15:30 26257 8192000000 10051221744 10494398464 1062795248 11.483 94 7 14 0 1102351296 38297824 1258224
13:17:24 73476 8192000000 10054570208 10503983104 1081935648 11.666 97 22 43 0 1137629008 51164992 4528368
13:19:58 87189 8192000000 10069490032 10574024704 1109621072 11.929 99 12 25 1 1173457760 61257376 2579312
13:22:28 42018 8192000000 10067429264 10541162496 1114480528 11.975 99 9 18 0 1161661136 45139088 2041520
13:24:11 78956 8192000000 10169430544 10623033344 1125358256 12.078 99 22 44 0 1263642288 133220320 5063712
13:26:37 37794 8192000000 9124268496 9427337216 264487088 3.127 23 6 11 1 302101392 36233568 1380736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-06-2021 13:28:39 to 20-06-2021 13:28:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 52 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 20-06-2021 13:28:55 to 20-06-2021 13:28:56.

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          237238
MON$OLDEST_ACTIVE               237239
MON$OLDEST_SNAPSHOT             237239
MON$NEXT_TRANSACTION            237239
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                       2125376
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             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2519 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2519 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2519 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2519 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 ---
--- end of diff output ---

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

20.06.21 13:28:57. 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-20 13:29:00 No severe PSQL-related problems occured
20.06.21 13:29:01. End of report.