Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.10.2022 01:40
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 15257204 kB
MemAvailable 30596080 kB
Buffers 0 kB
Cached 16479268 kB
SwapCached 2072 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.11
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-10-03 01:38:26
DTS_END                         2022-10-03 01:38:26.1610
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59594
TRN_ID                          237591
ATT_ID                          675
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: 9727.77 21278 1167332
customer order: creation 171.73 358 20608
customer order: refuse 14.88 395 1786
order to supplier: creation 45.84 1236 5501
order to supplier: removal 3.79 104546 455
invoice (draft): creation 46.00 3406 5520
invoice (draft): removal 56.96 811 6835
invoice accept: apply 41.79 17207 5015
invoice accept: cancel 51.63 791 6195
customer reserve: creation 3920.29 168 470435
customer reserve: removal 5068.03 61 608164
realization accept: apply 83.13 205 9975
realization accept: cancel 92.28 117 11073
payment from customer: creation 59.77 126 7172
payment from customer: removal 5.68 105 681
payment to supplier: creation 50.44 140 6053
payment to supplier: removal 3.42 84 410
service: total inventory turnovers 4.56 1121 547
service: total monetary turnovers 6.71 31 805
service: aggregate perf. data 0.82 20666 98
service: refresh index statistics 0.03 273981 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 03-10-2022 01:41:16 to 03-10-2022 01:41:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5408
TEST_TIME 2 5014
TEST_TIME 3 5033
TEST_TIME 4 5463
TEST_TIME 5 5359
TEST_TIME 6 5265
TEST_TIME 7 5624
TEST_TIME 8 5596
TEST_TIME 9 5842
TEST_TIME 10 5676
TEST_TIME 11 5972
TEST_TIME 12 6381
TEST_TIME 13 6824
TEST_TIME 14 7358
TEST_TIME 15 7302
TEST_TIME 16 7201
TEST_TIME 17 7816
TEST_TIME 18 7841
TEST_TIME 19 8300
TEST_TIME 20 8236
TEST_TIME 21 8270
TEST_TIME 22 8456
TEST_TIME 23 8586
TEST_TIME 24 8821
TEST_TIME 25 9215
TEST_TIME 26 9490
TEST_TIME 27 10545
TEST_TIME 28 11341
TEST_TIME 29 11522
TEST_TIME 30 11688
TEST_TIME 31 11418
TEST_TIME 32 11486
TEST_TIME 33 11301
TEST_TIME 34 11196
TEST_TIME 35 11458
TEST_TIME 36 11256
TEST_TIME 37 11038
TEST_TIME 38 10936
TEST_TIME 39 10850
TEST_TIME 40 10834
TEST_TIME 41 10983
TEST_TIME 42 10791
TEST_TIME 43 10686
TEST_TIME 44 10579
TEST_TIME 45 10634
TEST_TIME 46 10765
TEST_TIME 47 10699
TEST_TIME 48 10668
TEST_TIME 49 10619
TEST_TIME 50 10459
TEST_TIME 51 10321
TEST_TIME 52 10334
TEST_TIME 53 10325
TEST_TIME 54 10259
TEST_TIME 55 10186
TEST_TIME 56 10162
TEST_TIME 57 10172
TEST_TIME 58 10150
TEST_TIME 59 10080
TEST_TIME 60 10118
TEST_TIME 61 10311
TEST_TIME 62 10337
TEST_TIME 63 10390
TEST_TIME 64 10336
TEST_TIME 65 10469
TEST_TIME 66 10468
TEST_TIME 67 10472
TEST_TIME 68 10581
TEST_TIME 69 10506
TEST_TIME 70 10378
TEST_TIME 71 10428
TEST_TIME 72 10333
TEST_TIME 73 10284
TEST_TIME 74 10231
TEST_TIME 75 10150
TEST_TIME 76 10122
TEST_TIME 77 10169
TEST_TIME 78 10106
TEST_TIME 79 10040
TEST_TIME 80 10081
TEST_TIME 81 10075
TEST_TIME 82 10025
TEST_TIME 83 10112
TEST_TIME 84 10118
TEST_TIME 85 10092
TEST_TIME 86 10072
TEST_TIME 87 10165
TEST_TIME 88 10141
TEST_TIME 89 10177
TEST_TIME 90 10221
TEST_TIME 91 10268
TEST_TIME 92 10274
TEST_TIME 93 10178
TEST_TIME 94 10100
TEST_TIME 95 10072
TEST_TIME 96 10027
TEST_TIME 97 9995
TEST_TIME 98 9948
TEST_TIME 99 9973
TEST_TIME 100 10011
TEST_TIME 101 9973
TEST_TIME 102 9927
TEST_TIME 103 9948
TEST_TIME 104 9954
TEST_TIME 105 9977
TEST_TIME 106 9940
TEST_TIME 107 9996
TEST_TIME 108 10036
TEST_TIME 109 10015
TEST_TIME 110 10000
TEST_TIME 111 10020
TEST_TIME 112 9944
TEST_TIME 113 9938
TEST_TIME 114 9894
TEST_TIME 115 9873
TEST_TIME 116 9869
TEST_TIME 117 9850
TEST_TIME 118 9820
TEST_TIME 119 9786
TEST_TIME 120 9729

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-10-2022 01:41:20 to 03-10-2022 01:41:23.

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 1604388 1604388 0 0.00 0 17724 6 0 0
doc_list_biud 1604387 1604387 0 0.00 0 56952 29 0 0
sp_add_invoice_to_stock 5137 5015 122 2.37 44 151951 17207 0 122
sp_cancel_adding_invoice 6195 6195 0 0.00 3 52151 791 0 0
sp_cancel_client_order 1786 1786 0 0.00 3 11630 396 0 0
sp_cancel_customer_reserve 608164 608164 0 0.00 0 56954 62 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 2 6877 106 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 5933 84 0 0
sp_cancel_supplier_invoice 6839 6835 4 0.05 1 11218 812 0 4
sp_cancel_supplier_order 455 455 0 0.00 6 1272279 104546 0 0
sp_cancel_write_off 11073 11073 0 0.00 1 16402 118 0 0
sp_client_order 20608 20608 0 0.00 3 14370 359 0 0
sp_customer_reserve 470435 470435 0 0.00 0 112780 169 0 0
sp_fill_shopping_cart 31617 31617 0 0.00 1 12560 33 0 0
sp_fill_shopping_cart_clo_res 470430 470430 0 0.00 0 12305 13 0 0
sp_get_clo_for_invoice 5010 5010 0 0.00 0 17881 283 0 0
sp_kill_cost_storno 18359 18359 0 0.00 0 13865 59 0 0
sp_kill_qstorno_ret_qs2qd 1234691 1234691 0 0.00 0 56907 33 0 0
sp_kill_qty_storno 649493 649493 0 0.00 0 56952 72 0 0
sp_lock_selected_doc 20841 20841 0 0.00 0 7641 6 0 0
sp_make_cost_storno 28210 28210 0 0.00 0 17721 61 0 0
sp_multiply_rows_for_pdistr 28210 28210 0 0.00 0 12347 31 0 0
sp_multiply_rows_for_qdistr 20608 20608 0 0.00 0 10650 152 0 0
sp_pay_from_customer 7172 7172 0 0.00 4 13072 127 0 0
sp_pay_to_supplier 6053 6053 0 0.00 5 18345 140 0 0
sp_payment_common 13225 13225 0 0.00 1 17798 31 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 11624 367 0 0
sp_qd_handle_on_invoice_upd_sts 11205 11205 0 0.00 0 15118 342 0 0
sp_qd_handle_on_reserve_upd_sts 9975 9975 0 0.00 0 18452 75 0 0
sp_reserve_write_off 9975 9975 0 0.00 6 18468 206 0 0
sp_supplier_invoice 5520 5520 0 0.00 6 81811 3407 0 0
sp_supplier_order 5501 5501 0 0.00 5 27383 1236 0 0
srv_aggregate_perf_data 98 98 0 0.00 2970 406079 20667 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1178 148662 41698 0 0
srv_find_qd_qs_mism 1119787 1119787 0 0.00 0 9416 5 0 0
srv_make_invnt_saldo 547 547 0 0.00 34 21369 1122 0 0
srv_make_money_saldo 805 805 0 0.00 1 3464 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 86699 496615 273981 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5015 5015 0 0.00 0 11900 162 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 3911 83 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 5579 14 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 2226 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 312 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1620 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 2806 22 0 0
t$perf-norm:sp_cancel_supplier_order 455 455 0 0.00 0 32474 1705 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 1123 5 0 0
t$perf-norm:sp_client_order 20608 20608 0 0.00 0 12217 19 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 666 4 0 0
t$perf-norm:sp_pay_from_customer 7172 7172 0 0.00 0 8851 11 0 0
t$perf-norm:sp_pay_to_supplier 6053 6053 0 0.00 0 6588 13 0 0
t$perf-norm:sp_reserve_write_off 9975 9975 0 0.00 0 6486 13 0 0
t$perf-norm:sp_supplier_invoice 5520 5520 0 0.00 0 8579 17 0 0
t$perf-norm:sp_supplier_order 5501 5501 0 0.00 0 5898 13 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 197 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 3015 46 0 0
t$perf-norm:srv_make_invnt_saldo 547 547 0 0.00 0 1074 22 0 0
t$perf-norm:srv_make_money_saldo 805 805 0 0.00 0 921 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10258 10014 244 2.37 0 7434 19 0 244
v_all_customers 37886 37886 0 0.00 0 31 1 0 0
v_all_suppliers 31102 31102 0 0.00 0 18 1 0 0
v_all_wares 41216 41216 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 1184 7 0 0
v_cancel_client_order 3572 3572 0 0.00 0 3570 5 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 6865 21 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 1889 6 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 461 5 0 0
v_cancel_supplier_invoice 836 828 8 0.95 0 1441 18 0 8
v_cancel_supplier_order 910 910 0 0.00 0 2415 10 0 0
v_cancel_write_off 2000 2000 0 0.00 0 6590 17 0 0
v_max_id_clo_ord 5493 5493 0 0.00 0 1860 2 0 0
v_max_id_clo_res 987 987 0 0.00 0 1810 10 0 0
v_max_id_ord_sup 5515 5515 0 0.00 0 1203 2 0 0
v_max_non_paid_invoice 1510 1510 0 0.00 0 503 3 0 0
v_max_non_paid_realizn 3645 3645 0 0.00 0 3375 5 0 0
v_min_id_clo_ord 5493 5493 0 0.00 0 4533 7 0 0
v_min_id_clo_res 987 987 0 0.00 0 2955 9 0 0
v_min_id_ord_sup 5515 5515 0 0.00 0 6471 5 0 0
v_min_non_paid_invoice 6053 6053 0 0.00 0 17756 23 0 0
v_min_non_paid_realizn 7172 7172 0 0.00 0 10798 22 0 0
v_our_firm 10384 10384 0 0.00 0 20 1 0 0
v_reserve_write_off 19950 19950 0 0.00 0 6983 7 0 0
x_lock_depdocs_on_canc_invoice 12616 12616 0 0.00 0 19450 122 0 0
x_lock_depdocs_on_canc_sup_ord 455 455 0 0.00 0 7812 347 0 0
x_make_qstorno_1000_1200 5501 5501 0 0.00 1 27368 1160 0 0
x_make_qstorno_1200_2000 5520 5520 0 0.00 2 81369 3297 0 0
x_make_qstorno_2100_3300 470435 470435 0 0.00 0 112779 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-10-2022 01:41:24 to 03-10-2022 01:41:28.

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:30 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:30 29375 8192000000 9894784848 10203598848 974416992 10.630 100 87 174 0 1002593488 5401680 22774816
23:11:10 14465 8192000000 9927809792 10248151040 1012494256 10.999 100 79 158 0 1040781392 7960480 20326656
23:12:32 9500 8192000000 9967706576 10296299520 1053048608 11.390 100 81 162 0 1082826432 9375760 20402064
23:13:49 10098 8192000000 10011106256 10356072448 1101753376 11.854 100 72 144 1 1129472912 10559568 17159968
23:15:02 5295 8192000000 10062646912 10416246784 1149320544 12.303 100 77 154 0 1179425376 12261520 17843312
23:16:11 3186 8192000000 10107647280 10474733568 1202148256 12.796 100 77 152 0 1231197504 11549184 17500064
23:17:17 3459 8192000000 10147309248 10518683648 1234621776 13.097 100 79 154 0 1265097040 13235280 17239984
23:18:22 2206 8192000000 10167127328 10546728960 1262908112 13.357 100 88 176 0 1295274192 13639984 18726096
23:19:27 1347 8192000000 10202499184 10586451968 1285335680 13.562 100 94 188 0 1319561936 13348320 20877936
23:20:30 23143 8192000000 10199963072 10596528128 1297926992 13.676 100 53 106 0 1318568592 8912000 11729600
23:21:54 2945 8192000000 10197337072 10607009792 1309465936 13.781 100 33 66 0 1324119120 7432352 7220832
23:23:02 11651 8192000000 10198790784 10616516608 1317156752 13.851 100 17 35 1 1327379696 5849264 4373680
23:24:14 9022 8192000000 10215881648 10626113536 1322637664 13.901 100 36 70 0 1336795296 6614848 7542784
23:25:47 4799 8192000000 10227182752 10639777792 1327707184 13.946 100 49 98 1 1348725648 10477456 10541008
23:26:53 5498 8192000000 10222302448 10641858560 1332403248 13.989 100 30 59 1 1346762576 6843296 7516032
23:27:59 53886 8192000000 10223294928 10626736128 1295981456 13.659 98 50 104 1 1314357200 6170672 12205072
23:29:53 8899 8192000000 10163754128 10570252288 1236050240 13.110 95 24 48 1 1247503440 5483632 5969568
23:31:02 5379 8192000000 10155631456 10558816256 1244839648 13.191 98 27 55 1 1260203552 9699328 5664576
23:32:07 20694 8192000000 10126260928 10525212672 1193092032 12.712 95 27 54 0 1204260816 5121712 6047072
23:33:28 6357 8192000000 10154317792 10564173824 1270562160 13.427 100 24 48 0 1282291888 6239744 5489984
23:34:37 6959 8192000000 10168333184 10581123072 1281424224 13.526 100 19 36 0 1291108576 5390688 4293664
23:35:45 48046 8192000000 10193064640 10600157184 1295947808 13.658 100 20 38 0 1306160480 4870208 5342464
23:37:33 6068 8192000000 10208324496 10613166080 1304962672 13.740 100 35 71 0 1321010672 7539872 8508128
23:38:42 12464 8192000000 10235420704 10640437248 1331487168 13.981 100 47 91 0 1348186736 6334432 10365136
23:40:00 28097 8192000000 10267670464 10681081856 1354241168 14.186 100 41 82 0 1377404688 14117552 9045968
23:41:35 17757 8192000000 10286288320 10707496960 1372899760 14.353 100 33 66 0 1400385936 19707840 7778336
23:42:59 25710 8192000000 10303321424 10722844672 1381433936 14.429 100 53 104 0 1413597456 20281712 11881808
23:44:36 23899 8192000000 10314499328 10742439936 1398616704 14.583 100 40 80 0 1432785904 24333792 9835408
23:46:11 8130 8192000000 10342300992 10760818688 1408789792 14.673 100 70 139 1 1456859376 31658224 16411360
23:47:37 9673 8192000000 10339865088 10768732160 1413534016 14.715 100 36 73 0 1455260080 32466272 9259792
23:48:50 7488 8192000000 10369067776 10791206912 1426907936 14.834 100 58 114 0 1479611792 38531536 14172320
23:50:14 3069 8192000000 10372931872 10794315776 1415977328 14.737 99 64 124 1 1471081072 39877648 15226096
23:51:26 4694 8192000000 10378931712 10802016256 1428323344 14.846 100 61 122 0 1485162144 41502208 15336592
23:52:37 6341 8192000000 10370883568 10802921472 1424221280 14.810 100 49 98 0 1478427456 41890240 12315936
23:53:49 4725 8192000000 10364556000 10781184000 1395245696 14.553 99 67 131 0 1465057264 53123072 16688496
23:55:00 2800 8192000000 10374193792 10790588416 1406338848 14.651 100 75 150 0 1482430704 56484288 19607568
23:56:09 1176 8192000000 10359986640 10764595200 1393636784 14.538 100 91 180 0 1465915328 49576880 22701664
23:57:17 2941 8192000000 10380946672 10790604800 1412464032 14.706 100 80 156 1 1484522448 51626432 20431984
23:58:23 5051 8192000000 10369705296 10785419264 1402958848 14.621 98 68 133 2 1466798592 46574400 17265344
23:59:30 9227 8192000000 10352530608 10772312064 1384536352 14.457 98 41 82 0 1446967328 51070704 11360272
00:00:45 6006 8192000000 10358942624 10783457280 1398224416 14.579 100 50 97 1 1468222640 56349984 13648240
00:01:54 10181 8192000000 10365516880 10799357952 1403607760 14.627 100 39 77 0 1471312592 56688416 11016416
00:03:31 30729 8192000000 10327501296 10765873152 1359909328 14.237 99 28 57 0 1424131248 55479984 8741936
00:05:03 21610 8192000000 10320609680 10767790080 1345153696 14.104 98 22 44 0 1416094448 63868704 7072048
00:06:28 25461 8192000000 10281745456 10730729472 1346209744 14.113 98 16 32 0 1367977952 17865552 3902656
00:07:53 77887 8192000000 10290200976 10735771648 1353914192 14.183 99 10 20 0 1372449200 16422784 2112224
00:10:16 64657 8192000000 10286091472 10738143232 1358737280 14.226 99 7 14 0 1371259424 11331680 1190464
00:12:21 33055 8192000000 10296993600 10743230464 1380509904 14.421 100 18 36 0 1396022688 11271072 4241712
00:14:07 59174 8192000000 10292328960 10751004672 1379184768 14.409 100 7 12 0 1395654512 14806640 1663104
00:16:32 20115 8192000000 10307462080 10739204096 1378603824 14.404 100 45 92 0 1400837776 10933936 11300016
00:17:59 53369 8192000000 10323477120 10769199104 1400462208 14.599 100 54 108 0 1427889984 15414960 12012816
00:20:11 88345 8192000000 10323819952 10768310272 1383159600 14.445 99 20 40 1 1399424512 11703712 4561200
00:22:39 23308 8192000000 10327014512 10772881408 1401489344 14.608 99 25 50 0 1418494032 10886128 6118560
00:24:03 17330 8192000000 10303799904 10759716864 1389382128 14.500 99 57 106 3 1414421760 13094992 11944640
00:25:27 19671 8192000000 10291534240 10769829888 1393584256 14.538 100 7 14 0 1406149568 11263456 1301856
00:26:46 43051 8192000000 10294755488 10759270400 1391905760 14.523 100 11 22 0 1403761248 9511872 2343616
00:28:30 93251 8192000000 10308557392 10771832832 1405198752 14.641 100 19 38 0 1424068064 14001888 4867424
00:31:12 35919 8192000000 10307331040 10772779008 1402019072 14.613 100 17 34 0 1418642160 12520992 4102096
00:32:49 94465 8192000000 10298982336 10771771392 1388942416 14.496 99 11 22 0 1405464400 14128400 2393584
00:35:24 36914 8192000000 10293935232 10754482176 1376759536 14.388 99 29 58 1 1394223680 10205376 7258768
00:37:08 30753 8192000000 10298249456 10753617920 1381992928 14.434 99 18 36 0 1397024320 10647088 4384304
00:38:44 81519 8192000000 10296063264 10757951488 1349049392 14.139 97 12 24 1 1370768320 18828000 2890928
00:41:06 62463 8192000000 10295479616 10740887552 1306156912 13.751 94 24 48 0 1319572800 8378064 5037824
00:43:12 53140 8192000000 10279263744 10747994112 1357580576 14.216 99 8 16 0 1376571792 17219296 1771920
00:45:06 52459 8192000000 10278906672 10728542208 1354423984 14.187 99 11 22 0 1377261760 20103056 2734720
00:47:07 44494 8192000000 10248075440 10677563392 1283866736 13.548 93 25 48 1 1303425088 13934224 5624128
00:48:54 74219 8192000000 10245257216 10700759040 1323012144 13.904 97 22 44 1 1341087680 12996912 5078624
00:51:09 74170 8192000000 10243932448 10706350080 1300271744 13.698 95 16 31 0 1316962688 12972464 3718480
00:53:33 56463 8192000000 10263432816 10715406336 1341704704 14.073 99 10 18 0 1362469856 18546192 2218960
00:55:31 66574 8192000000 10261100368 10714247168 1360574704 14.243 100 15 30 0 1374763584 10279744 3909136
00:57:47 53597 8192000000 10265902592 10723139584 1363996352 14.273 100 25 48 0 1381741584 12002928 5742304
00:59:59 13085 8192000000 10253040832 10693177344 1327381344 13.943 98 37 71 2 1347144416 11500160 8262912
01:01:15 100871 8192000000 10254003600 10700267520 1278911792 13.503 94 15 30 0 1291148816 9176832 3060192
01:04:17 27016 8192000000 10293793824 10736627712 1374474720 14.367 100 48 96 0 1400387744 15046432 10866592
01:05:53 37314 8192000000 10286833968 10731556864 1374248688 14.365 100 18 36 0 1390426672 11978736 4199248
01:07:35 53364 8192000000 10317105040 10763325440 1383252192 14.446 100 25 50 0 1407308608 18584608 5471808
01:09:30 107741 8192000000 10282981664 10736312320 1325761168 13.929 95 7 14 0 1341860976 14946432 1153376
01:12:19 43491 8192000000 10259692464 10704007168 1352809136 14.173 97 21 42 0 1364689248 7419872 4460240
01:14:03 25902 8192000000 10297790592 10749845504 1387957792 14.488 100 36 72 0 1405755120 9885120 7912208
01:15:32 53162 8192000000 10295391424 10754719744 1384715488 14.459 99 19 36 1 1399502128 10804096 3982544
01:17:26 85627 8192000000 10282866560 10754277376 1335224672 14.014 96 31 60 0 1359750288 17061856 7463760
01:19:52 60952 8192000000 10300092128 10746212352 1348732256 14.136 97 26 52 0 1365218656 10860032 5626368
01:21:58 148659 8192000000 10272842192 10730418176 1305720112 13.747 94 12 24 0 1317986688 9727280 2539296
01:25:26 28814 8192000000 10291052944 10737393664 1371747648 14.343 100 23 46 0 1388614544 11619792 5247104
01:27:01 97938 8192000000 10280684848 10732597248 1311022448 13.795 95 6 13 0 1323878480 11609008 1247024
01:29:39 77571 8192000000 10285270352 10733182976 1371584720 14.341 100 34 68 2 1389436736 9403472 8448544
01:32:33 41806 8192000000 10277624800 10733305856 1380269152 14.419 100 19 38 0 1392408880 7525808 4613920
01:34:19 13496 8192000000 10285644032 10728509440 1374279392 14.365 100 43 84 0 1394030432 9990480 9760560
01:35:35 51257 8192000000 10246940048 10695888896 1333927984 14.003 98 21 42 0 1345741904 7150624 4663296
01:37:47 48012 8192000000 9733921872 10113114112 878016496 9.680 64 11 22 0 887906336 7384704 2505136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-10-2022 01:41:30 to 03-10-2022 01:41:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-10-2022 01:41:47 to 03-10-2022 01:41:49.

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          239816
MON$OLDEST_ACTIVE               240420
MON$OLDEST_SNAPSHOT             240420
MON$NEXT_TRANSACTION            240420
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                       2063456
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:129: 
:130: mscs-test-161.budzdorov.ru    Mon Oct  3 01:39:03 2022
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:132: 
:133: 
:134: mscs-test-161.budzdorov.ru    Mon Oct  3 01:39:03 2022
:135:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:136: 
:137: 
:138: mscs-test-161.budzdorov.ru    Mon Oct  3 01:39:03 2022
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:140: 
:141: 
:142: mscs-test-161.budzdorov.ru    Mon Oct  3 01:39:16 2022
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:144: 
--- end of diff output ---

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

03.10.22 01:41:50. 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-10-03 01:41:53 No severe PSQL-related problems occured
03.10.22 01:41:54. End of report.