Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.10.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 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 15762020 kB
MemAvailable 30736108 kB
Buffers 0 kB
Cached 15972196 kB
SwapCached 3864 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-10 13:37:09
DTS_END                         2021-10-10 13:37:09.5560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37176
TRN_ID                          232752
ATT_ID                          649
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: 8956.86 23398 1074823
customer order: creation 169.73 396 20368
customer order: refuse 14.85 371 1782
order to supplier: creation 45.45 1502 5454
order to supplier: removal 3.23 141920 388
invoice (draft): creation 45.38 3567 5445
invoice (draft): removal 49.51 1252 5941
invoice accept: apply 40.56 22368 4867
invoice accept: cancel 44.37 1029 5324
customer reserve: creation 3828.39 218 459407
customer reserve: removal 4426.53 79 531184
realization accept: apply 81.88 212 9826
realization accept: cancel 79.14 152 9497
payment from customer: creation 59.46 125 7135
payment from customer: removal 5.73 112 688
payment to supplier: creation 48.63 134 5835
payment to supplier: removal 3.18 104 381
service: total inventory turnovers 3.97 3858 476
service: total monetary turnovers 6.01 485 721
service: aggregate perf. data 0.83 15515 100
service: refresh index statistics 0.03 274560 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-10-2021 13:38:51 to 10-10-2021 13:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4381
TEST_TIME 2 3592
TEST_TIME 3 2764
TEST_TIME 4 3917
TEST_TIME 5 3899
TEST_TIME 6 4297
TEST_TIME 7 4667
TEST_TIME 8 4794
TEST_TIME 9 5500
TEST_TIME 10 5604
TEST_TIME 11 5521
TEST_TIME 12 5517
TEST_TIME 13 5788
TEST_TIME 14 6168
TEST_TIME 15 6503
TEST_TIME 16 6435
TEST_TIME 17 6571
TEST_TIME 18 6710
TEST_TIME 19 6968
TEST_TIME 20 6908
TEST_TIME 21 7176
TEST_TIME 22 7051
TEST_TIME 23 7328
TEST_TIME 24 7669
TEST_TIME 25 7850
TEST_TIME 26 7983
TEST_TIME 27 8545
TEST_TIME 28 8857
TEST_TIME 29 9787
TEST_TIME 30 10344
TEST_TIME 31 10448
TEST_TIME 32 10541
TEST_TIME 33 10559
TEST_TIME 34 10664
TEST_TIME 35 10521
TEST_TIME 36 10343
TEST_TIME 37 10369
TEST_TIME 38 10385
TEST_TIME 39 10329
TEST_TIME 40 10408
TEST_TIME 41 10396
TEST_TIME 42 10467
TEST_TIME 43 10507
TEST_TIME 44 10311
TEST_TIME 45 10111
TEST_TIME 46 10007
TEST_TIME 47 9865
TEST_TIME 48 9811
TEST_TIME 49 9724
TEST_TIME 50 9624
TEST_TIME 51 9726
TEST_TIME 52 9693
TEST_TIME 53 9653
TEST_TIME 54 9636
TEST_TIME 55 9583
TEST_TIME 56 9497
TEST_TIME 57 9406
TEST_TIME 58 9504
TEST_TIME 59 9367
TEST_TIME 60 9426
TEST_TIME 61 9365
TEST_TIME 62 9345
TEST_TIME 63 9377
TEST_TIME 64 9348
TEST_TIME 65 9477
TEST_TIME 66 9462
TEST_TIME 67 9477
TEST_TIME 68 9499
TEST_TIME 69 9559
TEST_TIME 70 9525
TEST_TIME 71 9532
TEST_TIME 72 9494
TEST_TIME 73 9525
TEST_TIME 74 9527
TEST_TIME 75 9423
TEST_TIME 76 9442
TEST_TIME 77 9359
TEST_TIME 78 9330
TEST_TIME 79 9304
TEST_TIME 80 9231
TEST_TIME 81 9198
TEST_TIME 82 9281
TEST_TIME 83 9273
TEST_TIME 84 9248
TEST_TIME 85 9230
TEST_TIME 86 9228
TEST_TIME 87 9204
TEST_TIME 88 9202
TEST_TIME 89 9341
TEST_TIME 90 9358
TEST_TIME 91 9350
TEST_TIME 92 9350
TEST_TIME 93 9386
TEST_TIME 94 9409
TEST_TIME 95 9373
TEST_TIME 96 9325
TEST_TIME 97 9245
TEST_TIME 98 9276
TEST_TIME 99 9237
TEST_TIME 100 9210
TEST_TIME 101 9162
TEST_TIME 102 9091
TEST_TIME 103 9131
TEST_TIME 104 9097
TEST_TIME 105 9085
TEST_TIME 106 9054
TEST_TIME 107 9079
TEST_TIME 108 9077
TEST_TIME 109 9106
TEST_TIME 110 9123
TEST_TIME 111 9105
TEST_TIME 112 9141
TEST_TIME 113 9120
TEST_TIME 114 9198
TEST_TIME 115 9140
TEST_TIME 116 9104
TEST_TIME 117 9066
TEST_TIME 118 9023
TEST_TIME 119 9029
TEST_TIME 120 8960

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-10-2021 13:38:54 to 10-10-2021 13:38:58.

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 1500333 1500333 0 0.00 0 34890 8 0 0
doc_list_biud 1500333 1500333 0 0.00 0 34044 36 0 0
sp_add_invoice_to_stock 4993 4867 126 2.52 56 205088 22369 0 126
sp_cancel_adding_invoice 5324 5324 0 0.00 6 75428 1029 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 18048 371 0 0
sp_cancel_customer_reserve 531184 531184 0 0.00 1 34051 80 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 3 6138 112 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 3 5859 104 0 0
sp_cancel_supplier_invoice 5950 5941 9 0.15 2 31821 1252 0 9
sp_cancel_supplier_order 388 388 0 0.00 6 1363933 141920 0 0
sp_cancel_write_off 9497 9497 0 0.00 1 27471 152 0 0
sp_client_order 20368 20368 0 0.00 4 29305 396 0 0
sp_customer_reserve 459407 459407 0 0.00 0 47907 218 0 0
sp_fill_shopping_cart 31253 31253 0 0.00 1 23058 44 0 0
sp_fill_shopping_cart_clo_res 459403 459403 0 0.00 0 21861 17 0 0
sp_get_clo_for_invoice 4861 4861 0 0.00 0 28747 321 0 0
sp_kill_cost_storno 15890 15890 0 0.00 0 7714 78 0 0
sp_kill_qstorno_ret_qs2qd 1078194 1078194 0 0.00 0 33980 44 0 0
sp_kill_qty_storno 568803 568803 0 0.00 0 34043 94 0 0
sp_lock_selected_doc 20324 20324 0 0.00 0 9161 5 0 0
sp_make_cost_storno 27657 27657 0 0.00 0 16063 59 0 0
sp_multiply_rows_for_pdistr 27657 27657 0 0.00 0 7566 35 0 0
sp_multiply_rows_for_qdistr 20368 20368 0 0.00 0 13365 194 0 0
sp_pay_from_customer 7135 7135 0 0.00 4 13069 125 0 0
sp_pay_to_supplier 5835 5835 0 0.00 5 13589 134 0 0
sp_payment_common 12970 12970 0 0.00 1 8657 30 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 18038 346 0 0
sp_qd_handle_on_invoice_upd_sts 10185 10185 0 0.00 0 22337 428 0 0
sp_qd_handle_on_reserve_upd_sts 9826 9826 0 0.00 0 19475 83 0 0
sp_reserve_write_off 9826 9826 0 0.00 7 19486 213 0 0
sp_supplier_invoice 5445 5445 0 0.00 6 97919 3567 0 0
sp_supplier_order 5454 5454 0 0.00 6 57870 1502 0 0
srv_aggregate_perf_data 100 100 0 0.00 2929 187829 15515 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1044 144420 40422 0 0
srv_find_qd_qs_mism 1027556 1027556 0 0.00 0 34889 8 0 0
srv_make_invnt_saldo 476 476 0 0.00 49 95547 3859 0 0
srv_make_money_saldo 721 721 0 0.00 1 11731 486 0 0
srv_recalc_idx_stat 4 4 0 0.00 162056 378015 274561 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4867 4867 0 0.00 0 21731 206 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 8306 142 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 4710 15 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 1926 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 4219 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 9904 37 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 855 13 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 24085 1887 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 1846 8 0 0
t$perf-norm:sp_client_order 20368 20368 0 0.00 0 10904 21 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 2824 10 0 0
t$perf-norm:sp_pay_from_customer 7135 7135 0 0.00 0 5183 10 0 0
t$perf-norm:sp_pay_to_supplier 5835 5835 0 0.00 0 10086 12 0 0
t$perf-norm:sp_reserve_write_off 9826 9826 0 0.00 0 18277 16 0 0
t$perf-norm:sp_supplier_invoice 5445 5445 0 0.00 0 11953 23 0 0
t$perf-norm:sp_supplier_order 5454 5454 0 0.00 0 9972 20 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 1893 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1025 23 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 1505 15 0 0
t$perf-norm:srv_make_money_saldo 721 721 0 0.00 0 2029 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9974 9722 252 2.52 0 6238 17 0 252
v_all_customers 37736 37736 0 0.00 0 21 1 0 0
v_all_suppliers 30876 30876 0 0.00 0 24 1 0 0
v_all_wares 40734 40734 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 158 6 0 0
v_cancel_client_order 3564 3564 0 0.00 0 990 3 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 5353 8 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 1311 4 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 1340 7 0 0
v_cancel_supplier_invoice 784 766 18 2.29 0 1728 17 0 18
v_cancel_supplier_order 776 776 0 0.00 0 2279 9 0 0
v_cancel_write_off 1922 1922 0 0.00 0 5078 17 0 0
v_max_id_clo_ord 5445 5445 0 0.00 0 832 2 0 0
v_max_id_clo_res 959 959 0 0.00 0 16385 45 0 0
v_max_id_ord_sup 5441 5441 0 0.00 0 4709 4 0 0
v_max_non_paid_invoice 1283 1283 0 0.00 0 182 2 0 0
v_max_non_paid_realizn 3641 3641 0 0.00 0 6395 5 0 0
v_min_id_clo_ord 5445 5445 0 0.00 0 3015 7 0 0
v_min_id_clo_res 959 959 0 0.00 0 2647 10 0 0
v_min_id_ord_sup 5441 5441 0 0.00 0 8833 9 0 0
v_min_non_paid_invoice 5835 5835 0 0.00 0 6590 19 0 0
v_min_non_paid_realizn 7135 7135 0 0.00 0 6935 23 0 0
v_our_firm 9986 9986 0 0.00 0 130 1 0 0
v_reserve_write_off 19652 19652 0 0.00 0 2569 5 0 0
x_lock_depdocs_on_canc_invoice 10882 10882 0 0.00 0 25442 138 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 17643 509 0 0
x_make_qstorno_1000_1200 5454 5454 0 0.00 2 57726 1417 0 0
x_make_qstorno_1200_2000 5445 5445 0 0.00 3 97909 3400 0 0
x_make_qstorno_2100_3300 459407 459407 0 0.00 0 47905 198 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-10-2021 13:38:59 to 10-10-2021 13:39:03.

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:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 19709 8192000000 9717276480 10015834112 798741136 8.884 100 83 166 0 825852144 7532928 19578080
11:09:44 21388 8192000000 9765861616 10075201536 838451168 9.284 100 85 170 0 872378128 12936160 20990800
11:11:14 7380 8192000000 9797635728 10116276224 871585552 9.616 100 87 174 0 910857376 19155520 20116304
11:12:25 12699 8192000000 9859771328 10188537856 908270352 9.980 100 78 156 0 970839472 45066336 17502784
11:13:44 9476 8192000000 9889842640 10233573376 941304960 10.306 100 78 157 0 1008158992 49472400 17381632
11:14:55 6675 8192000000 9939181424 10292817920 978502640 10.670 100 75 147 0 1059595808 66010400 15082768
11:16:05 3945 8192000000 10002235424 10365206528 1012305408 10.998 100 88 174 0 1113705968 83817616 17582944
11:17:12 2279 8192000000 9999452752 10364510208 1038612576 11.251 100 82 164 2 1116734912 61564256 16558080
11:18:16 1891 8192000000 10024411680 10394382336 1052808512 11.388 100 88 176 1 1131096896 59672832 18615552
11:19:21 1796 8192000000 10044692176 10416283648 1068001632 11.533 100 91 181 1 1157909376 71164416 18743328
11:20:24 3603 8192000000 9999033488 10384977920 1068937680 11.542 100 57 115 1 1107273760 25585216 12750864
11:21:31 7772 8192000000 10001022000 10396680192 1081512496 11.662 100 45 92 0 1122028688 30176512 10339680
11:22:42 5161 8192000000 10019142432 10416386048 1088629104 11.730 100 40 80 1 1133675696 36675744 8370848
11:23:48 62651 8192000000 10013126384 10423173120 1096352336 11.803 100 33 65 3 1125330368 21763792 7214240
11:25:52 6855 8192000000 10030600224 10439593984 1101832016 11.855 100 37 74 1 1135552096 26226640 7493440
11:27:02 11552 8192000000 10020326576 10439168000 1104569232 11.881 100 20 40 1 1139783136 30663392 4550512
11:28:14 3958 8192000000 9981732272 10367442944 1039513728 11.260 96 36 72 0 1072808880 25210992 8084160
11:29:19 37115 8192000000 9997862464 10397765632 1054606368 11.405 99 33 66 2 1091146176 30212608 6327200
11:30:57 4672 8192000000 9970781136 10342899712 1007654512 10.953 97 48 94 6 1053519360 34894432 10970416
11:32:04 6250 8192000000 9950227360 10336813056 1024951344 11.120 99 33 65 2 1055013968 22346176 7716448
11:33:11 11242 8192000000 9936609296 10326917120 1000737648 10.886 97 32 64 0 1020919024 12949280 7232096
11:34:27 37240 8192000000 9954717824 10357764096 1035685264 11.223 99 40 81 1 1061943680 17166784 9091632
11:36:05 2917 8192000000 9980638560 10365915136 1057082176 11.429 100 56 112 4 1088286576 18749104 12455296
11:37:09 63050 8192000000 9999050464 10398949376 1080843760 11.656 100 42 84 0 1102423328 12040816 9538752
11:39:31 20408 8192000000 10076051296 10475487232 1104343488 11.879 100 52 104 0 1177190848 61071888 11775472
11:41:59 37237 8192000000 10108335072 10521513984 1119548464 12.023 100 45 87 4 1210884160 81531328 9804368
11:43:41 16586 8192000000 10143606944 10550198272 1130432752 12.125 100 46 92 1 1262359872 121530576 10396544
11:45:02 18627 8192000000 10158798032 10564718592 1139047840 12.207 100 58 116 2 1259795232 107712576 13034816
11:46:28 7239 8192000000 10206753168 10627682304 1151281600 12.322 100 56 113 0 1311019152 147010576 12726976
11:47:54 12784 8192000000 10242370592 10659315712 1164013968 12.441 100 53 105 1 1344092816 168296112 11782736
11:49:14 3072 8192000000 10281787552 10691821568 1166010000 12.460 100 75 147 0 1383645984 200480800 17155184
11:50:23 3467 8192000000 10317055072 10747592704 1174086752 12.535 100 76 151 3 1424126016 233151856 16887408
11:51:39 1043 8192000000 10329336320 10757107712 1190898880 12.692 100 75 147 2 1434981296 226427520 17654896
11:52:44 5107 8192000000 10320907280 10756460544 1184697152 12.634 100 66 132 0 1416995376 217294560 15003664
11:53:57 4747 8192000000 10374660688 10792890368 1180712928 12.597 100 85 169 0 1477556192 277883760 18959504
11:55:05 1271 8192000000 10422360064 10832433152 1186403984 12.650 100 92 184 0 1525941568 317883840 21653744
11:56:13 1927 8192000000 10472866016 10878038016 1186470704 12.651 100 86 172 1 1562235200 355759072 20005424
11:57:20 3613 8192000000 10476986960 10886033408 1180443088 12.594 99 77 152 0 1566494272 367621760 18429424
11:58:30 3715 8192000000 10516982192 10927382528 1179653536 12.587 100 68 136 0 1616862528 420401968 16807024
11:59:42 2040 8192000000 10563547072 10972983296 1186185280 12.648 100 92 184 0 1660304320 451846528 22272512
12:00:47 15196 8192000000 10589464224 11016241152 1163291536 12.434 100 49 98 0 1688844480 511986672 13566272
12:02:04 10344 8192000000 10570559392 11001790464 1152594192 12.334 100 45 89 1 1672368688 507206160 12568336
12:03:20 19570 8192000000 10539474896 11022921728 1163709840 12.438 100 42 84 1 1649221232 474274928 11236464
12:04:43 23152 8192000000 10504154608 10981003264 1142894864 12.243 100 28 56 0 1592359760 441691360 7773536
12:06:39 30345 8192000000 10117586784 10650759168 1095413952 11.794 97 18 36 0 1333201904 233379728 4408224
12:08:38 71321 8192000000 10089413328 10580201472 1117522800 12.004 99 10 20 1 1181405568 62199600 1683168
12:10:50 106677 8192000000 10060986368 10521903104 1038832864 11.253 92 10 20 0 1119536608 78811216 1892528
12:13:38 44780 8192000000 10108057536 10551742464 1114734928 11.977 99 47 93 1 1189656400 64289408 10632064
12:15:24 58870 8192000000 10091721616 10543644672 1112907520 11.960 99 14 25 2 1189673568 73588400 3177648
12:17:23 54099 8192000000 10089670592 10514788352 1113392832 11.965 98 34 68 0 1191377408 70887968 7096608
12:19:21 144420 8192000000 10065961600 10530152448 1102950496 11.866 97 8 16 0 1154454720 50351776 1152448
12:22:46 95380 8192000000 10062789216 10517196800 1096915168 11.808 97 27 54 0 1136506496 33850064 5741264
12:26:00 63905 8192000000 10126229888 10562404352 1127998928 12.102 99 47 92 0 1199624016 61825680 9799408
12:28:19 30747 8192000000 10118254336 10619543552 1131839104 12.139 99 14 28 0 1224708976 89686912 3182960
12:30:14 37176 8192000000 10091030080 10579353600 1132835696 12.148 99 9 18 0 1191481248 56463328 2182224
12:31:52 31706 8192000000 10074469840 10512343040 1127507312 12.098 99 42 82 0 1170748208 34962320 8278576
12:33:28 52072 8192000000 10124176800 10600292352 1147537008 12.286 100 17 34 1 1215581904 63949792 4095104
12:35:38 78006 8192000000 10116596496 10619011072 1146318288 12.275 100 7 14 0 1228052784 80299472 1435024
12:37:57 62237 8192000000 10085099056 10568065024 1141663264 12.231 99 8 16 1 1185386448 42078704 1644480
12:39:59 72862 8192000000 10115969360 10594750464 1146574512 12.277 99 17 35 0 1218782352 68999856 3207984
12:42:12 60779 8192000000 10112700512 10593153024 1111426880 11.946 97 26 53 1 1172685600 56354480 4904240
12:44:14 17976 8192000000 10085739616 10518433792 1133930752 12.158 99 45 89 0 1165679520 23326384 8422384
12:45:32 20453 8192000000 10156865952 10603425792 1148354080 12.294 100 56 112 2 1243706704 84045520 11307104
12:47:02 42609 8192000000 10071933888 10511622144 1115873856 11.988 97 18 36 0 1146478864 27462128 3142880
12:48:45 58187 8192000000 10089409648 10520006656 1096481312 11.804 96 31 62 0 1133065152 30602272 5981568
12:50:45 17479 8192000000 10096278256 10547703808 1106106880 11.896 98 33 66 1 1175617120 62867200 6643040
12:52:04 74211 8192000000 10078418960 10534993920 1129729904 12.119 100 27 54 1 1170959024 35890976 5338144
12:54:22 35397 8192000000 10050637760 10484740096 1114241696 11.973 99 42 83 0 1145986048 22738592 9005760
12:56:08 49652 8192000000 10072461536 10532319232 1105884848 11.893 99 21 42 0 1184037840 73595104 4557888
12:58:00 44504 8192000000 10113061008 10570723328 1109517792 11.928 99 20 41 0 1211729472 97800592 4411088
12:59:48 71020 8192000000 10048726032 10550456320 1107360608 11.907 99 4 8 0 1177054368 68848096 845664
13:02:09 24722 8192000000 10061152704 10496032768 1090037632 11.743 97 25 50 2 1138364976 43254208 5073136
13:03:38 30949 8192000000 10101161120 10520719360 1082445472 11.671 97 39 78 0 1153708800 63227536 8035792
13:05:10 76067 8192000000 10109755360 10621775872 1074664304 11.597 96 16 32 0 1173222704 94996432 3561968
13:07:33 39162 8192000000 10078075024 10537062400 1114457520 11.975 99 35 70 2 1155648976 32288560 8902896
13:09:12 30981 8192000000 10116362640 10557464576 1133656080 12.156 99 37 72 2 1186621888 46065840 6899968
13:10:45 19492 8192000000 10102828176 10542600192 1109813680 11.931 98 50 100 1 1175777168 56094544 9868944
13:12:11 75915 8192000000 10115018720 10551570432 1146095952 12.273 100 47 94 0 1209566608 54179600 9291056
13:14:38 30790 8192000000 10103947008 10551750656 1147435808 12.285 100 30 60 1 1195041168 41577392 6027968
13:16:09 43994 8192000000 10061822800 10521817088 1141065904 12.226 100 14 27 0 1166032352 22107072 2859376
13:17:53 49937 8192000000 10074885264 10551898112 1145882928 12.271 100 14 27 1 1173900640 25207616 2810096
13:19:44 71005 8192000000 10078093008 10597228544 1145961904 12.272 100 12 24 0 1199898464 51405536 2531024
13:22:01 17898 8192000000 10084307328 10526986240 1123854016 12.063 98 29 57 1 1165067680 35310352 5903312
13:23:20 61688 8192000000 10133175328 10599428096 1144055632 12.254 99 24 48 0 1238441488 88549536 5836320
13:25:29 50148 8192000000 10116279200 10557341696 1112576864 11.957 97 31 62 1 1210049424 90581568 6890992
13:27:19 63713 8192000000 10114447456 10577895424 1104131136 11.877 97 25 50 1 1199249024 90055056 5062832
13:29:33 21620 8192000000 10102485040 10533347328 1133844512 12.158 100 39 78 0 1183517488 42048544 7624432
13:31:00 54781 8192000000 10093687728 10556481536 1129417424 12.116 99 28 56 0 1245412864 110170704 5824736
13:32:58 97611 8192000000 10040009552 10512629760 1097904720 11.818 97 18 35 0 1145652032 44294592 3452720
13:35:42 46613 8192000000 10086638576 10575376384 1108666320 11.920 98 8 16 0 1172808320 62623424 1518576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 10-10-2021 13:39:21 to 10-10-2021 13:39:22.

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          231166
MON$OLDEST_ACTIVE               232886
MON$OLDEST_SNAPSHOT             232886
MON$NEXT_TRANSACTION            232886
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                       2063456
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2625 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2625 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2625 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2625 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Sun Oct 10 13:37:12 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Sun Oct 10 13:37:20 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Sun Oct 10 13:37:20 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

10.10.21 13:39:23. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-10-10 13:39:26 No severe PSQL-related problems occured
10.10.21 13:39:27. End of report.