Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.05.2022 01: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 16319020 kB
MemAvailable 31450456 kB
Buffers 0 kB
Cached 15412932 kB
SwapCached 2588 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 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-05-20 01:38:15
DTS_END                         2022-05-20 01:38:15.6940
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(57:17)
IP                              ::1/53864
TRN_ID                          234460
ATT_ID                          615
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: 9584.94 22642 1150193
customer order: creation 165.82 355 19898
customer order: refuse 13.73 342 1648
order to supplier: creation 43.41 1355 5209
order to supplier: removal 3.74 109173 449
invoice (draft): creation 43.51 3719 5221
invoice (draft): removal 58.30 854 6996
invoice accept: apply 39.62 18263 4754
invoice accept: cancel 52.48 770 6298
customer reserve: creation 3732.10 176 447852
customer reserve: removal 5143.32 61 617198
realization accept: apply 79.03 200 9483
realization accept: cancel 86.21 108 10345
payment from customer: creation 56.97 130 6836
payment from customer: removal 5.15 83 618
payment to supplier: creation 46.51 153 5581
payment to supplier: removal 3.63 120 436
service: total inventory turnovers 4.64 1069 557
service: total monetary turnovers 5.98 43 718
service: aggregate perf. data 0.77 18523 92
service: refresh index statistics 0.03 297347 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-05-2022 01:39:48 to 20-05-2022 01:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3568
TEST_TIME 2 4007
TEST_TIME 3 4461
TEST_TIME 4 4049
TEST_TIME 5 5049
TEST_TIME 6 5180
TEST_TIME 7 5507
TEST_TIME 8 5907
TEST_TIME 9 5920
TEST_TIME 10 6248
TEST_TIME 11 6570
TEST_TIME 12 6693
TEST_TIME 13 6698
TEST_TIME 14 6821
TEST_TIME 15 6829
TEST_TIME 16 7305
TEST_TIME 17 7772
TEST_TIME 18 7875
TEST_TIME 19 8012
TEST_TIME 20 8113
TEST_TIME 21 8086
TEST_TIME 22 8385
TEST_TIME 23 8460
TEST_TIME 24 8639
TEST_TIME 25 8739
TEST_TIME 26 9057
TEST_TIME 27 8923
TEST_TIME 28 9231
TEST_TIME 29 10742
TEST_TIME 30 10863
TEST_TIME 31 10679
TEST_TIME 32 10805
TEST_TIME 33 10792
TEST_TIME 34 10681
TEST_TIME 35 10445
TEST_TIME 36 10509
TEST_TIME 37 10354
TEST_TIME 38 10374
TEST_TIME 39 10218
TEST_TIME 40 10144
TEST_TIME 41 10067
TEST_TIME 42 10018
TEST_TIME 43 10056
TEST_TIME 44 9994
TEST_TIME 45 9954
TEST_TIME 46 9842
TEST_TIME 47 9780
TEST_TIME 48 9795
TEST_TIME 49 10063
TEST_TIME 50 10119
TEST_TIME 51 10036
TEST_TIME 52 10086
TEST_TIME 53 9977
TEST_TIME 54 10164
TEST_TIME 55 10136
TEST_TIME 56 10075
TEST_TIME 57 10064
TEST_TIME 58 10156
TEST_TIME 59 10154
TEST_TIME 60 10169
TEST_TIME 61 10125
TEST_TIME 62 10005
TEST_TIME 63 9959
TEST_TIME 64 9896
TEST_TIME 65 9847
TEST_TIME 66 9842
TEST_TIME 67 9744
TEST_TIME 68 9736
TEST_TIME 69 9844
TEST_TIME 70 9768
TEST_TIME 71 9770
TEST_TIME 72 9801
TEST_TIME 73 9818
TEST_TIME 74 9860
TEST_TIME 75 9855
TEST_TIME 76 9838
TEST_TIME 77 9837
TEST_TIME 78 9833
TEST_TIME 79 9859
TEST_TIME 80 9951
TEST_TIME 81 9994
TEST_TIME 82 10070
TEST_TIME 83 9952
TEST_TIME 84 9917
TEST_TIME 85 9883
TEST_TIME 86 9850
TEST_TIME 87 9833
TEST_TIME 88 9775
TEST_TIME 89 9709
TEST_TIME 90 9679
TEST_TIME 91 9641
TEST_TIME 92 9635
TEST_TIME 93 9638
TEST_TIME 94 9640
TEST_TIME 95 9620
TEST_TIME 96 9670
TEST_TIME 97 9638
TEST_TIME 98 9651
TEST_TIME 99 9722
TEST_TIME 100 9719
TEST_TIME 101 9741
TEST_TIME 102 9744
TEST_TIME 103 9788
TEST_TIME 104 9765
TEST_TIME 105 9796
TEST_TIME 106 9777
TEST_TIME 107 9837
TEST_TIME 108 9794
TEST_TIME 109 9727
TEST_TIME 110 9747
TEST_TIME 111 9698
TEST_TIME 112 9656
TEST_TIME 113 9695
TEST_TIME 114 9706
TEST_TIME 115 9658
TEST_TIME 116 9641
TEST_TIME 117 9600
TEST_TIME 118 9615
TEST_TIME 119 9608
TEST_TIME 120 9587

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-05-2022 01:39:51 to 20-05-2022 01:39:55.

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 1565517 1565517 0 0.00 0 28212 6 0 0
doc_list_biud 1565517 1565517 0 0.00 0 29043 30 0 0
sp_add_invoice_to_stock 4878 4754 124 2.54 32 219673 18263 0 124
sp_cancel_adding_invoice 6298 6298 0 0.00 4 52777 770 0 0
sp_cancel_client_order 1648 1648 0 0.00 4 8062 342 0 0
sp_cancel_customer_reserve 617198 617198 0 0.00 1 20609 61 0 0
sp_cancel_pay_from_customer 618 618 0 0.00 3 7322 84 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 2 7917 121 0 0
sp_cancel_supplier_invoice 7010 6996 14 0.19 1 16070 854 0 14
sp_cancel_supplier_order 449 449 0 0.00 6 1202328 109173 0 0
sp_cancel_write_off 10345 10345 0 0.00 1 9383 109 0 0
sp_client_order 19898 19898 0 0.00 3 21473 355 0 0
sp_customer_reserve 447852 447852 0 0.00 0 59417 176 0 0
sp_fill_shopping_cart 30324 30324 0 0.00 1 15339 37 0 0
sp_fill_shopping_cart_clo_res 447849 447849 0 0.00 0 19351 14 0 0
sp_get_clo_for_invoice 4751 4751 0 0.00 0 42649 326 0 0
sp_kill_cost_storno 17697 17697 0 0.00 0 18699 61 0 0
sp_kill_qstorno_ret_qs2qd 1252186 1252186 0 0.00 0 29040 33 0 0
sp_kill_qty_storno 657168 657168 0 0.00 0 29042 72 0 0
sp_lock_selected_doc 19771 19771 0 0.00 0 7943 6 0 0
sp_make_cost_storno 26651 26651 0 0.00 0 28205 70 0 0
sp_multiply_rows_for_pdistr 26651 26651 0 0.00 0 21152 36 0 0
sp_multiply_rows_for_qdistr 19898 19898 0 0.00 0 11342 175 0 0
sp_pay_from_customer 6836 6836 0 0.00 4 21159 130 0 0
sp_pay_to_supplier 5581 5581 0 0.00 4 16212 154 0 0
sp_payment_common 12417 12417 0 0.00 1 11683 34 0 0
sp_qd_handle_on_cancel_clo 1648 1648 0 0.00 1 8054 316 0 0
sp_qd_handle_on_invoice_upd_sts 11049 11049 0 0.00 0 18714 348 0 0
sp_qd_handle_on_reserve_upd_sts 9483 9483 0 0.00 0 15288 64 0 0
sp_reserve_write_off 9483 9483 0 0.00 6 15306 201 0 0
sp_supplier_invoice 5221 5221 0 0.00 7 117289 3719 0 0
sp_supplier_order 5209 5209 0 0.00 5 41981 1356 0 0
srv_aggregate_perf_data 92 92 0 0.00 2603 234180 18524 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1294 111798 46044 0 0
srv_find_qd_qs_mism 1104610 1104610 0 0.00 0 24344 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 41 65241 1069 0 0
srv_make_money_saldo 718 718 0 0.00 1 11254 44 0 0
srv_recalc_idx_stat 4 4 0 0.00 178504 486003 297347 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4755 4755 0 0.00 0 17182 188 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 2652 85 0 0
t$perf-norm:sp_cancel_client_order 1648 1648 0 0.00 0 5663 18 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 2392 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 618 618 0 0.00 0 2219 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 10058 38 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 944 14 0 0
t$perf-norm:sp_cancel_supplier_order 449 449 0 0.00 0 30066 1624 0 0
t$perf-norm:sp_cancel_write_off 951 951 0 0.00 0 4041 12 0 0
t$perf-norm:sp_client_order 19898 19898 0 0.00 0 11047 21 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 8836 13 0 0
t$perf-norm:sp_pay_from_customer 6836 6836 0 0.00 0 12627 14 0 0
t$perf-norm:sp_pay_to_supplier 5581 5581 0 0.00 0 9787 15 0 0
t$perf-norm:sp_reserve_write_off 9483 9483 0 0.00 0 14446 14 0 0
t$perf-norm:sp_supplier_invoice 5221 5221 0 0.00 0 13612 22 0 0
t$perf-norm:sp_supplier_order 5209 5209 0 0.00 0 9831 22 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 549 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 4 1 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 927 26 0 0
t$perf-norm:srv_make_money_saldo 718 718 0 0.00 0 931 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9750 9502 248 2.54 0 9389 17 0 248
v_all_customers 36392 36392 0 0.00 0 48 1 0 0
v_all_suppliers 29162 29162 0 0.00 0 19 1 0 0
v_all_wares 39796 39796 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 700 7 0 0
v_cancel_client_order 3296 3296 0 0.00 0 5130 6 0 0
v_cancel_customer_prepayment 1236 1236 0 0.00 0 4335 10 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 1489 5 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 7900 17 0 0
v_cancel_supplier_invoice 804 776 28 3.48 0 1558 15 0 28
v_cancel_supplier_order 898 898 0 0.00 0 5839 12 0 0
v_cancel_write_off 1902 1902 0 0.00 0 3951 16 0 0
v_max_id_clo_ord 5209 5209 0 0.00 0 735 1 0 0
v_max_id_clo_res 947 947 0 0.00 0 3879 11 0 0
v_max_id_ord_sup 5217 5217 0 0.00 0 5587 5 0 0
v_max_non_paid_invoice 1426 1426 0 0.00 0 1377 4 0 0
v_max_non_paid_realizn 3557 3557 0 0.00 0 5690 7 0 0
v_min_id_clo_ord 5209 5209 0 0.00 0 5822 9 0 0
v_min_id_clo_res 947 947 0 0.00 0 1521 4 0 0
v_min_id_ord_sup 5217 5217 0 0.00 0 7258 6 0 0
v_min_non_paid_invoice 5581 5581 0 0.00 0 11678 21 0 0
v_min_non_paid_realizn 6836 6836 0 0.00 0 10294 26 0 0
v_our_firm 9962 9962 0 0.00 0 18 1 0 0
v_reserve_write_off 18966 18966 0 0.00 0 6021 6 0 0
x_lock_depdocs_on_canc_invoice 12906 12906 0 0.00 0 18972 126 0 0
x_lock_depdocs_on_canc_sup_ord 449 449 0 0.00 0 6767 360 0 0
x_make_qstorno_1000_1200 5209 5209 0 0.00 1 41975 1267 0 0
x_make_qstorno_1200_2000 5221 5221 0 0.00 2 117217 3591 0 0
x_make_qstorno_2100_3300 447852 447852 0 0.00 0 59415 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-05-2022 01:39:56 to 20-05-2022 01:40:00.

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
23:08:19 13 8192000000 8809690752 8824524800 14188176 0.172 4 3 5 0 14382656 77360 117120
23:09:19 17434 8192000000 9892329280 10198876160 978050576 10.665 100 91 182 0 1008079952 6127584 23901792
23:10:42 30651 8192000000 9929899168 10248036352 1010710112 10.982 100 88 174 1 1041919088 8603984 22604992
23:12:14 18154 8192000000 9967668928 10297208832 1047368512 11.335 100 83 166 1 1078192496 9771184 21052800
23:13:53 15029 8192000000 10041306096 10391232512 1124654304 12.071 100 80 160 0 1154832448 10753472 19424672
23:15:15 6553 8192000000 10092569872 10452357120 1176069040 12.554 100 82 164 0 1208611536 13269184 19273312
23:16:25 3531 8192000000 10135287184 10505445376 1218883424 12.951 100 89 178 0 1254818048 14915856 21018768
23:17:31 3011 8192000000 10159384848 10537164800 1248965552 13.229 100 79 155 3 1277726672 11935456 16825664
23:18:35 1919 8192000000 10204384336 10586140672 1283017744 13.541 100 90 176 0 1315925136 13227616 19679776
23:19:39 32247 8192000000 10212101408 10601795584 1299176384 13.688 100 57 114 1 1320965808 8091632 13697792
23:21:11 4483 8192000000 10205566832 10614661120 1310492416 13.791 100 42 84 2 1328228976 7836816 9899744
23:22:16 12873 8192000000 10211800432 10627162112 1323318912 13.907 100 15 30 0 1332305376 5793664 3192800
23:23:33 3031 8192000000 10215858304 10634301440 1329641744 13.964 100 36 73 1 1345973360 8383584 7948032
23:24:36 53161 8192000000 10233495216 10651791360 1334327136 14.006 100 23 47 0 1345065360 5299472 5438752
23:26:35 6035 8192000000 10238992544 10651516928 1335541888 14.017 100 30 58 0 1348759728 6444992 6772848
23:27:42 9737 8192000000 10226839328 10641698816 1307140512 13.760 98 26 52 3 1320185504 6563904 6481088
23:28:52 8791 8192000000 10211967984 10631512064 1312702960 13.811 99 23 46 1 1324743568 7095968 4944640
23:30:02 31750 8192000000 10161419280 10569842688 1205707824 12.829 93 32 63 1 1218084432 5704240 6672368
23:31:35 8780 8192000000 10150661936 10548871168 1242796272 13.172 98 25 50 2 1254075360 5923168 5355920
23:32:46 10872 8192000000 10149353344 10552676352 1238529024 13.133 98 16 32 0 1247961888 5271088 4161776
23:33:58 29417 8192000000 10171883824 10583789568 1272854768 13.448 99 34 68 0 1287514624 6705968 7953888
23:35:59 11299 8192000000 10195627824 10606637056 1298670048 13.683 100 23 46 0 1314597904 9833680 6094176
23:37:11 8777 8192000000 10205559616 10623180800 1311663168 13.801 100 16 32 0 1325052208 9244416 4144624
23:38:28 26791 8192000000 10231554384 10648064000 1337865024 14.038 100 21 41 0 1347680976 5139056 4676896
23:40:00 15967 8192000000 10258303072 10674655232 1354691376 14.190 100 43 84 2 1373775568 9125040 9959152
23:41:25 107136 8192000000 10275927264 10705117184 1367237904 14.302 100 45 90 0 1387092752 8816304 11038544
23:44:17 14957 8192000000 10284850720 10710028288 1377174784 14.391 100 37 74 1 1399484128 12720352 9588992
23:45:39 17021 8192000000 10303121136 10732220416 1385816304 14.469 100 36 72 0 1415185024 20397792 8970928
23:47:02 7336 8192000000 10321341216 10744127488 1399459968 14.590 100 68 134 2 1434636832 19442272 15734592
23:48:17 12899 8192000000 10330645680 10748841984 1397818640 14.576 100 72 141 1 1442728704 27934720 16975344
23:49:39 2618 8192000000 10370206176 10781495296 1424783408 14.815 100 89 176 2 1477143088 31750448 20609232
23:50:46 1293 8192000000 10377050128 10788843520 1426528704 14.831 100 85 169 2 1479008896 30869792 21610400
23:51:52 2753 8192000000 10374044336 10784862208 1419134832 14.765 100 78 156 1 1475082912 36541296 19406784
23:52:59 6129 8192000000 10378775824 10796244992 1425323744 14.820 100 66 132 3 1481628352 39609568 16695040
23:54:10 3079 8192000000 10376246800 10787344384 1414342608 14.723 100 82 161 2 1483782944 47668000 21772336
23:55:20 5929 8192000000 10343578528 10763796480 1395443488 14.554 100 41 83 1 1446433728 39659632 11330608
23:56:44 5117 8192000000 10351617248 10768719872 1398553136 14.582 100 67 134 1 1453693520 38277952 16862432
23:57:55 7377 8192000000 10355245136 10776317952 1405801744 14.647 100 54 107 0 1460715936 40515616 14398576
23:59:08 10907 8192000000 10330011712 10746998784 1369925648 14.326 99 44 88 1 1425348464 43380304 12042512
00:00:26 4994 8192000000 10358646032 10771795968 1394613216 14.547 100 54 107 0 1459440432 50658384 14168832
00:01:34 8363 8192000000 10314938032 10727858176 1343297568 14.087 97 43 86 3 1398992352 43837824 11856960
00:02:49 9505 8192000000 10341695456 10764873728 1382696576 14.441 100 35 70 0 1445262672 52905328 9660768
00:04:07 13133 8192000000 10322125984 10750230528 1369816800 14.325 99 28 56 1 1428587568 50676096 8094672
00:05:24 19782 8192000000 10326364112 10754723840 1354470176 14.188 100 34 68 2 1428059136 63531216 10057744
00:06:46 25353 8192000000 10314635312 10750398464 1334873824 14.011 99 24 48 0 1403643968 61399280 7370864
00:08:14 45943 8192000000 10282167040 10712145920 1358327264 14.222 100 12 24 1 1379904448 18933824 2643360
00:10:00 6989 8192000000 10265539232 10695000064 1343314704 14.087 99 45 90 0 1362591200 8897104 10379392
00:11:09 60380 8192000000 10271736512 10711199744 1336423696 14.025 98 6 12 1 1349880096 12182992 1273408
00:13:09 74203 8192000000 10298998208 10734137344 1377817856 14.397 100 41 82 0 1396757488 9378736 9560896
00:16:19 96730 8192000000 10296804128 10752012288 1378167584 14.400 99 28 54 1 1393265856 9214624 5883648
00:19:02 60722 8192000000 10330930960 10778136576 1412397008 14.705 100 30 60 2 1434569888 14875296 7297584
00:21:04 47019 8192000000 10313942304 10780188672 1408584816 14.671 100 11 22 0 1429623632 18183984 2854832
00:22:52 89567 8192000000 10314672688 10780676096 1372133936 14.346 98 14 28 0 1400466080 25035408 3296736
00:25:23 51813 8192000000 10303245328 10758307840 1371948720 14.345 98 18 36 0 1398705296 22071088 4685488
00:27:16 30803 8192000000 10291645808 10749825024 1389208048 14.499 100 18 36 0 1412246016 18396672 4641296
00:28:51 48018 8192000000 10293080368 10745749504 1355763888 14.199 97 19 38 1 1377525616 16323360 5438368
00:30:47 52974 8192000000 10311269216 10768801792 1391378192 14.518 100 10 20 0 1408930416 15256304 2295920
00:32:56 49321 8192000000 10277314800 10729553920 1319317904 13.871 95 19 38 0 1331185024 7848848 4018272
00:34:45 39785 8192000000 10294678176 10727190528 1335869024 14.020 97 43 86 1 1358503200 13309520 9324656
00:36:27 100261 8192000000 10271184848 10724933632 1308366512 13.771 95 14 28 0 1323828288 12628320 2833456
00:39:10 86311 8192000000 10270792640 10711130112 1346104016 14.112 98 34 68 1 1363394064 9456048 7834000
00:41:42 59556 8192000000 10285459696 10738225152 1366812720 14.298 99 22 45 0 1384941184 13455728 4672736
00:43:43 83696 8192000000 10285902640 10742267904 1364155152 14.275 99 22 44 0 1384180000 14822448 5202400
00:46:55 55139 8192000000 10250463760 10719592448 1309274784 13.779 96 11 22 0 1333036640 21077200 2684656
00:48:53 72556 8192000000 10263521584 10718085120 1320798096 13.884 96 21 42 0 1345706528 19769840 5138592
00:51:06 111797 8192000000 10275167472 10734538752 1338513120 14.044 98 11 22 0 1360718736 19857904 2347712
00:54:00 27257 8192000000 10261791472 10704961536 1313039712 13.814 96 32 64 1 1328266576 7346544 7880320
00:55:28 45654 8192000000 10252815952 10692907008 1324244032 13.915 97 34 69 0 1341192192 9069792 7878368
00:57:17 64561 8192000000 10263583056 10719604736 1329070688 13.959 98 28 56 0 1360750784 25237200 6442896
00:59:24 109764 8192000000 10265950400 10739752960 1328707536 13.955 98 5 10 0 1353448896 23690000 1051360
01:03:00 44271 8192000000 10264366304 10717282304 1353419568 14.178 100 20 40 1 1371340176 13353664 4566944
01:04:49 45931 8192000000 10267990816 10724945920 1351969232 14.165 99 12 23 2 1368304320 13118480 3216608
01:06:35 64975 8192000000 10275173568 10732814336 1359953184 14.237 99 9 18 0 1369187792 7332624 1901984
01:08:40 74644 8192000000 10270505744 10729459712 1362207456 14.257 99 18 37 1 1380130800 13715216 4208128
01:11:13 53677 8192000000 10268883072 10726813696 1360702896 14.244 99 11 22 0 1378555024 15336000 2516128
01:13:19 84536 8192000000 10303047200 10762743808 1389802016 14.504 100 13 26 1 1407008512 14048320 3158176
01:15:47 75141 8192000000 10310841392 10778619904 1381030320 14.426 98 16 32 0 1402827216 17743456 4053440
01:18:04 52885 8192000000 10321730192 10776129536 1389773088 14.504 99 22 44 1 1407013536 12706848 4533600
01:19:58 35823 8192000000 10325868160 10769141760 1397543856 14.573 100 39 78 0 1420783088 14325232 8914000
01:21:37 45350 8192000000 10305103360 10754179072 1369612384 14.324 98 14 28 1 1382691216 10342064 2736768
01:23:23 51464 8192000000 10285034800 10734448640 1335468368 14.017 96 16 32 0 1349987456 10932080 3587008
01:25:26 56903 8192000000 10299736944 10763264000 1361169648 14.248 98 16 32 0 1383083616 18354400 3559568
01:27:40 104053 8192000000 10292383536 10757865472 1375891312 14.380 99 22 44 0 1393706304 12703168 5111824
01:30:53 70938 8192000000 10279261776 10748170240 1360021760 14.238 98 24 48 1 1383374240 17316720 6035760
01:33:05 66955 8192000000 10294734224 10753101824 1389076192 14.498 100 12 25 0 1406634304 14536144 3021968
01:35:20 81746 8192000000 10286870752 10753245184 1370676368 14.333 98 17 34 2 1389317216 14159776 4481072
01:37:46 38215 8192000000 9004914688 9293197312 146019968 1.751 11 3 6 0 147975360 1428016 527376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-05-2022 01:40:02 to 20-05-2022 01:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-05-2022 01:40:17 to 20-05-2022 01:40:19.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          234761
MON$OLDEST_ACTIVE               234762
MON$OLDEST_SNAPSHOT             234762
MON$NEXT_TRANSACTION            234762
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-03 16:41:55.3470
MON$PAGES                       2054688
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Fri May 20 01:38:25 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
--- end of diff output ---

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

20.05.22 01:40:20. There are 3 logs in /var/tmp/logs-oltp30 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-05-20 01:40:23 No severe PSQL-related problems occured
20.05.22 01:40:24. End of report.