Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.07.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 16072356 kB
MemAvailable 31058120 kB
Buffers 0 kB
Cached 15615888 kB
SwapCached 2480 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-07-17 01:38:21
DTS_END                         2022-07-17 01:38:21.2950
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/36650
TRN_ID                          238204
ATT_ID                          712
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: 9219.33 21641 1106319
customer order: creation 169.23 393 20307
customer order: refuse 14.91 354 1789
order to supplier: creation 44.51 1354 5341
order to supplier: removal 3.47 97010 416
invoice (draft): creation 45.50 3517 5460
invoice (draft): removal 51.73 779 6207
invoice accept: apply 41.81 17075 5017
invoice accept: cancel 47.54 885 5705
customer reserve: creation 3847.26 169 461671
customer reserve: removal 4656.93 56 558831
realization accept: apply 82.32 198 9878
realization accept: cancel 87.67 103 10520
payment from customer: creation 58.77 131 7052
payment from customer: removal 5.55 89 666
payment to supplier: creation 46.90 144 5628
payment to supplier: removal 3.59 67 431
service: total inventory turnovers 4.41 1323 529
service: total monetary turnovers 6.43 30 772
service: aggregate perf. data 0.79 22493 95
service: refresh index statistics 0.03 286649 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-07-2022 01:39:25 to 17-07-2022 01:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2709
TEST_TIME 2 3549
TEST_TIME 3 3619
TEST_TIME 4 5399
TEST_TIME 5 4970
TEST_TIME 6 5437
TEST_TIME 7 5617
TEST_TIME 8 6432
TEST_TIME 9 6307
TEST_TIME 10 6411
TEST_TIME 11 6347
TEST_TIME 12 6263
TEST_TIME 13 6274
TEST_TIME 14 6538
TEST_TIME 15 6781
TEST_TIME 16 7029
TEST_TIME 17 7231
TEST_TIME 18 7819
TEST_TIME 19 8098
TEST_TIME 20 8441
TEST_TIME 21 8691
TEST_TIME 22 8729
TEST_TIME 23 9005
TEST_TIME 24 8961
TEST_TIME 25 9019
TEST_TIME 26 9125
TEST_TIME 27 9122
TEST_TIME 28 9722
TEST_TIME 29 10528
TEST_TIME 30 10437
TEST_TIME 31 10504
TEST_TIME 32 10588
TEST_TIME 33 10431
TEST_TIME 34 10278
TEST_TIME 35 10114
TEST_TIME 36 9991
TEST_TIME 37 9969
TEST_TIME 38 9833
TEST_TIME 39 9756
TEST_TIME 40 9677
TEST_TIME 41 9641
TEST_TIME 42 9612
TEST_TIME 43 9487
TEST_TIME 44 9665
TEST_TIME 45 9484
TEST_TIME 46 9429
TEST_TIME 47 9636
TEST_TIME 48 9502
TEST_TIME 49 9414
TEST_TIME 50 9381
TEST_TIME 51 9389
TEST_TIME 52 9326
TEST_TIME 53 9242
TEST_TIME 54 9154
TEST_TIME 55 9158
TEST_TIME 56 9202
TEST_TIME 57 9116
TEST_TIME 58 9076
TEST_TIME 59 9053
TEST_TIME 60 9047
TEST_TIME 61 8945
TEST_TIME 62 8911
TEST_TIME 63 8922
TEST_TIME 64 9024
TEST_TIME 65 9097
TEST_TIME 66 9054
TEST_TIME 67 9155
TEST_TIME 68 9126
TEST_TIME 69 9175
TEST_TIME 70 9150
TEST_TIME 71 9243
TEST_TIME 72 9325
TEST_TIME 73 9321
TEST_TIME 74 9303
TEST_TIME 75 9382
TEST_TIME 76 9414
TEST_TIME 77 9347
TEST_TIME 78 9347
TEST_TIME 79 9294
TEST_TIME 80 9274
TEST_TIME 81 9222
TEST_TIME 82 9160
TEST_TIME 83 9129
TEST_TIME 84 9100
TEST_TIME 85 9050
TEST_TIME 86 9067
TEST_TIME 87 9107
TEST_TIME 88 9144
TEST_TIME 89 9138
TEST_TIME 90 9161
TEST_TIME 91 9211
TEST_TIME 92 9224
TEST_TIME 93 9245
TEST_TIME 94 9248
TEST_TIME 95 9230
TEST_TIME 96 9342
TEST_TIME 97 9324
TEST_TIME 98 9314
TEST_TIME 99 9385
TEST_TIME 100 9423
TEST_TIME 101 9409
TEST_TIME 102 9373
TEST_TIME 103 9347
TEST_TIME 104 9325
TEST_TIME 105 9319
TEST_TIME 106 9303
TEST_TIME 107 9309
TEST_TIME 108 9282
TEST_TIME 109 9259
TEST_TIME 110 9263
TEST_TIME 111 9252
TEST_TIME 112 9225
TEST_TIME 113 9234
TEST_TIME 114 9228
TEST_TIME 115 9235
TEST_TIME 116 9262
TEST_TIME 117 9245
TEST_TIME 118 9297
TEST_TIME 119 9259
TEST_TIME 120 9220

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-07-2022 01:39:29 to 17-07-2022 01:39:32.

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 1532325 1532325 0 0.00 0 28327 6 0 0
doc_list_biud 1532323 1532323 0 0.00 0 42651 26 0 0
sp_add_invoice_to_stock 5114 5017 97 1.89 39 175429 17075 0 97
sp_cancel_adding_invoice 5705 5705 0 0.00 4 103851 886 0 0
sp_cancel_client_order 1789 1789 0 0.00 5 11271 355 0 0
sp_cancel_customer_reserve 558831 558831 0 0.00 0 42654 57 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 2 6839 89 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 2 7144 67 0 0
sp_cancel_supplier_invoice 6218 6207 11 0.17 1 16231 780 0 11
sp_cancel_supplier_order 416 416 0 0.00 6 989452 97010 0 0
sp_cancel_write_off 10520 10520 0 0.00 1 9896 104 0 0
sp_client_order 20307 20307 0 0.00 3 42057 393 0 0
sp_customer_reserve 461671 461671 0 0.00 0 46562 169 0 0
sp_fill_shopping_cart 31102 31102 0 0.00 1 17069 41 0 0
sp_fill_shopping_cart_clo_res 461669 461669 0 0.00 0 13990 14 0 0
sp_get_clo_for_invoice 5014 5014 0 0.00 0 23100 323 0 0
sp_kill_cost_storno 17322 17322 0 0.00 0 8730 57 0 0
sp_kill_qstorno_ret_qs2qd 1134805 1134805 0 0.00 0 42649 31 0 0
sp_kill_qty_storno 598360 598360 0 0.00 0 42651 67 0 0
sp_lock_selected_doc 20740 20740 0 0.00 0 6808 6 0 0
sp_make_cost_storno 27572 27572 0 0.00 0 28306 68 0 0
sp_multiply_rows_for_pdistr 27572 27572 0 0.00 0 10157 29 0 0
sp_multiply_rows_for_qdistr 20307 20307 0 0.00 0 42046 167 0 0
sp_pay_from_customer 7052 7052 0 0.00 4 17139 131 0 0
sp_pay_to_supplier 5628 5628 0 0.00 5 16275 144 0 0
sp_payment_common 12680 12680 0 0.00 1 10183 36 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 11261 326 0 0
sp_qd_handle_on_invoice_upd_sts 10719 10719 0 0.00 0 16995 335 0 0
sp_qd_handle_on_reserve_upd_sts 9878 9878 0 0.00 0 10370 55 0 0
sp_reserve_write_off 9878 9878 0 0.00 6 18791 199 0 0
sp_supplier_invoice 5460 5460 0 0.00 5 119025 3517 0 0
sp_supplier_order 5341 5341 0 0.00 4 42024 1355 0 0
srv_aggregate_perf_data 95 95 0 0.00 2708 624075 22493 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2507 118902 45585 0 0
srv_find_qd_qs_mism 1058457 1058457 0 0.00 0 17696 5 0 0
srv_make_invnt_saldo 529 529 0 0.00 38 137484 1324 0 0
srv_make_money_saldo 772 772 0 0.00 1 6427 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 231656 342894 286650 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5017 5017 0 0.00 0 12329 166 0 0
t$perf-norm:sp_cancel_adding_invoice 466 466 0 0.00 0 4028 122 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 3508 13 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 2470 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 23668 53 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 6855 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 926 17 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 39581 1834 0 0
t$perf-norm:sp_cancel_write_off 1037 1037 0 0.00 0 2068 7 0 0
t$perf-norm:sp_client_order 20307 20307 0 0.00 0 20842 19 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 2782 10 0 0
t$perf-norm:sp_pay_from_customer 7052 7052 0 0.00 0 10445 14 0 0
t$perf-norm:sp_pay_to_supplier 5628 5628 0 0.00 0 11497 15 0 0
t$perf-norm:sp_reserve_write_off 9878 9878 0 0.00 0 10492 14 0 0
t$perf-norm:sp_supplier_invoice 5460 5460 0 0.00 0 11442 16 0 0
t$perf-norm:sp_supplier_order 5341 5341 0 0.00 0 9659 15 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 1971 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 33 1 0 0
t$perf-norm:srv_make_invnt_saldo 529 529 0 0.00 0 1161 14 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 1438 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 24 9 0 0
v_add_invoice_to_stock 10220 10026 194 1.89 0 6784 18 0 194
v_all_customers 37206 37206 0 0.00 0 108 0 0 0
v_all_suppliers 29562 29562 0 0.00 0 120 1 0 0
v_all_wares 40610 40610 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 932 932 0 0.00 0 4193 12 0 0
v_cancel_client_order 3578 3578 0 0.00 0 3404 7 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 3104 7 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 4141 8 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 591 4 0 0
v_cancel_supplier_invoice 806 784 22 2.72 0 1342 15 0 22
v_cancel_supplier_order 832 832 0 0.00 0 2040 8 0 0
v_cancel_write_off 2074 2074 0 0.00 0 3466 20 0 0
v_max_id_clo_ord 5338 5338 0 0.00 0 3922 3 0 0
v_max_id_clo_res 974 974 0 0.00 0 4131 14 0 0
v_max_id_ord_sup 5458 5458 0 0.00 0 5381 6 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 5421 6 0 0
v_max_non_paid_realizn 3703 3703 0 0.00 0 5288 7 0 0
v_min_id_clo_ord 5338 5338 0 0.00 0 8679 10 0 0
v_min_id_clo_res 974 974 0 0.00 0 5180 12 0 0
v_min_id_ord_sup 5458 5458 0 0.00 0 9515 8 0 0
v_min_non_paid_invoice 5628 5628 0 0.00 0 10179 28 0 0
v_min_non_paid_realizn 7052 7052 0 0.00 0 8199 24 0 0
v_our_firm 10102 10102 0 0.00 0 27 1 0 0
v_reserve_write_off 19756 19756 0 0.00 0 6790 8 0 0
x_lock_depdocs_on_canc_invoice 11520 11520 0 0.00 0 16363 134 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 8039 360 0 0
x_make_qstorno_1000_1200 5341 5341 0 0.00 1 42014 1279 0 0
x_make_qstorno_1200_2000 5460 5460 0 0.00 2 118861 3360 0 0
x_make_qstorno_2100_3300 461671 461671 0 0.00 0 46560 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-07-2022 01:39:33 to 17-07-2022 01:39:37.

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:25 2 8192000000 8799267488 8810758144 5664016 0.069 2 1 2 0 5763648 56848 42784
23:09:26 23166 8192000000 9895549904 10200506368 974912272 10.635 100 87 174 0 1003489216 5672960 22903984
23:10:53 21367 8192000000 9939158320 10258825216 1022700464 11.098 100 91 182 0 1053671360 7397648 23573248
23:12:25 15497 8192000000 9991116672 10322935808 1071028752 11.562 100 87 173 0 1101145264 8882480 21234032
23:13:47 6260 8192000000 10033899584 10376691712 1118836304 12.016 100 84 168 0 1148907696 10493216 19578176
23:14:57 6714 8192000000 10072853760 10426777600 1160958720 12.412 100 84 167 0 1192656832 12759712 18938400
23:16:06 4978 8192000000 10119158976 10482192384 1206043184 12.832 100 79 155 0 1235585696 12327440 17215072
23:17:13 3387 8192000000 10157971504 10533756928 1248826640 13.227 100 77 153 0 1278977968 13696272 16455056
23:18:19 1335 8192000000 10183979888 10564911104 1270123104 13.423 100 85 169 1 1303772320 14194304 19454912
23:19:21 1944 8192000000 10207305360 10591084544 1293064624 13.632 100 86 170 2 1325063280 13040800 18957856
23:20:23 20368 8192000000 10213008816 10605989888 1308568128 13.773 100 67 133 0 1332475952 9236768 14671056
23:21:46 6643 8192000000 10205921488 10612707328 1314434304 13.826 100 45 90 0 1333020000 8635616 9950080
23:22:56 6191 8192000000 10212201232 10629398528 1327287328 13.943 100 27 54 0 1339679472 5640496 6751648
23:24:05 10585 8192000000 10216628720 10637320192 1333368544 13.998 100 14 28 0 1342137472 5603984 3164944
23:25:16 69106 8192000000 10234364816 10659438592 1342190976 14.077 100 24 46 0 1352881520 5542176 5148368
23:27:27 6195 8192000000 10237621728 10654642176 1340287952 14.060 100 31 62 1 1353041696 6757056 5996688
23:28:38 9277 8192000000 10190245712 10606948352 1279381328 13.507 96 15 28 1 1288123664 5315760 3426576
23:29:48 44923 8192000000 10208520368 10606555136 1292064592 13.623 99 39 77 0 1306321456 5410112 8846752
23:31:34 7777 8192000000 10145412768 10537639936 1214371168 12.910 96 27 55 1 1225830800 5501584 5958048
23:32:43 10261 8192000000 10139291072 10542374912 1206850352 12.840 95 21 42 0 1216950336 5100240 4999744
23:33:54 41338 8192000000 10179868944 10591424512 1290749520 13.611 100 44 85 2 1305781552 5195648 9836384
23:35:39 4196 8192000000 10187771440 10599477248 1296577072 13.664 100 25 51 1 1308145200 5983280 5584848
23:36:46 9205 8192000000 10200082912 10616029184 1309634896 13.783 100 24 48 0 1321126352 5872352 5619104
23:37:56 14392 8192000000 10213290992 10630852608 1322039024 13.895 100 22 45 0 1333004624 5737616 5227984
23:39:15 18258 8192000000 10248911248 10660450304 1343485568 14.089 100 40 77 2 1360539552 8684912 8369072
23:40:51 99721 8192000000 10267432672 10701361152 1365877168 14.290 100 39 78 0 1387297152 12349424 9070560
23:43:43 6250 8192000000 10296386576 10718380032 1384085760 14.453 100 52 104 0 1412284576 16549088 11649728
23:44:56 9261 8192000000 10311432208 10739437568 1397543072 14.573 100 47 92 0 1427830624 19050608 11236944
23:46:11 5865 8192000000 10330596576 10754035712 1397087584 14.569 99 54 104 1 1430204416 21579952 11536880
23:47:26 6875 8192000000 10340725792 10765070336 1420735600 14.779 100 59 113 0 1453744912 19894448 13114864
23:49:17 11228 8192000000 10348962288 10776354816 1422047968 14.791 100 68 133 0 1458583744 20765392 15770384
23:50:33 2647 8192000000 10361198656 10786775040 1432234992 14.881 100 59 117 0 1470839264 23649920 14954352
23:51:39 9259 8192000000 10359226832 10778398720 1423518016 14.804 100 70 137 1 1469121584 28244672 17358896
23:52:56 4181 8192000000 10358566768 10776887296 1415801680 14.735 100 75 148 1 1465260112 31068704 18389728
23:54:06 2505 8192000000 10366763584 10792304640 1422140768 14.792 100 51 102 2 1475349472 39779088 13429616
23:55:16 3931 8192000000 10341252160 10759573504 1383185328 14.445 99 64 126 0 1439759104 40165328 16408448
23:56:24 2617 8192000000 10330321120 10741440512 1375736240 14.378 99 74 148 1 1433804080 38768880 19298960
23:57:30 4106 8192000000 10313215264 10738851840 1373256816 14.356 99 47 94 0 1414062432 28774592 12031024
23:58:39 12749 8192000000 10288745360 10719961088 1346675344 14.118 99 31 62 0 1388217216 33233808 8308064
00:00:12 6415 8192000000 10299864000 10718945280 1350693904 14.154 99 61 122 0 1402199248 35981296 15524048
00:01:26 9167 8192000000 10306973776 10740162560 1370000848 14.327 100 36 72 0 1416936224 37129936 9805440
00:02:43 5508 8192000000 10311212256 10738241536 1380436576 14.420 100 37 71 0 1420359104 30449840 9472688
00:04:03 21034 8192000000 10306213584 10743586816 1372164160 14.346 100 22 44 1 1411942576 33943712 5834704
00:05:26 25965 8192000000 10296525968 10728243200 1333912016 14.002 99 21 42 1 1383873904 43226368 6735520
00:06:54 59766 8192000000 10236178000 10667958272 1261498528 13.344 94 9 19 1 1293495360 29878272 2118560
00:08:55 45255 8192000000 10264156544 10693316608 1346705120 14.118 99 19 39 0 1364794288 12874128 5215040
00:10:41 79483 8192000000 10260483040 10703966208 1323400448 13.907 97 15 29 1 1338048704 11646192 3002064
00:13:05 30282 8192000000 10262401888 10710945792 1355133472 14.194 99 8 16 0 1367830096 11058080 1638544
00:14:43 98634 8192000000 10282485248 10725863424 1370302928 14.330 100 13 24 0 1383267760 10346640 2618192
00:17:24 29603 8192000000 10301389504 10736340992 1383679728 14.449 100 41 80 0 1403291632 10532064 9079840
00:19:00 77299 8192000000 10288145344 10746085376 1369010560 14.318 99 22 44 0 1389876160 15607872 5257728
00:21:20 118764 8192000000 10291142128 10741342208 1383029936 14.444 100 25 50 1 1404158352 14499328 6629088
00:24:23 76348 8192000000 10314196304 10762240000 1385695920 14.467 100 30 60 1 1418785152 25712400 7376832
00:26:42 23033 8192000000 10293900992 10769350656 1382258000 14.437 100 10 18 0 1404756384 20618592 1879792
00:28:13 35884 8192000000 10298446880 10745810944 1377435024 14.394 99 51 99 0 1398161136 9372720 11353392
00:29:50 71229 8192000000 10294558816 10760548352 1397089216 14.569 100 25 48 0 1412042752 9539968 5413568
00:32:04 75293 8192000000 10290501536 10757591040 1387072448 14.480 99 12 22 0 1404639248 14497840 3068960
00:34:20 49593 8192000000 10285556128 10767425536 1393367424 14.536 100 8 16 0 1408993344 13864496 1761424
00:36:10 63248 8192000000 10292269520 10767953920 1400316336 14.598 100 22 44 0 1413403616 8270320 4816960
00:38:41 38901 8192000000 10283719264 10748096512 1373739104 14.361 98 10 20 0 1389056032 13461952 1854976
00:40:38 76550 8192000000 10312186512 10780168192 1384423072 14.456 98 16 31 0 1400547056 12389456 3734528
00:43:03 62347 8192000000 10287990576 10745397248 1365299696 14.285 97 19 38 0 1381221584 11404480 4517408
00:45:09 52815 8192000000 10263257968 10727088128 1341970384 14.075 97 12 24 0 1356929936 12470016 2489536
00:47:02 29493 8192000000 10283112048 10736312320 1370498752 14.332 100 29 58 0 1389772768 12743392 6530624
00:48:32 58990 8192000000 10278021264 10740506624 1369568784 14.323 100 18 37 0 1392503632 18524896 4409952
00:50:37 70176 8192000000 10247712352 10696081408 1302447168 13.717 96 12 24 0 1320172416 14788672 2936576
00:52:53 58237 8192000000 10235015024 10674827264 1269664320 13.419 94 14 28 0 1281794384 9137680 2992384
00:54:59 83170 8192000000 10246155168 10684391424 1334157616 14.005 100 35 70 1 1354031056 11838064 8035376
00:57:27 47047 8192000000 10245789280 10709639168 1331970448 13.985 99 11 22 0 1355810224 21562768 2277008
00:59:18 60292 8192000000 10250758976 10700636160 1352248464 14.168 100 19 38 0 1367730368 11143824 4338080
01:01:26 57534 8192000000 10268031808 10717237248 1362927712 14.264 100 23 44 0 1381107472 13378640 4801120
01:03:33 102091 8192000000 10258822640 10717650944 1332727424 13.992 97 13 26 0 1350797744 15055184 3015136
01:06:26 67423 8192000000 10274109280 10711912448 1300309872 13.698 95 23 46 0 1318196144 12508976 5377296
01:08:34 68008 8192000000 10256629136 10714984448 1352774880 14.172 99 16 32 0 1371176032 14762064 3639088
01:10:47 35602 8192000000 10288819568 10740723712 1381742896 14.432 100 34 68 0 1398357888 9627952 6987040
01:12:38 48033 8192000000 10304998352 10752020480 1388309456 14.491 100 31 61 0 1408284736 13405584 6569696
01:14:28 104174 8192000000 10301876272 10751094784 1364926000 14.282 98 11 22 1 1383975328 16805376 2243952
01:17:21 62859 8192000000 10298806928 10767077376 1373585440 14.359 99 14 29 0 1394952448 18606544 2760464
01:19:26 112718 8192000000 10290822992 10749288448 1388452384 14.492 100 15 30 1 1407332352 15438848 3441120
01:22:44 13814 8192000000 10293715632 10731958272 1379410016 14.411 100 54 107 1 1403271504 11799504 12061984
01:24:01 40571 8192000000 10298117968 10750119936 1391429008 14.519 100 15 30 0 1405264352 10130000 3705344
01:25:42 35657 8192000000 10288996336 10753544192 1392275280 14.526 100 6 12 0 1403883840 10313328 1295232
01:27:18 67230 8192000000 10279754432 10733445120 1340442848 14.061 96 15 31 1 1352503936 8284544 3776544
01:29:28 59838 8192000000 10287872752 10742341632 1379547840 14.413 99 11 22 0 1390990336 8719680 2722816
01:31:33 81121 8192000000 10292601360 10747539456 1377167232 14.391 99 7 14 1 1386869312 8369440 1332640
01:34:10 50385 8192000000 10274955312 10730078208 1352711520 14.172 98 10 21 1 1364451520 9268432 2471568
01:36:01 50435 8192000000 10261421568 10709000192 1324384928 13.916 96 20 39 1 1338103680 9392032 4326720
01:38:09 20417 8192000000 9174448848 9436491776 326931616 3.837 25 5 10 0 339573696 11504560 1137520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-07-2022 01:39:39 to 17-07-2022 01:39:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-07-2022 01:39:55 to 17-07-2022 01:39:57.

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          237857
MON$OLDEST_ACTIVE               238500
MON$OLDEST_SNAPSHOT             238500
MON$NEXT_TRANSACTION            238500
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                       2029472
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.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Sun Jul 17 01:38:52 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
--- end of diff output ---

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

17.07.22 01:39:58. 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-07-17 01:40:00 No severe PSQL-related problems occured
17.07.22 01:40:02. End of report.