Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.06.2022 10:37
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 15935556 kB
MemAvailable 31313068 kB
Buffers 0 kB
Cached 15734376 kB
SwapCached 2448 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.2
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 2022-06-04 10:37:18
DTS_END                         2022-06-04 10:37:18.1180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50644
TRN_ID                          215215
ATT_ID                          660
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: 9589.15 21658 1150698
customer order: creation 163.61 425 19633
customer order: refuse 13.63 380 1635
order to supplier: creation 41.50 1328 4980
order to supplier: removal 3.52 168288 422
invoice (draft): creation 43.15 2890 5178
invoice (draft): removal 57.07 955 6848
invoice accept: apply 40.60 27139 4872
invoice accept: cancel 51.95 1090 6234
customer reserve: creation 3820.46 255 458455
customer reserve: removal 5060.43 90 607251
realization accept: apply 80.45 271 9654
realization accept: cancel 89.66 158 10759
payment from customer: creation 55.53 175 6664
payment from customer: removal 5.25 122 630
payment to supplier: creation 46.78 178 5613
payment to supplier: removal 3.33 103 399
service: total inventory turnovers 3.95 4038 474
service: total monetary turnovers 7.33 330 880
service: aggregate perf. data 0.93 17102 112
service: refresh index statistics 0.04 207833 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-06-2022 10:37:59 to 04-06-2022 10:38:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7442
TEST_TIME 2 6431
TEST_TIME 3 6340
TEST_TIME 4 6284
TEST_TIME 5 6685
TEST_TIME 6 6315
TEST_TIME 7 6597
TEST_TIME 8 6398
TEST_TIME 9 6316
TEST_TIME 10 6417
TEST_TIME 11 6635
TEST_TIME 12 6674
TEST_TIME 13 6605
TEST_TIME 14 6820
TEST_TIME 15 6865
TEST_TIME 16 7058
TEST_TIME 17 7286
TEST_TIME 18 7640
TEST_TIME 19 7854
TEST_TIME 20 7940
TEST_TIME 21 8032
TEST_TIME 22 8489
TEST_TIME 23 8642
TEST_TIME 24 8633
TEST_TIME 25 8798
TEST_TIME 26 8990
TEST_TIME 27 9754
TEST_TIME 28 9935
TEST_TIME 29 10086
TEST_TIME 30 10063
TEST_TIME 31 10326
TEST_TIME 32 10773
TEST_TIME 33 11485
TEST_TIME 34 11518
TEST_TIME 35 11693
TEST_TIME 36 11543
TEST_TIME 37 11317
TEST_TIME 38 11055
TEST_TIME 39 11020
TEST_TIME 40 10933
TEST_TIME 41 10875
TEST_TIME 42 10845
TEST_TIME 43 10773
TEST_TIME 44 10600
TEST_TIME 45 10533
TEST_TIME 46 10470
TEST_TIME 47 10512
TEST_TIME 48 10451
TEST_TIME 49 10518
TEST_TIME 50 10364
TEST_TIME 51 10367
TEST_TIME 52 10315
TEST_TIME 53 10290
TEST_TIME 54 10161
TEST_TIME 55 10139
TEST_TIME 56 10081
TEST_TIME 57 9994
TEST_TIME 58 9924
TEST_TIME 59 9840
TEST_TIME 60 9789
TEST_TIME 61 9719
TEST_TIME 62 9717
TEST_TIME 63 9688
TEST_TIME 64 9761
TEST_TIME 65 9838
TEST_TIME 66 9899
TEST_TIME 67 9851
TEST_TIME 68 9868
TEST_TIME 69 9924
TEST_TIME 70 9901
TEST_TIME 71 9996
TEST_TIME 72 10037
TEST_TIME 73 10056
TEST_TIME 74 10007
TEST_TIME 75 9950
TEST_TIME 76 9897
TEST_TIME 77 9848
TEST_TIME 78 9815
TEST_TIME 79 9777
TEST_TIME 80 9736
TEST_TIME 81 9700
TEST_TIME 82 9602
TEST_TIME 83 9549
TEST_TIME 84 9510
TEST_TIME 85 9504
TEST_TIME 86 9542
TEST_TIME 87 9504
TEST_TIME 88 9467
TEST_TIME 89 9476
TEST_TIME 90 9426
TEST_TIME 91 9428
TEST_TIME 92 9616
TEST_TIME 93 9611
TEST_TIME 94 9597
TEST_TIME 95 9613
TEST_TIME 96 9626
TEST_TIME 97 9711
TEST_TIME 98 9671
TEST_TIME 99 9588
TEST_TIME 100 9519
TEST_TIME 101 9568
TEST_TIME 102 9541
TEST_TIME 103 9508
TEST_TIME 104 9503
TEST_TIME 105 9451
TEST_TIME 106 9411
TEST_TIME 107 9420
TEST_TIME 108 9393
TEST_TIME 109 9376
TEST_TIME 110 9375
TEST_TIME 111 9349
TEST_TIME 112 9347
TEST_TIME 113 9361
TEST_TIME 114 9358
TEST_TIME 115 9327
TEST_TIME 116 9290
TEST_TIME 117 9438
TEST_TIME 118 9479
TEST_TIME 119 9600
TEST_TIME 120 9615

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-06-2022 10:38:03 to 04-06-2022 10:38:07.

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 1571507 1571507 0 0.00 0 30393 14 0 0
doc_list_biud 1571484 1571484 0 0.00 0 64287 41 0 0
sp_add_invoice_to_stock 4977 4872 105 2.10 57 208429 27140 0 105
sp_cancel_adding_invoice 6234 6234 0 0.00 5 108211 1091 0 0
sp_cancel_client_order 1635 1635 0 0.00 6 6731 380 0 0
sp_cancel_customer_reserve 607251 607251 0 0.00 1 64291 91 0 0
sp_cancel_pay_from_customer 630 630 0 0.00 4 6416 123 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 2061 103 0 0
sp_cancel_supplier_invoice 6855 6848 7 0.10 2 23968 956 0 7
sp_cancel_supplier_order 422 422 0 0.00 17 1518481 168289 0 0
sp_cancel_write_off 10759 10759 0 0.00 2 11571 158 0 0
sp_client_order 19633 19633 0 0.00 6 15522 425 0 0
sp_customer_reserve 458455 458455 0 0.00 1 151745 256 0 0
sp_fill_shopping_cart 29773 29773 0 0.00 1 11955 76 0 0
sp_fill_shopping_cart_clo_res 458416 458416 0 0.00 0 18233 20 0 0
sp_get_clo_for_invoice 4826 4826 0 0.00 0 31625 283 0 0
sp_kill_cost_storno 18022 18022 0 0.00 0 13484 64 0 0
sp_kill_qstorno_ret_qs2qd 1232531 1232531 0 0.00 0 64280 40 0 0
sp_kill_qty_storno 647626 647626 0 0.00 0 64285 92 0 0
sp_lock_selected_doc 19967 19967 0 0.00 0 6248 8 0 0
sp_make_cost_storno 26756 26756 0 0.00 0 16423 85 0 0
sp_multiply_rows_for_pdistr 26753 26753 0 0.00 0 6934 37 0 0
sp_multiply_rows_for_qdistr 19631 19631 0 0.00 0 13984 176 0 0
sp_pay_from_customer 6664 6664 0 0.00 6 12113 176 0 0
sp_pay_to_supplier 5613 5613 0 0.00 6 15699 179 0 0
sp_payment_common 12276 12276 0 0.00 2 6232 42 0 0
sp_qd_handle_on_cancel_clo 1635 1635 0 0.00 1 6627 304 0 0
sp_qd_handle_on_invoice_upd_sts 11058 11058 0 0.00 0 36405 514 0 0
sp_qd_handle_on_reserve_upd_sts 9653 9653 0 0.00 0 13391 56 0 0
sp_reserve_write_off 9654 9654 0 0.00 8 15905 271 0 0
sp_supplier_invoice 5178 5178 0 0.00 9 94170 2890 0 0
sp_supplier_order 4980 4980 0 0.00 8 34890 1329 0 0
srv_aggregate_perf_data 112 112 0 0.00 2392 204006 17102 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 822 211223 38104 0 0
srv_find_qd_qs_mism 1102930 1102930 0 0.00 0 30392 11 0 0
srv_make_invnt_saldo 474 474 0 0.00 77 120395 4038 0 0
srv_make_money_saldo 880 880 0 0.00 1 13455 331 0 0
srv_recalc_idx_stat 5 5 0 0.00 44282 349832 207834 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4875 4875 0 0.00 1 24556 917 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 6309 379 0 0
t$perf-norm:sp_cancel_client_order 1635 1635 0 0.00 0 4507 30 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 871 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 630 630 0 0.00 0 1110 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 5271 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 4697 50 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 74937 6936 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 990 16 0 0
t$perf-norm:sp_client_order 19633 19633 0 0.00 0 11444 36 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 951 16 0 0
t$perf-norm:sp_pay_from_customer 6664 6664 0 0.00 0 6622 17 0 0
t$perf-norm:sp_pay_to_supplier 5613 5613 0 0.00 0 6629 20 0 0
t$perf-norm:sp_reserve_write_off 9654 9654 0 0.00 0 7242 21 0 0
t$perf-norm:sp_supplier_invoice 5178 5178 0 0.00 0 5261 29 0 0
t$perf-norm:sp_supplier_order 4980 4980 0 0.00 0 2315 30 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 614 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 582 15 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 2439 21 0 0
t$perf-norm:srv_make_money_saldo 880 880 0 0.00 0 351 3 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 191 83 0 0
v_add_invoice_to_stock 9856 9646 210 2.13 0 7429 25 0 210
v_all_customers 35776 35776 0 0.00 0 245 3 0 0
v_all_suppliers 28946 28946 0 0.00 0 130 4 0 0
v_all_wares 39250 39250 0 0.00 0 84 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 791 13 0 0
v_cancel_client_order 3270 3270 0 0.00 0 3786 12 0 0
v_cancel_customer_prepayment 1260 1260 0 0.00 0 2629 16 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 3633 14 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 1959 13 0 0
v_cancel_supplier_invoice 792 778 14 1.76 0 485 21 0 14
v_cancel_supplier_order 844 844 0 0.00 0 842 11 0 0
v_cancel_write_off 1892 1892 0 0.00 0 3646 23 0 0
v_max_id_clo_ord 4975 4975 0 0.00 0 3358 5 0 0
v_max_id_clo_res 942 942 0 0.00 0 2017 14 0 0
v_max_id_ord_sup 5172 5172 0 0.00 0 6094 9 0 0
v_max_non_paid_invoice 1285 1285 0 0.00 0 2545 10 0 0
v_max_non_paid_realizn 3419 3419 0 0.00 0 494 7 0 0
v_min_id_clo_ord 4975 4975 0 0.00 0 1858 8 0 0
v_min_id_clo_res 942 942 0 0.00 0 3452 18 0 0
v_min_id_ord_sup 5172 5172 0 0.00 0 5633 13 0 0
v_min_non_paid_invoice 5612 5612 0 0.00 0 3729 12 0 0
v_min_non_paid_realizn 6663 6663 0 0.00 0 4900 18 0 0
v_our_firm 9960 9960 0 0.00 0 48 4 0 0
v_reserve_write_off 19306 19306 0 0.00 0 4783 10 0 0
x_lock_depdocs_on_canc_invoice 12693 12693 0 0.00 0 14242 106 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 20124 408 0 0
x_make_qstorno_1000_1200 4980 4980 0 0.00 3 34865 1166 0 0
x_make_qstorno_1200_2000 5178 5178 0 0.00 4 94145 2687 0 0
x_make_qstorno_2100_3300 458455 458455 0 0.00 0 151736 224 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-06-2022 10:38:08 to 04-06-2022 10:38:12.

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:21 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 34947 8192000000 9736150720 10039517184 816831904 9.067 100 89 178 0 846341200 7935632 21573664
08:10:07 12158 8192000000 9773993136 10086731776 853667072 9.437 100 93 186 0 889697008 14019440 22010496
08:11:25 16072 8192000000 9818484016 10141392896 883816304 9.738 100 87 174 1 927958496 24245200 19896992
08:12:45 8841 8192000000 9880982272 10215100416 922646592 10.122 100 80 160 1 987391552 46826912 17918048
08:13:58 4127 8192000000 9930441200 10278797312 975271824 10.638 100 81 160 0 1045891120 53690928 16928368
08:15:05 1550 8192000000 9978642064 10334470144 1003559856 10.913 100 94 182 1 1101003952 78645280 18798816
08:16:09 1203 8192000000 10016336544 10382307328 1032935440 11.197 100 95 188 2 1140759184 88602112 19221632
08:17:11 844 8192000000 10027394688 10400968704 1058144976 11.439 100 95 178 3 1153106464 76452352 18509136
08:18:14 1101 8192000000 10041367152 10416549888 1070339200 11.555 100 94 187 0 1161093024 71070112 19683712
08:19:17 1120 8192000000 10051074656 10435100672 1084867552 11.694 100 95 180 2 1173784576 71106480 17810544
08:20:20 1261 8192000000 10072232256 10457448448 1081616608 11.663 100 97 194 0 1191413472 89902592 19894272
08:21:22 1347 8192000000 10056873024 10444161024 1084437200 11.690 100 84 165 1 1180339360 79371360 16530800
08:22:24 1094 8192000000 10055705600 10448375808 1088037824 11.724 100 80 156 2 1181854432 78019152 15797456
08:23:27 1274 8192000000 10042515104 10430365696 1093534320 11.776 100 94 185 1 1162950112 50347184 19068608
08:24:29 1327 8192000000 10074661552 10463285248 1097001152 11.809 100 96 183 1 1194586736 79365712 18219872
08:25:32 4242 8192000000 10078191072 10467082240 1095615520 11.796 100 86 167 4 1207329296 96544768 15169008
08:26:37 1844 8192000000 10049269680 10442739712 1092263536 11.764 100 74 148 1 1167892320 61696112 13932672
08:27:39 15510 8192000000 10043367728 10446684160 1089005472 11.733 100 54 104 2 1153356768 54440736 9910560
08:28:57 1620 8192000000 10059874816 10455539712 1085732416 11.702 100 82 158 1 1181349552 79455904 16161232
08:29:59 1024 8192000000 10048187264 10430447616 1076068912 11.610 100 94 182 1 1168338448 73890608 18378928
08:31:01 1826 8192000000 10019477792 10401128448 1047992256 11.341 99 85 160 1 1136954928 73289904 15672768
08:32:03 2556 8192000000 10034111632 10430517248 1060581840 11.462 100 64 118 1 1144244336 71349184 12313312
08:33:07 1890 8192000000 9959615424 10334601216 1024210176 11.113 98 69 132 2 1074839952 37356176 13273600
08:34:10 997 8192000000 9971502176 10340802560 1038203696 11.247 100 90 174 1 1090695952 35916656 16575600
08:35:11 1526 8192000000 10017705376 10399334400 1046071792 11.323 100 93 184 1 1140986304 76099968 18814544
08:36:13 1196 8192000000 9966747968 10343182336 1020797424 11.080 98 85 162 3 1096885104 59037776 17049904
08:37:15 1201 8192000000 10001223952 10373533696 1043918768 11.302 100 91 172 0 1119828272 56891872 19017632
08:38:26 35348 8192000000 10041572688 10419023872 1067236320 11.526 100 76 142 1 1157083152 75957984 13888848
08:40:07 1444 8192000000 10072684160 10456645632 1088551648 11.729 100 90 171 1 1180561296 74917232 17092416
08:41:12 4408 8192000000 10090663712 10481037312 1103687376 11.873 100 73 134 1 1213467248 96071760 13708112
08:42:27 844 8192000000 10122028528 10530312192 1118459184 12.012 100 69 130 1 1243708992 111468176 13781632
08:43:32 813 8192000000 10154049024 10543353856 1128354000 12.106 100 99 183 1 1267917632 120004976 19558656
08:44:37 897 8192000000 10196080032 10589212672 1131491376 12.135 100 98 181 1 1306714400 155923040 19299984
08:45:43 3863 8192000000 10209958528 10616315904 1149110352 12.301 100 72 141 0 1327204336 162857168 15236816
08:46:58 1356 8192000000 10267949952 10667352064 1164897968 12.449 100 85 170 0 1385439712 201901344 18640400
08:48:02 1305 8192000000 10303567120 10706059264 1177160016 12.564 100 96 186 1 1419475984 221729344 20586624
08:49:06 1062 8192000000 10316735456 10720460800 1176430688 12.557 100 85 162 0 1428186160 234036992 17718480
08:50:13 908 8192000000 10384641536 10790359040 1193941568 12.720 100 97 189 1 1498427952 281481488 23004896
08:51:17 1206 8192000000 10433929312 10846117888 1194291776 12.723 100 98 196 1 1543364272 324781760 24290736
08:52:23 1255 8192000000 10438108592 10852872192 1200098720 12.777 100 96 192 0 1546701696 324399568 22203408
08:53:26 2324 8192000000 10466266320 10879721472 1202039232 12.795 100 94 186 1 1575310656 350958672 22312752
08:54:31 1055 8192000000 10484277264 10907435008 1192671728 12.708 100 100 196 1 1594684864 378010544 24002592
08:55:34 2215 8192000000 10476029552 10906361856 1192747600 12.709 100 96 192 0 1586674400 371221520 22705280
08:56:40 904 8192000000 10505652352 10929037312 1201790272 12.793 100 95 190 1 1614349248 389710832 22848144
08:57:44 1140 8192000000 10505260688 10919952384 1190400752 12.687 100 98 189 0 1610480176 396538416 23541008
08:58:48 1938 8192000000 10534502368 10974457856 1188091296 12.666 100 82 161 1 1640982624 433309024 19582304
08:59:53 5968 8192000000 10523953584 10980311040 1192200928 12.704 100 56 110 0 1638878416 432250192 14427296
09:01:17 2878 8192000000 10534734016 10965889024 1193441952 12.715 100 65 129 0 1638194128 428403792 16348384
09:02:23 9837 8192000000 10545883168 11007643648 1188424224 12.669 100 58 117 0 1649717312 445960800 15332288
09:03:34 7331 8192000000 10488206352 11009417216 1187603344 12.661 100 50 99 1 1607732768 407280656 12848768
09:04:44 5512 8192000000 10421715728 10958147584 1175367712 12.547 100 44 87 0 1539851984 353467136 11017136
09:05:58 24846 8192000000 10392365248 10919522304 1168554800 12.483 100 26 50 2 1516511648 341020816 6936032
09:07:25 41087 8192000000 10428568640 10933071872 1144855360 12.261 98 24 48 0 1505810672 354573712 6381600
09:09:27 61760 8192000000 10159820688 10778501120 1131843328 12.139 99 16 32 3 1338266512 202401744 4021440
09:12:21 68910 8192000000 10070461616 10540605440 1074623120 11.596 94 15 30 1 1121388176 43633760 3131296
09:14:46 127512 8192000000 10080128144 10511007744 1096948848 11.809 96 32 64 0 1127168960 23973424 6246688
09:18:07 211221 8192000000 10122045584 10566090752 1136548752 12.183 99 38 76 0 1248175568 103698256 7928560
09:22:41 42494 8192000000 10181044032 10622238720 1158403424 12.388 99 41 80 0 1256591408 89245296 8942688
09:24:27 19621 8192000000 10241968368 10710196224 1177470352 12.567 100 45 90 1 1349319952 161971568 9878032
09:25:53 69399 8192000000 10170906592 10688016384 1157771152 12.382 99 16 32 1 1290225504 128592944 3861408
09:28:03 61979 8192000000 10149216592 10636869632 1137016432 12.187 98 13 26 0 1243045440 102889440 3139568
09:30:05 50611 8192000000 10093734320 10553339904 1142801520 12.242 99 17 34 1 1184157408 38126816 3229072
09:32:08 92327 8192000000 10054246960 10544214016 1096483568 11.804 96 12 22 0 1138923584 40252608 2187408
09:34:41 61733 8192000000 10029357904 10498699264 1101938336 11.856 99 17 34 0 1142829584 36825168 4066080
09:36:45 79530 8192000000 10075454448 10514268160 1113820160 11.969 98 19 34 1 1146616368 29460864 3335344
09:39:11 36857 8192000000 10150088128 10614804480 1151408976 12.323 100 22 44 0 1255107872 99000320 4698576
09:41:22 37991 8192000000 10174057648 10648915968 1154700400 12.354 100 28 56 3 1287318944 126780960 5837584
09:43:01 57311 8192000000 10134362096 10607267840 1108146160 11.915 96 21 42 0 1173409312 60786384 4476768
09:44:59 54142 8192000000 10158283760 10595467264 1139289792 12.209 100 39 78 3 1234825584 87657200 7878592
09:46:53 77571 8192000000 10194792704 10651471872 1133032800 12.150 98 22 44 0 1262961824 125170432 4758592
09:49:21 55328 8192000000 10102267216 10587934720 1119328064 12.021 98 14 26 2 1201823648 79560720 2934864
09:51:26 102715 8192000000 10077150240 10516332544 1110574480 11.938 97 25 50 0 1151243584 34690464 5978640
09:54:10 9268 8192000000 10086585632 10539802624 1116672992 11.996 98 42 82 0 1174967456 50001776 8292688
09:55:22 58249 8192000000 10020083232 10494869504 1075019248 11.600 96 12 25 1 1126066880 48105808 2941824
09:57:23 43076 8192000000 10028538736 10485542912 1087527936 11.719 97 15 30 0 1116777376 26192096 3057344
09:59:23 45237 8192000000 10059538544 10504830976 1126696064 12.090 100 27 52 2 1164455840 31988224 5771552
10:01:10 58360 8192000000 10086421536 10560798720 1109926928 11.932 98 12 24 1 1160750768 48019312 2804528
10:03:20 48983 8192000000 10043721056 10484527104 1098939184 11.828 97 24 46 1 1126325632 21836800 5549648
10:05:19 9956 8192000000 10107962448 10543546368 1136988608 12.187 100 54 106 1 1205108544 57854656 10265280
10:06:31 37468 8192000000 10138578448 10582532096 1096827696 11.808 97 44 88 1 1226870352 120532320 9510336
10:08:09 43657 8192000000 10188368368 10625310720 1112356880 11.955 98 46 92 0 1274640048 152396528 9886640
10:09:59 45052 8192000000 10089611136 10553425920 1122096944 12.047 99 19 38 2 1211294608 85322272 3875392
10:11:47 61452 8192000000 10122904192 10583490560 1099460528 11.833 97 48 96 1 1176241040 66724416 10056096
10:13:53 23939 8192000000 10124552112 10596147200 1128664032 12.109 99 45 87 1 1231242672 93661776 8916864
10:16:03 101873 8192000000 10139640640 10596614144 1125081504 12.075 99 26 50 0 1232517328 101386912 6048912
10:18:45 52330 8192000000 10097362608 10651131904 1104149408 11.877 98 11 22 0 1195604432 89241952 2213072
10:20:38 82017 8192000000 10051730128 10544713728 1119638240 12.024 100 10 21 0 1161121312 38577840 2905232
10:23:14 61317 8192000000 10088146688 10547662848 1113314128 11.964 98 14 28 0 1170546384 54354800 2877456
10:25:19 47209 8192000000 10066318016 10512867328 1090605360 11.748 96 24 46 3 1122267424 26995776 4666288
10:27:10 34972 8192000000 10113833920 10561085440 1118600528 12.014 98 42 80 0 1192755504 65923024 8231952
10:28:45 34220 8192000000 10180540800 10612854784 1139499712 12.211 100 41 82 1 1260803952 112484768 8819472
10:30:20 18980 8192000000 10140832752 10571472896 1106464512 11.899 98 43 84 2 1224305024 109157328 8683184
10:31:43 34092 8192000000 10215539328 10645782528 1110376080 11.936 98 46 92 0 1268496816 148514272 9606464
10:33:57 38998 8192000000 10235681936 10788737024 1125211776 12.076 99 20 40 1 1378076720 247370064 5494880
10:35:46 69377 8192000000 10161838640 10704715776 1138088624 12.198 100 14 28 1 1300177952 158853744 3235584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-06-2022 10:38:14 to 04-06-2022 10:38:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-06-2022 10:38:30 to 04-06-2022 10:38:32.

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          214899
MON$OLDEST_ACTIVE               215370
MON$OLDEST_SNAPSHOT             215370
MON$NEXT_TRANSACTION            215370
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                       2078560
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
:103: 
:104: mscs-test-161.budzdorov.ru    Sat Jun  4 10:37:19 2022
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:106: 
:107: 
:108: mscs-test-161.budzdorov.ru    Sat Jun  4 10:37:28 2022
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:110: 
--- end of diff output ---

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

04.06.22 10:38:33. 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
2022-06-04 10:38:36 No severe PSQL-related problems occured
04.06.22 10:38:37. End of report.