Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.06.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 16278060 kB
MemAvailable 31187516 kB
Buffers 0 kB
Cached 15410180 kB
SwapCached 2508 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-06-25 01:38:20
DTS_END                         2022-06-25 01:38:20.2790
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/42482
TRN_ID                          233328
ATT_ID                          688
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: 9372.00 26258 1124640
customer order: creation 160.23 367 19227
customer order: refuse 13.77 325 1652
order to supplier: creation 43.30 1280 5196
order to supplier: removal 3.48 119457 417
invoice (draft): creation 43.14 3361 5177
invoice (draft): removal 55.81 795 6697
invoice accept: apply 39.47 16983 4736
invoice accept: cancel 50.88 758 6106
customer reserve: creation 3717.33 163 446079
customer reserve: removal 4950.88 66 594105
realization accept: apply 78.11 179 9373
realization accept: cancel 93.13 114 11176
payment from customer: creation 55.70 124 6684
payment from customer: removal 5.57 77 668
payment to supplier: creation 46.70 141 5604
payment to supplier: removal 3.16 88 379
service: total inventory turnovers 4.80 905 576
service: total monetary turnovers 5.72 23 686
service: aggregate perf. data 0.82 22429 98
service: refresh index statistics 0.03 357517 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-06-2022 01:39:31 to 25-06-2022 01:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3016
TEST_TIME 2 4665
TEST_TIME 3 4192
TEST_TIME 4 4559
TEST_TIME 5 4283
TEST_TIME 6 3982
TEST_TIME 7 3978
TEST_TIME 8 4502
TEST_TIME 9 4566
TEST_TIME 10 5410
TEST_TIME 11 5553
TEST_TIME 12 5824
TEST_TIME 13 6031
TEST_TIME 14 6190
TEST_TIME 15 6379
TEST_TIME 16 6559
TEST_TIME 17 6525
TEST_TIME 18 7048
TEST_TIME 19 7146
TEST_TIME 20 7391
TEST_TIME 21 8150
TEST_TIME 22 8221
TEST_TIME 23 8577
TEST_TIME 24 8859
TEST_TIME 25 9629
TEST_TIME 26 10256
TEST_TIME 27 10826
TEST_TIME 28 11339
TEST_TIME 29 11194
TEST_TIME 30 10966
TEST_TIME 31 10732
TEST_TIME 32 10831
TEST_TIME 33 10713
TEST_TIME 34 10515
TEST_TIME 35 10394
TEST_TIME 36 10212
TEST_TIME 37 10378
TEST_TIME 38 10272
TEST_TIME 39 10198
TEST_TIME 40 10075
TEST_TIME 41 10004
TEST_TIME 42 9967
TEST_TIME 43 9935
TEST_TIME 44 9771
TEST_TIME 45 9815
TEST_TIME 46 9805
TEST_TIME 47 9682
TEST_TIME 48 9715
TEST_TIME 49 9697
TEST_TIME 50 9655
TEST_TIME 51 9720
TEST_TIME 52 9721
TEST_TIME 53 9679
TEST_TIME 54 9748
TEST_TIME 55 9911
TEST_TIME 56 9871
TEST_TIME 57 9900
TEST_TIME 58 10010
TEST_TIME 59 9933
TEST_TIME 60 9869
TEST_TIME 61 10104
TEST_TIME 62 9949
TEST_TIME 63 9901
TEST_TIME 64 9787
TEST_TIME 65 9796
TEST_TIME 66 9809
TEST_TIME 67 9716
TEST_TIME 68 9696
TEST_TIME 69 9672
TEST_TIME 70 9636
TEST_TIME 71 9535
TEST_TIME 72 9505
TEST_TIME 73 9533
TEST_TIME 74 9487
TEST_TIME 75 9541
TEST_TIME 76 9534
TEST_TIME 77 9544
TEST_TIME 78 9533
TEST_TIME 79 9571
TEST_TIME 80 9569
TEST_TIME 81 9574
TEST_TIME 82 9619
TEST_TIME 83 9625
TEST_TIME 84 9721
TEST_TIME 85 9734
TEST_TIME 86 9737
TEST_TIME 87 9685
TEST_TIME 88 9606
TEST_TIME 89 9541
TEST_TIME 90 9533
TEST_TIME 91 9489
TEST_TIME 92 9462
TEST_TIME 93 9420
TEST_TIME 94 9447
TEST_TIME 95 9463
TEST_TIME 96 9476
TEST_TIME 97 9458
TEST_TIME 98 9473
TEST_TIME 99 9480
TEST_TIME 100 9472
TEST_TIME 101 9557
TEST_TIME 102 9564
TEST_TIME 103 9617
TEST_TIME 104 9605
TEST_TIME 105 9651
TEST_TIME 106 9698
TEST_TIME 107 9685
TEST_TIME 108 9609
TEST_TIME 109 9581
TEST_TIME 110 9557
TEST_TIME 111 9546
TEST_TIME 112 9508
TEST_TIME 113 9469
TEST_TIME 114 9467
TEST_TIME 115 9431
TEST_TIME 116 9424
TEST_TIME 117 9423
TEST_TIME 118 9412
TEST_TIME 119 9436
TEST_TIME 120 9375

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-06-2022 01:39:34 to 25-06-2022 01:39:38.

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 1537850 1537850 0 0.00 0 17749 6 0 0
doc_list_biud 1537849 1537849 0 0.00 0 67690 31 0 0
sp_add_invoice_to_stock 4896 4736 160 3.26 42 151985 16983 0 160
sp_cancel_adding_invoice 6106 6106 0 0.00 3 81857 758 0 0
sp_cancel_client_order 1652 1652 0 0.00 3 11022 325 0 0
sp_cancel_customer_reserve 594105 594105 0 0.00 1 23247 66 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 5262 77 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 6872 89 0 0
sp_cancel_supplier_invoice 6713 6697 16 0.23 2 67698 796 0 16
sp_cancel_supplier_order 417 417 0 0.00 7 1132843 119457 0 0
sp_cancel_write_off 11176 11176 0 0.00 1 10690 114 0 0
sp_client_order 19227 19227 0 0.00 3 42964 367 0 0
sp_customer_reserve 446079 446079 0 0.00 0 79395 163 0 0
sp_fill_shopping_cart 29584 29584 0 0.00 1 13000 40 0 0
sp_fill_shopping_cart_clo_res 446075 446075 0 0.00 0 17641 15 0 0
sp_get_clo_for_invoice 4731 4731 0 0.00 0 26357 346 0 0
sp_kill_cost_storno 18329 18329 0 0.00 0 14884 58 0 0
sp_kill_qstorno_ret_qs2qd 1206500 1206500 0 0.00 0 67685 35 0 0
sp_kill_qty_storno 634257 634257 0 0.00 0 67688 75 0 0
sp_lock_selected_doc 19508 19508 0 0.00 0 9117 6 0 0
sp_make_cost_storno 26392 26392 0 0.00 0 15790 56 0 0
sp_multiply_rows_for_pdistr 26392 26392 0 0.00 0 12402 30 0 0
sp_multiply_rows_for_qdistr 19227 19227 0 0.00 0 42887 173 0 0
sp_pay_from_customer 6684 6684 0 0.00 4 21405 124 0 0
sp_pay_to_supplier 5604 5604 0 0.00 5 13757 141 0 0
sp_payment_common 12288 12288 0 0.00 1 15256 39 0 0
sp_qd_handle_on_cancel_clo 1652 1652 0 0.00 0 11014 298 0 0
sp_qd_handle_on_invoice_upd_sts 10837 10837 0 0.00 0 17791 332 0 0
sp_qd_handle_on_reserve_upd_sts 9373 9373 0 0.00 0 18134 60 0 0
sp_reserve_write_off 9373 9373 0 0.00 6 18162 180 0 0
sp_supplier_invoice 5177 5177 0 0.00 6 67495 3362 0 0
sp_supplier_order 5196 5196 0 0.00 5 33804 1280 0 0
srv_aggregate_perf_data 98 98 0 0.00 2791 660042 22430 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2134 186854 48595 0 0
srv_find_qd_qs_mism 1079396 1079396 0 0.00 0 17746 5 0 0
srv_make_invnt_saldo 576 576 0 0.00 21 27793 905 0 0
srv_make_money_saldo 686 686 0 0.00 1 922 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 154803 490582 357517 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4736 4736 0 0.00 0 8367 154 0 0
t$perf-norm:sp_cancel_adding_invoice 368 368 0 0.00 0 3582 81 0 0
t$perf-norm:sp_cancel_client_order 1652 1652 0 0.00 0 6063 12 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 2014 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 1821 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 2003 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 271 9 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 19321 1692 0 0
t$perf-norm:sp_cancel_write_off 882 882 0 0.00 0 2110 9 0 0
t$perf-norm:sp_client_order 19227 19227 0 0.00 0 8435 18 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 2174 6 0 0
t$perf-norm:sp_pay_from_customer 6684 6684 0 0.00 0 8205 10 0 0
t$perf-norm:sp_pay_to_supplier 5604 5604 0 0.00 0 13517 15 0 0
t$perf-norm:sp_reserve_write_off 9373 9373 0 0.00 0 15933 12 0 0
t$perf-norm:sp_supplier_invoice 5177 5177 0 0.00 0 10575 18 0 0
t$perf-norm:sp_supplier_order 5196 5196 0 0.00 0 15224 22 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 595 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 213 5 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 1140 18 0 0
t$perf-norm:srv_make_money_saldo 686 686 0 0.00 0 4798 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 9782 9462 320 3.27 0 6655 18 0 320
v_all_customers 35372 35372 0 0.00 0 195 0 0 0
v_all_suppliers 29170 29170 0 0.00 0 21 1 0 0
v_all_wares 38454 38454 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 736 736 0 0.00 0 4052 11 0 0
v_cancel_client_order 3304 3304 0 0.00 0 6078 7 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 1621 6 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 312 3 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 5760 12 0 0
v_cancel_supplier_invoice 770 738 32 4.15 0 341 13 0 32
v_cancel_supplier_order 834 834 0 0.00 0 1113 5 0 0
v_cancel_write_off 1764 1764 0 0.00 0 2920 14 0 0
v_max_id_clo_ord 5193 5193 0 0.00 0 1700 2 0 0
v_max_id_clo_res 958 958 0 0.00 0 1317 10 0 0
v_max_id_ord_sup 5163 5163 0 0.00 0 5397 5 0 0
v_max_non_paid_invoice 1375 1375 0 0.00 0 1610 6 0 0
v_max_non_paid_realizn 3438 3438 0 0.00 0 2124 4 0 0
v_min_id_clo_ord 5193 5193 0 0.00 0 11169 10 0 0
v_min_id_clo_res 958 958 0 0.00 0 3122 12 0 0
v_min_id_ord_sup 5163 5163 0 0.00 0 3626 8 0 0
v_min_non_paid_invoice 5604 5604 0 0.00 0 10560 27 0 0
v_min_non_paid_realizn 6684 6684 0 0.00 0 15253 34 0 0
v_our_firm 9574 9574 0 0.00 0 11 1 0 0
v_reserve_write_off 18746 18746 0 0.00 0 5045 6 0 0
x_lock_depdocs_on_canc_invoice 12434 12434 0 0.00 0 21181 120 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 10737 439 0 0
x_make_qstorno_1000_1200 5196 5196 0 0.00 2 33792 1199 0 0
x_make_qstorno_1200_2000 5177 5177 0 0.00 3 67484 3219 0 0
x_make_qstorno_2100_3300 446079 446079 0 0.00 0 79389 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-06-2022 01:39:39 to 25-06-2022 01:39:43.

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:24 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:24 22716 8192000000 9905049776 10215555072 982541600 10.709 100 89 178 0 1012708336 6394496 23772240
23:10:58 18350 8192000000 9950318416 10273460224 1027629568 11.146 100 86 172 1 1058752704 8844384 22278752
23:12:22 8074 8192000000 9972429728 10305179648 1060298128 11.459 100 80 160 1 1091130816 9798448 21034240
23:13:37 11318 8192000000 10027040688 10375016448 1111049648 11.942 100 75 148 2 1139322384 9865536 18407200
23:14:51 8972 8192000000 10083789440 10443272192 1173048096 12.525 100 78 155 0 1202584320 12106864 17429360
23:16:04 6675 8192000000 10125183312 10490535936 1211078624 12.879 100 81 163 1 1242685936 13674064 17933248
23:17:12 3926 8192000000 10167660400 10540425216 1251100816 13.248 100 90 179 5 1284798496 14157312 19540368
23:18:17 2131 8192000000 10195140192 10574770176 1282658912 13.537 100 84 165 1 1313592672 12753920 18179840
23:19:21 20812 8192000000 10206293824 10598072320 1299319616 13.689 100 66 133 0 1325692096 10388176 15984304
23:20:42 5120 8192000000 10205391568 10613329920 1313212160 13.815 100 39 78 2 1331168880 9533200 8423520
23:21:48 5489 8192000000 10210077840 10619310080 1332027440 13.985 100 49 97 3 1348753872 6233648 10492784
23:22:55 16329 8192000000 10208010896 10631856128 1323910176 13.912 100 17 34 1 1333408816 5157520 4341120
23:24:12 8208 8192000000 10222577760 10642616320 1331245632 13.978 100 24 48 0 1343420992 6850240 5325120
23:25:20 5534 8192000000 10233479664 10655309824 1341719200 14.073 100 18 36 2 1351056272 5716992 3620080
23:26:29 25671 8192000000 10207851104 10629914624 1302805040 13.721 97 36 71 3 1316868688 5408432 8655216
23:28:21 4441 8192000000 10237288560 10655645696 1340953744 14.066 100 33 66 0 1355564992 7353840 7257408
23:29:27 8779 8192000000 10166576592 10572791808 1242082272 13.165 95 12 24 2 1249809792 5368896 2358624
23:30:40 9201 8192000000 10143320816 10538246144 1198303072 12.761 95 13 26 1 1206953232 5810064 2840096
23:31:51 31727 8192000000 10118017920 10518478848 1186330048 12.649 95 33 63 0 1199596784 5702144 7564592
23:33:23 8600 8192000000 10160093872 10559889408 1250890336 13.246 99 22 44 0 1261352416 5371584 5090496
23:34:33 4078 8192000000 10174483440 10582556672 1284268288 13.552 100 26 53 0 1296434928 6137152 6029488
23:35:38 10308 8192000000 10186355328 10596564992 1298453072 13.681 100 23 44 1 1308341760 4846448 5042240
23:36:50 24969 8192000000 10190549392 10608058368 1303307504 13.725 100 18 36 0 1312482048 4943184 4231360
23:38:16 17162 8192000000 10221798608 10633068544 1324713296 13.919 100 25 50 1 1335121840 4838656 5569888
23:39:38 19026 8192000000 10261262832 10671435776 1352211744 14.167 100 54 108 0 1374525584 10292016 12021824
23:41:02 25493 8192000000 10274313104 10691526656 1365621248 14.288 100 35 70 2 1384184384 10461040 8102096
23:42:43 77785 8192000000 10293416000 10719940608 1386741104 14.477 100 43 86 2 1411070512 13448064 10881344
23:45:06 14293 8192000000 10321366640 10743140352 1403415008 14.625 100 44 88 3 1430211872 16187008 10609856
23:46:24 9666 8192000000 10332900496 10759213056 1413030176 14.711 100 52 102 2 1450392672 24971232 12391264
23:47:43 4837 8192000000 10352134544 10778001408 1422334608 14.793 100 50 100 1 1467204352 32089600 12780144
23:48:55 8120 8192000000 10363218272 10782584832 1423129760 14.800 100 54 108 0 1470546720 33084736 14332224
23:50:07 8046 8192000000 10386332512 10805592064 1436146528 14.916 100 68 135 0 1492409488 39541840 16721120
23:51:28 4246 8192000000 10400334096 10821832704 1445450384 14.998 100 70 140 0 1509777440 45388608 18938448
23:52:38 3296 8192000000 10372115616 10784112640 1410510432 14.688 99 73 144 2 1483219328 53615520 19093376
23:53:50 2510 8192000000 10411890912 10823426048 1424031184 14.808 99 89 178 1 1504607728 57990144 22586400
23:55:02 12403 8192000000 10407297952 10822656000 1432761392 14.886 100 73 144 0 1509543424 57796928 18985104
23:56:21 2133 8192000000 10400975472 10810036224 1421286640 14.784 100 86 173 2 1503279680 60117040 21876000
23:57:30 5452 8192000000 10397514976 10806484992 1409153072 14.676 100 79 154 1 1496321984 66353760 20815152
23:58:40 9499 8192000000 10389435840 10805460992 1407140864 14.659 100 58 114 1 1495974032 72713520 16119648
00:00:04 12240 8192000000 10365711168 10794311680 1398383696 14.581 100 41 82 1 1472693520 62484160 11825664
00:01:26 13304 8192000000 10347446624 10778816512 1367546592 14.305 100 34 68 0 1450759968 73026608 10186768
00:02:45 17727 8192000000 10336053904 10776502272 1366601664 14.297 100 31 63 0 1444747408 69459600 8686144
00:04:05 37361 8192000000 10312968096 10756034560 1347372592 14.124 99 26 52 2 1411210832 56821168 7017072
00:05:43 48596 8192000000 10276926752 10719895552 1336835760 14.029 99 21 43 0 1373148992 30744256 5568976
00:07:33 22320 8192000000 10272907120 10722054144 1363987504 14.273 100 16 32 0 1385268304 16988144 4292656
00:08:57 49925 8192000000 10266124672 10710630400 1357091888 14.211 99 10 20 1 1368979024 10312320 1574816
00:10:47 31787 8192000000 10290958512 10730041344 1373655360 14.360 100 14 28 0 1383725680 7281696 2788624
00:12:19 146253 8192000000 10298994576 10736218112 1380025056 14.417 100 18 36 0 1400481200 16226032 4230112
00:15:59 100479 8192000000 10298801088 10755620864 1395352496 14.554 100 17 33 2 1410756832 11186368 4217968
00:18:40 27726 8192000000 10298943072 10758078464 1387201056 14.481 99 11 22 0 1402985648 12928432 2856160
00:20:08 30415 8192000000 10312558320 10763616256 1398694656 14.583 100 46 90 2 1424203984 14977264 10532064
00:21:44 72266 8192000000 10304171488 10770907136 1391649120 14.521 99 14 28 0 1414986576 20202944 3134512
00:23:57 48795 8192000000 10283967776 10765856768 1389650928 14.503 100 8 16 0 1404907552 13544128 1712496
00:25:55 83479 8192000000 10282058832 10747854848 1371004528 14.336 98 10 20 0 1384369040 11020848 2343664
00:28:20 45607 8192000000 10302573600 10746589184 1382957472 14.443 100 29 56 0 1402437584 13312672 6167440
00:30:06 46089 8192000000 10301737584 10760531968 1377935344 14.398 99 18 36 0 1392891376 10715584 4240448
00:31:54 38787 8192000000 10320271168 10765586432 1398477024 14.581 100 23 46 3 1420133904 16511760 5145120
00:33:34 37848 8192000000 10319833248 10773757952 1401252320 14.606 100 18 36 1 1418821440 13856192 3712928
00:35:19 95215 8192000000 10326656704 10795855872 1411369856 14.696 100 14 28 1 1430587728 16140592 3077280
00:37:58 35795 8192000000 10340176784 10792095744 1413900784 14.719 99 23 46 0 1433882016 14793296 5187936
00:39:47 103485 8192000000 10305879936 10760081408 1372128560 14.346 97 28 56 3 1385652176 7115472 6408144
00:42:32 32476 8192000000 10308175424 10754150400 1393787424 14.540 100 37 75 0 1413811200 11520480 8503296
00:44:07 77046 8192000000 10264202496 10726666240 1322368864 13.898 95 10 20 0 1333148576 8813200 1966512
00:46:25 52967 8192000000 10276199024 10738368512 1379423024 14.411 100 8 14 1 1390989952 9699680 1867248
00:48:19 54927 8192000000 10249117312 10702110720 1274111184 13.459 92 26 53 0 1289739280 10461008 5167088
00:50:14 78790 8192000000 10276516128 10739658752 1353139184 14.176 99 10 20 0 1380621744 25040544 2442016
00:52:33 35936 8192000000 10248493888 10710130688 1329726800 13.965 98 15 30 0 1348137424 14749168 3661456
00:54:10 37406 8192000000 10248292912 10697101312 1292259104 13.625 95 18 34 1 1305829824 9112448 4458272
00:55:58 43296 8192000000 10268762752 10714886144 1337858128 14.038 98 26 52 2 1351969216 8293328 5817760
00:57:46 96444 8192000000 10271310592 10725203968 1316545520 13.845 96 23 46 0 1336395072 14646752 5202800
01:00:25 45071 8192000000 10278449184 10743787520 1362030960 14.256 99 22 44 0 1380525280 13555152 4939168
01:02:24 97280 8192000000 10270976144 10727325696 1317320688 13.852 96 9 18 0 1330018992 10829504 1868800
01:05:17 101820 8192000000 10268499568 10713579520 1321170080 13.887 96 33 66 0 1336598176 8008768 7419328
01:07:59 39309 8192000000 10263096000 10722594816 1360568560 14.242 99 10 20 1 1371625824 9128464 1928800
01:09:39 55243 8192000000 10271616032 10734424064 1384180976 14.454 100 11 22 0 1394813424 8192432 2440016
01:11:41 84663 8192000000 10289103856 10756292608 1350736016 14.154 97 14 28 0 1365471456 11461776 3273664
01:14:06 70436 8192000000 10292852176 10762043392 1385363472 14.464 99 18 37 2 1399117408 9404784 4349152
01:16:23 55644 8192000000 10299224480 10771374080 1388728432 14.495 99 23 46 1 1406287568 12435920 5123216
01:18:25 54240 8192000000 10308188288 10764320768 1371362672 14.339 98 13 26 0 1388634768 14418112 2853984
01:20:19 114765 8192000000 10307338864 10771230720 1385797952 14.468 99 12 24 0 1403449376 15030192 2621232
01:23:17 38970 8192000000 10298029664 10763014144 1378357104 14.402 99 8 16 1 1401289040 20851888 2080048
01:24:56 186853 8192000000 10277123040 10743058432 1345689168 14.109 97 24 48 0 1365423328 14189360 5544800
01:29:22 41551 8192000000 10287205712 10750574592 1387915952 14.487 100 13 26 1 1397740976 7011248 2813776
01:31:15 45268 8192000000 10280252144 10734428160 1352414288 14.169 98 11 22 1 1362347680 7057360 2876032
01:33:01 84569 8192000000 10272079104 10733821952 1360995568 14.246 99 20 41 0 1374691792 9162752 4533472
01:35:30 73953 8192000000 10264070848 10717835264 1303425200 13.726 95 16 32 0 1320524704 13871056 3228448
01:37:45 41628 8192000000 9240079248 9513934848 398741536 4.641 29 3 6 0 404044080 4858032 444512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 25-06-2022 01:39:45 to 25-06-2022 01:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 160 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-06-2022 01:40:00 to 25-06-2022 01:40:02.

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          233674
MON$OLDEST_ACTIVE               233675
MON$OLDEST_SNAPSHOT             233675
MON$NEXT_TRANSACTION            233675
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                       2021312
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:405: 
:406: mscs-test-161.budzdorov.ru    Sat Jun 25 01:38:29 2022
:407:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:408: 
--- end of diff output ---

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

25.06.22 01:40:03. 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-06-25 01:40:06 No severe PSQL-related problems occured
25.06.22 01:40:07. End of report.