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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16264440 kB
MemAvailable 31590900 kB
Buffers 0 kB
Cached 15442548 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-11-27 10:37:05
DTS_END                         2021-11-27 10:37:05.6640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47276
TRN_ID                          230804
ATT_ID                          605
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: 9700.79 21219 1164095
customer order: creation 165.94 395 19913
customer order: refuse 14.43 314 1731
order to supplier: creation 43.97 1504 5276
order to supplier: removal 3.82 121251 458
invoice (draft): creation 43.53 3476 5223
invoice (draft): removal 57.92 880 6950
invoice accept: apply 40.51 19072 4861
invoice accept: cancel 52.48 852 6298
customer reserve: creation 3841.63 183 460996
customer reserve: removal 5129.68 70 615561
realization accept: apply 78.93 225 9471
realization accept: cancel 104.28 123 12514
payment from customer: creation 57.53 147 6903
payment from customer: removal 5.36 76 643
payment to supplier: creation 46.96 150 5635
payment to supplier: removal 3.37 75 404
service: total inventory turnovers 3.60 3896 432
service: total monetary turnovers 6.18 539 742
service: aggregate perf. data 0.66 25283 79
service: refresh index statistics 0.04 245861 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-11-2021 10:39:27 to 27-11-2021 10:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3939
TEST_TIME 2 5948
TEST_TIME 3 5328
TEST_TIME 4 4856
TEST_TIME 5 4280
TEST_TIME 6 4608
TEST_TIME 7 4759
TEST_TIME 8 5139
TEST_TIME 9 5823
TEST_TIME 10 6053
TEST_TIME 11 6769
TEST_TIME 12 7045
TEST_TIME 13 7498
TEST_TIME 14 7547
TEST_TIME 15 7441
TEST_TIME 16 7560
TEST_TIME 17 7644
TEST_TIME 18 8173
TEST_TIME 19 8326
TEST_TIME 20 8391
TEST_TIME 21 8361
TEST_TIME 22 8189
TEST_TIME 23 8412
TEST_TIME 24 8563
TEST_TIME 25 8907
TEST_TIME 26 9660
TEST_TIME 27 10151
TEST_TIME 28 11109
TEST_TIME 29 12093
TEST_TIME 30 12302
TEST_TIME 31 12036
TEST_TIME 32 11926
TEST_TIME 33 11826
TEST_TIME 34 11724
TEST_TIME 35 11571
TEST_TIME 36 11422
TEST_TIME 37 11394
TEST_TIME 38 11604
TEST_TIME 39 11541
TEST_TIME 40 11492
TEST_TIME 41 11545
TEST_TIME 42 11477
TEST_TIME 43 11336
TEST_TIME 44 11182
TEST_TIME 45 10963
TEST_TIME 46 10977
TEST_TIME 47 10825
TEST_TIME 48 10774
TEST_TIME 49 10758
TEST_TIME 50 10780
TEST_TIME 51 10751
TEST_TIME 52 10801
TEST_TIME 53 10750
TEST_TIME 54 10639
TEST_TIME 55 10636
TEST_TIME 56 10567
TEST_TIME 57 10498
TEST_TIME 58 10576
TEST_TIME 59 10477
TEST_TIME 60 10388
TEST_TIME 61 10314
TEST_TIME 62 10301
TEST_TIME 63 10281
TEST_TIME 64 10209
TEST_TIME 65 10317
TEST_TIME 66 10351
TEST_TIME 67 10463
TEST_TIME 68 10437
TEST_TIME 69 10449
TEST_TIME 70 10459
TEST_TIME 71 10403
TEST_TIME 72 10425
TEST_TIME 73 10394
TEST_TIME 74 10441
TEST_TIME 75 10410
TEST_TIME 76 10312
TEST_TIME 77 10235
TEST_TIME 78 10259
TEST_TIME 79 10211
TEST_TIME 80 10197
TEST_TIME 81 10113
TEST_TIME 82 10048
TEST_TIME 83 10010
TEST_TIME 84 10004
TEST_TIME 85 9920
TEST_TIME 86 10031
TEST_TIME 87 10005
TEST_TIME 88 9998
TEST_TIME 89 9959
TEST_TIME 90 9946
TEST_TIME 91 10024
TEST_TIME 92 9988
TEST_TIME 93 10010
TEST_TIME 94 9997
TEST_TIME 95 10075
TEST_TIME 96 10085
TEST_TIME 97 10045
TEST_TIME 98 10058
TEST_TIME 99 9994
TEST_TIME 100 9905
TEST_TIME 101 9979
TEST_TIME 102 9932
TEST_TIME 103 9870
TEST_TIME 104 9836
TEST_TIME 105 9778
TEST_TIME 106 9817
TEST_TIME 107 9800
TEST_TIME 108 9806
TEST_TIME 109 9824
TEST_TIME 110 9786
TEST_TIME 111 9773
TEST_TIME 112 9808
TEST_TIME 113 9797
TEST_TIME 114 9816
TEST_TIME 115 9862
TEST_TIME 116 9905
TEST_TIME 117 9858
TEST_TIME 118 9783
TEST_TIME 119 9752
TEST_TIME 120 9702

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-11-2021 10:39:30 to 27-11-2021 10:39:33.

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 1590826 1590826 0 0.00 0 25126 7 0 0
doc_list_biud 1590826 1590826 0 0.00 0 62621 34 0 0
sp_add_invoice_to_stock 5014 4861 153 3.05 41 201935 19073 0 153
sp_cancel_adding_invoice 6298 6298 0 0.00 6 51217 853 0 0
sp_cancel_client_order 1731 1731 0 0.00 4 13239 315 0 0
sp_cancel_customer_reserve 615561 615561 0 0.00 1 62622 71 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 5200 76 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 2298 76 0 0
sp_cancel_supplier_invoice 6968 6950 18 0.25 2 62122 880 0 18
sp_cancel_supplier_order 458 458 0 0.00 8 1332671 121252 0 0
sp_cancel_write_off 12514 12514 0 0.00 1 13477 124 0 0
sp_client_order 19913 19913 0 0.00 4 20490 396 0 0
sp_customer_reserve 460996 460996 0 0.00 0 121778 183 0 0
sp_fill_shopping_cart 30401 30401 0 0.00 1 21807 41 0 0
sp_fill_shopping_cart_clo_res 460990 460990 0 0.00 0 13436 14 0 0
sp_get_clo_for_invoice 4855 4855 0 0.00 0 33728 356 0 0
sp_kill_cost_storno 19859 19859 0 0.00 0 13292 64 0 0
sp_kill_qstorno_ret_qs2qd 1251044 1251044 0 0.00 0 62616 38 0 0
sp_kill_qty_storno 657838 657838 0 0.00 0 62621 81 0 0
sp_lock_selected_doc 20013 20013 0 0.00 0 11067 6 0 0
sp_make_cost_storno 26864 26864 0 0.00 0 23477 82 0 0
sp_multiply_rows_for_pdistr 26864 26864 0 0.00 0 14817 39 0 0
sp_multiply_rows_for_qdistr 19913 19913 0 0.00 0 15874 203 0 0
sp_pay_from_customer 6903 6903 0 0.00 4 23704 148 0 0
sp_pay_to_supplier 5635 5635 0 0.00 5 23882 151 0 0
sp_payment_common 12538 12538 0 0.00 1 23681 36 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 0 13230 274 0 0
sp_qd_handle_on_invoice_upd_sts 11153 11153 0 0.00 0 27310 403 0 0
sp_qd_handle_on_reserve_upd_sts 9471 9471 0 0.00 0 13736 54 0 0
sp_reserve_write_off 9471 9471 0 0.00 7 25176 225 0 0
sp_supplier_invoice 5223 5223 0 0.00 8 99473 3476 0 0
sp_supplier_order 5276 5276 0 0.00 6 83313 1505 0 0
srv_aggregate_perf_data 79 79 0 0.00 2770 265562 25284 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 927 120552 44515 0 0
srv_find_qd_qs_mism 1117548 1117548 0 0.00 0 19673 5 0 0
srv_make_invnt_saldo 432 432 0 0.00 60 93308 3896 0 0
srv_make_money_saldo 742 742 0 0.00 1 35069 540 0 0
srv_recalc_idx_stat 5 5 0 0.00 107218 363429 245862 0 0
t$perf-abend:sp_add_invoice_to_stock 153 153 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4861 4861 0 0.00 0 20517 189 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 8266 96 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 2771 9 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 17076 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 13909 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 1723 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 649 10 0 0
t$perf-norm:sp_cancel_supplier_order 458 458 0 0.00 0 24963 1712 0 0
t$perf-norm:sp_cancel_write_off 933 933 0 0.00 0 7487 17 0 0
t$perf-norm:sp_client_order 19913 19913 0 0.00 0 15996 19 0 0
t$perf-norm:sp_customer_reserve 930 930 0 0.00 0 4590 16 0 0
t$perf-norm:sp_pay_from_customer 6903 6903 0 0.00 0 11973 10 0 0
t$perf-norm:sp_pay_to_supplier 5635 5635 0 0.00 0 3738 9 0 0
t$perf-norm:sp_reserve_write_off 9471 9471 0 0.00 0 9128 11 0 0
t$perf-norm:sp_supplier_invoice 5223 5223 0 0.00 0 5647 19 0 0
t$perf-norm:sp_supplier_order 5276 5276 0 0.00 0 6869 19 0 0
t$perf-norm:srv_aggregate_perf_data 79 79 0 0.00 0 89 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 40 1 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 716 11 0 0
t$perf-norm:srv_make_money_saldo 742 742 0 0.00 0 5688 17 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10016 9710 306 3.05 0 7906 18 0 306
v_all_customers 36702 36702 0 0.00 0 25 1 0 0
v_all_suppliers 29296 29296 0 0.00 0 18 1 0 0
v_all_wares 39826 39826 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 100 5 0 0
v_cancel_client_order 3462 3462 0 0.00 0 3596 6 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 1434 6 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 1799 6 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 2290 8 0 0
v_cancel_supplier_invoice 872 836 36 4.12 0 6066 19 0 36
v_cancel_supplier_order 916 916 0 0.00 0 381 3 0 0
v_cancel_write_off 1866 1866 0 0.00 0 1860 14 0 0
v_max_id_clo_ord 5275 5275 0 0.00 0 556 1 0 0
v_max_id_clo_res 930 930 0 0.00 0 3406 22 0 0
v_max_id_ord_sup 5213 5213 0 0.00 0 7574 7 0 0
v_max_non_paid_invoice 1475 1475 0 0.00 0 175 2 0 0
v_max_non_paid_realizn 3387 3387 0 0.00 0 1391 4 0 0
v_min_id_clo_ord 5275 5275 0 0.00 0 7932 11 0 0
v_min_id_clo_res 930 930 0 0.00 0 5873 26 0 0
v_min_id_ord_sup 5213 5213 0 0.00 0 5454 7 0 0
v_min_non_paid_invoice 5635 5635 0 0.00 0 13572 27 0 0
v_min_non_paid_realizn 6903 6903 0 0.00 0 23678 30 0 0
v_our_firm 10156 10156 0 0.00 0 15 1 0 0
v_reserve_write_off 18942 18942 0 0.00 0 5407 5 0 0
x_lock_depdocs_on_canc_invoice 12830 12830 0 0.00 0 12432 120 0 0
x_lock_depdocs_on_canc_sup_ord 458 458 0 0.00 0 9274 320 0 0
x_make_qstorno_1000_1200 5276 5276 0 0.00 2 83252 1420 0 0
x_make_qstorno_1200_2000 5223 5223 0 0.00 3 96124 3329 0 0
x_make_qstorno_2100_3300 460996 460996 0 0.00 0 121777 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-11-2021 10:39:34 to 27-11-2021 10:39:38.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:09 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:10 29147 8192000000 9718934320 10015752192 796705888 8.863 100 95 190 0 827736096 8016544 23013664
08:09:42 23066 8192000000 9754587632 10059182080 824826368 9.147 100 93 186 1 860205696 12875136 22504192
08:11:10 21423 8192000000 9794904144 10112122880 859706048 9.497 100 81 162 1 905192112 26796240 18689824
08:12:35 15257 8192000000 9857353920 10188206080 908038080 9.978 100 86 172 1 972445472 45628800 18778592
08:13:53 10230 8192000000 9898338928 10240159744 945308048 10.345 100 81 161 1 1016703296 53899440 17495808
08:15:07 7253 8192000000 9953762768 10311774208 989313936 10.775 100 70 140 1 1066681184 62994256 14372992
08:16:16 3189 8192000000 9987612480 10354827264 1023921600 11.110 100 75 150 0 1105035104 65480608 15632896
08:17:21 1675 8192000000 10035250768 10402729984 1047328624 11.335 100 93 186 0 1149124784 83642416 18153744
08:18:24 5101 8192000000 10016389360 10397851648 1062941072 11.485 100 59 118 0 1126809584 51980416 11888096
08:19:31 5133 8192000000 9994342992 10377224192 1069757584 11.550 100 41 79 1 1098760256 20677136 8325536
08:20:37 46965 8192000000 9995634640 10417758208 1076714368 11.616 100 40 80 1 1100138480 14610176 8813936
08:22:30 4021 8192000000 10013372928 10415390720 1080964528 11.657 100 44 88 1 1120262512 30162608 9135376
08:23:36 8112 8192000000 10009029152 10421739520 1088028336 11.724 100 38 76 2 1125407904 28762176 8617392
08:24:45 5915 8192000000 10007743984 10411888640 1093956304 11.780 100 31 62 0 1112899840 12195232 6748304
08:25:55 28567 8192000000 10008507232 10424774656 1082353472 11.670 99 22 44 0 1111936304 25040944 4541888
08:27:26 4592 8192000000 9990827408 10373111808 1065081744 11.505 99 40 79 1 1094304176 19582848 9639584
08:28:31 5989 8192000000 9965259824 10354884608 1044142240 11.304 98 33 67 1 1071956144 19900496 7913408
08:29:40 11166 8192000000 9937220944 10320457728 1014572944 11.020 98 36 72 1 1043390336 20884064 7933328
08:30:53 8552 8192000000 9954998432 10319065088 971730352 10.604 94 68 137 2 1014467952 27388656 15348944
08:32:02 7383 8192000000 9941282000 10333052928 1013230192 11.007 99 35 71 0 1057190656 36427952 7532512
08:33:12 6292 8192000000 9966180416 10342789120 1036432128 11.230 99 40 79 2 1061170032 15457344 9280560
08:34:23 80194 8192000000 9977510656 10381307904 1060492656 11.461 100 24 47 2 1081336496 15848032 4995808
08:36:47 6163 8192000000 10002983632 10397700096 1075644800 11.606 100 59 116 1 1107889600 19293536 12951264
08:37:57 16089 8192000000 10045204800 10437050368 1100367072 11.841 100 74 147 1 1145904384 29514576 16022736
08:39:15 36178 8192000000 10050455392 10457227264 1108648592 11.920 100 49 96 3 1195081728 76194608 10238528
08:41:12 21221 8192000000 10106882368 10512793600 1129705840 12.119 100 40 78 1 1218151872 80459360 7986672
08:42:44 19496 8192000000 10157045904 10582065152 1143101840 12.245 100 39 77 2 1262986656 111520848 8363968
08:44:06 23653 8192000000 10202688784 10616774656 1159400752 12.398 100 46 92 0 1309915168 140574384 9940032
08:45:38 4532 8192000000 10237092256 10657374208 1164796752 12.448 100 55 110 0 1337569824 160493088 12279984
08:46:46 8206 8192000000 10256796800 10662211584 1169184288 12.489 100 77 154 1 1364464016 178844480 16435248
08:48:03 3932 8192000000 10279018896 10697740288 1159922224 12.403 99 81 159 0 1377316656 199454080 17940352
08:49:11 3681 8192000000 10312388800 10722856960 1182142848 12.610 100 81 157 1 1413992928 213909168 17940912
08:50:19 3154 8192000000 10301114448 10738855936 1183545712 12.623 100 68 136 1 1408266960 208014368 16706880
08:51:26 4634 8192000000 10315523472 10738503680 1189994832 12.683 100 79 155 2 1430826928 222759120 18072976
08:52:38 2021 8192000000 10373724704 10776260608 1182700288 12.615 99 89 176 2 1463750160 260144192 20905680
08:53:46 962 8192000000 10415353584 10817638400 1195126128 12.731 100 96 190 1 1513272464 297162624 20983712
08:54:54 923 8192000000 10449003152 10847772672 1187448512 12.660 100 95 188 1 1546185728 336659376 22077840
08:55:57 3374 8192000000 10417915424 10833420288 1161815920 12.420 99 60 115 2 1520103424 344361280 13926224
08:57:02 9120 8192000000 10460273664 10877943808 1152848480 12.336 99 47 91 0 1549460912 384903728 11708704
08:58:15 7631 8192000000 10509888096 10925314048 1150891664 12.318 100 46 90 0 1608832400 446340800 11599936
08:59:26 4168 8192000000 10581426944 10991915008 1147476624 12.286 99 62 123 1 1672781152 508956816 16347712
09:00:37 9029 8192000000 10633468128 11070050304 1155837184 12.364 99 51 96 0 1728004400 559399024 12768192
09:01:51 28591 8192000000 10720769088 11153674240 1155484848 12.361 100 42 84 0 1794490880 627624192 11381840
09:03:20 17311 8192000000 10659063440 11186757632 1149542448 12.305 100 34 68 0 1798280432 639591920 9146064
09:04:51 22953 8192000000 10504187696 10976215040 1115009680 11.980 100 23 46 0 1608528016 486671696 6846640
09:06:49 63066 8192000000 10054995232 10542424064 1115653280 11.986 100 13 26 0 1199727696 81435616 2638800
09:08:52 73707 8192000000 10041329248 10479222784 1082709696 11.673 97 15 30 0 1108160288 22587696 2862896
09:11:07 101209 8192000000 10054917344 10481070080 1088045888 11.724 96 21 42 1 1135960512 44127008 3787616
09:13:58 68157 8192000000 10165124880 10618961920 1151247520 12.321 100 17 34 0 1246097616 91110752 3739344
09:16:07 62288 8192000000 10095861920 10541248512 1124655600 12.071 99 14 28 1 1191554144 63787040 3111504
09:18:10 102213 8192000000 10080463472 10536894464 1127231248 12.095 99 34 68 3 1164427984 29641712 7555024
09:20:53 68371 8192000000 10100446736 10539511808 1137577344 12.193 100 23 46 2 1190015840 47994864 4443632
09:23:37 46340 8192000000 10125660400 10567254016 1147730112 12.288 100 56 111 1 1256174400 96718992 11725296
09:25:27 17556 8192000000 10145866720 10579783680 1147554336 12.287 99 39 80 1 1233769360 78063664 8151360
09:26:46 69052 8192000000 10148116960 10620760064 1127413168 12.097 98 20 39 2 1209113648 76315792 5384688
09:28:57 24030 8192000000 10150230256 10566709248 1137117088 12.188 100 72 142 2 1228453664 75632896 15703680
09:30:25 50666 8192000000 10105798896 10588160000 1133477488 12.154 99 9 18 0 1199483344 64027248 1978608
09:32:16 111147 8192000000 10122933568 10581700608 1124197328 12.067 98 17 34 2 1219304592 91133136 3974128
09:35:24 48387 8192000000 10067127888 10598449152 1125784288 12.082 99 9 18 0 1172948048 45477376 1686384
09:37:21 63975 8192000000 10085281344 10558402560 1094978976 11.790 97 14 28 2 1166902656 69211696 2711984
09:39:43 104042 8192000000 10130639392 10606534656 1137203280 12.189 100 11 22 0 1189859456 50538272 2117904
09:42:27 54345 8192000000 10134521744 10591125504 1119245280 12.020 97 15 30 2 1202787008 80400496 3141232
09:44:23 61816 8192000000 10049997296 10513412096 1069190448 11.544 94 11 22 0 1129769472 58309584 2269440
09:46:25 23298 8192000000 10102995568 10555547648 1132004048 12.140 100 37 74 0 1190578320 51131136 7443136
09:47:49 30914 8192000000 10072534560 10492461056 1109653312 11.929 98 28 56 2 1145373760 30069168 5651280
09:49:25 47939 8192000000 10058683584 10518855680 1077807696 11.627 95 21 42 0 1135246352 52877504 4561152
09:51:13 96249 8192000000 10061576624 10492211200 1088898368 11.732 97 26 52 0 1167079088 73104960 5075760
09:53:55 81475 8192000000 10044172736 10517970944 1088580752 11.729 98 41 82 1 1183990560 86563392 8846416
09:56:20 10223 8192000000 10044018064 10486788096 1107286496 11.907 100 26 52 0 1145843904 32853808 5703600
09:57:30 85360 8192000000 10051577904 10521640960 1080413600 11.651 97 8 16 0 1127899200 45986288 1499312
09:59:56 62712 8192000000 10119295664 10589986816 1108480192 11.918 99 15 30 1 1212163344 99691968 3991184
10:02:00 70404 8192000000 10114742560 10587926528 1120785168 12.034 100 15 30 0 1211583296 87429648 3368480
10:04:12 70223 8192000000 10038139520 10489921536 1095508752 11.795 98 10 20 2 1138069568 40383152 2177664
10:06:32 42638 8192000000 10082020016 10528182272 1116347104 11.992 99 26 52 0 1187565392 65884352 5333936
10:08:29 43181 8192000000 10070504176 10507034624 1114422480 11.974 99 33 65 1 1152043648 31271632 6349536
10:10:16 21846 8192000000 10132421328 10566213632 1132746608 12.147 100 49 98 0 1208844928 66736400 9361920
10:11:48 89052 8192000000 10086726560 10613166080 1124099024 12.066 99 13 26 0 1196980480 70111472 2769984
10:14:18 37626 8192000000 10082040144 10522681344 1110618400 11.938 98 23 46 0 1151298304 36131040 4548864
10:16:55 92749 8192000000 10132330832 10578104320 1119718912 12.024 98 18 36 0 1221596816 98014480 3863424
10:19:28 66450 8192000000 10075475296 10540609536 1125615936 12.080 99 6 12 1 1170232240 43481056 1135248
10:21:35 57872 8192000000 10115022720 10563846144 1131210944 12.133 99 19 36 2 1202975920 67503920 4261056
10:24:32 67260 8192000000 10099585888 10575249408 1117592304 12.004 98 17 34 0 1183100944 61719824 3788816
10:26:40 49725 8192000000 10109822816 10557378560 1124792640 12.072 98 20 40 0 1189284064 60093856 4397568
10:28:34 56039 8192000000 10121736336 10613964800 1106437856 11.899 97 25 48 1 1170370816 58586928 5346032
10:30:35 50267 8192000000 10117989568 10585165824 1103090544 11.867 97 31 63 0 1198133856 88446064 6597248
10:32:47 33595 8192000000 10096912688 10527207424 1117271504 12.001 99 24 48 0 1173669632 51694720 4703408
10:34:31 120551 8192000000 10051092864 10515132416 1099499792 11.833 98 22 44 0 1144514080 40062960 4951328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-11-2021 10:39:40 to 27-11-2021 10:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 153 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-11-2021 10:39:56 to 27-11-2021 10:39:58.

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          230180
MON$OLDEST_ACTIVE               230914
MON$OLDEST_SNAPSHOT             230914
MON$NEXT_TRANSACTION            230914
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                       2091648
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2669 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2669 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2669 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2669 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 ---
:79: 
:80: mscs-test-161.budzdorov.ru    Sat Nov 27 10:37:29 2021
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Sat Nov 27 10:37:29 2021
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Sat Nov 27 10:37:29 2021
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
--- end of diff output ---

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

27.11.21 10:39:59. 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-11-27 10:40:01 No severe PSQL-related problems occured
27.11.21 10:40:02. End of report.