Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.01.2023 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 15410520 kB
MemAvailable 30369996 kB
Buffers 0 kB
Cached 16299788 kB
SwapCached 4556 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 2023-01-07 01:38:34
DTS_END                         2023-01-07 01:38:34.4850
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/40960
TRN_ID                          241180
ATT_ID                          702
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.67 18886 1106361
customer order: creation 174.00 339 20880
customer order: refuse 14.58 356 1750
order to supplier: creation 47.25 1279 5670
order to supplier: removal 3.08 91587 370
invoice (draft): creation 46.38 3531 5565
invoice (draft): removal 52.54 645 6305
invoice accept: apply 42.19 17635 5063
invoice accept: cancel 47.69 797 5723
customer reserve: creation 3885.50 176 466260
customer reserve: removal 4605.67 48 552680
realization accept: apply 83.30 177 9996
realization accept: cancel 87.27 98 10472
payment from customer: creation 60.01 113 7201
payment from customer: removal 5.53 64 663
payment to supplier: creation 49.55 131 5946
payment to supplier: removal 3.77 107 452
service: total inventory turnovers 4.43 1323 531
service: total monetary turnovers 6.12 23 734
service: aggregate perf. data 0.80 21763 96
service: refresh index statistics 0.03 237527 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-01-2023 01:39:55 to 07-01-2023 01:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2560
TEST_TIME 2 2905
TEST_TIME 3 4597
TEST_TIME 4 5551
TEST_TIME 5 5500
TEST_TIME 6 6063
TEST_TIME 7 6306
TEST_TIME 8 6068
TEST_TIME 9 6187
TEST_TIME 10 6558
TEST_TIME 11 7125
TEST_TIME 12 7089
TEST_TIME 13 6983
TEST_TIME 14 6867
TEST_TIME 15 7157
TEST_TIME 16 7207
TEST_TIME 17 7823
TEST_TIME 18 7679
TEST_TIME 19 7556
TEST_TIME 20 7950
TEST_TIME 21 8111
TEST_TIME 22 8568
TEST_TIME 23 9047
TEST_TIME 24 9214
TEST_TIME 25 9535
TEST_TIME 26 9580
TEST_TIME 27 9717
TEST_TIME 28 9617
TEST_TIME 29 9819
TEST_TIME 30 9830
TEST_TIME 31 9823
TEST_TIME 32 9744
TEST_TIME 33 9842
TEST_TIME 34 9694
TEST_TIME 35 9587
TEST_TIME 36 9586
TEST_TIME 37 9513
TEST_TIME 38 9458
TEST_TIME 39 9539
TEST_TIME 40 9499
TEST_TIME 41 9336
TEST_TIME 42 9307
TEST_TIME 43 9403
TEST_TIME 44 9377
TEST_TIME 45 9391
TEST_TIME 46 9244
TEST_TIME 47 9207
TEST_TIME 48 9444
TEST_TIME 49 9284
TEST_TIME 50 9175
TEST_TIME 51 9143
TEST_TIME 52 9170
TEST_TIME 53 9246
TEST_TIME 54 9341
TEST_TIME 55 9360
TEST_TIME 56 9426
TEST_TIME 57 9560
TEST_TIME 58 9531
TEST_TIME 59 9447
TEST_TIME 60 9495
TEST_TIME 61 9452
TEST_TIME 62 9617
TEST_TIME 63 9685
TEST_TIME 64 9748
TEST_TIME 65 9650
TEST_TIME 66 9512
TEST_TIME 67 9520
TEST_TIME 68 9499
TEST_TIME 69 9506
TEST_TIME 70 9461
TEST_TIME 71 9410
TEST_TIME 72 9348
TEST_TIME 73 9340
TEST_TIME 74 9269
TEST_TIME 75 9208
TEST_TIME 76 9222
TEST_TIME 77 9215
TEST_TIME 78 9289
TEST_TIME 79 9300
TEST_TIME 80 9349
TEST_TIME 81 9313
TEST_TIME 82 9380
TEST_TIME 83 9380
TEST_TIME 84 9348
TEST_TIME 85 9456
TEST_TIME 86 9441
TEST_TIME 87 9441
TEST_TIME 88 9428
TEST_TIME 89 9341
TEST_TIME 90 9355
TEST_TIME 91 9282
TEST_TIME 92 9310
TEST_TIME 93 9243
TEST_TIME 94 9230
TEST_TIME 95 9185
TEST_TIME 96 9216
TEST_TIME 97 9175
TEST_TIME 98 9229
TEST_TIME 99 9267
TEST_TIME 100 9307
TEST_TIME 101 9275
TEST_TIME 102 9330
TEST_TIME 103 9344
TEST_TIME 104 9375
TEST_TIME 105 9366
TEST_TIME 106 9481
TEST_TIME 107 9457
TEST_TIME 108 9453
TEST_TIME 109 9418
TEST_TIME 110 9400
TEST_TIME 111 9377
TEST_TIME 112 9365
TEST_TIME 113 9345
TEST_TIME 114 9320
TEST_TIME 115 9328
TEST_TIME 116 9309
TEST_TIME 117 9295
TEST_TIME 118 9263
TEST_TIME 119 9248
TEST_TIME 120 9221

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-01-2023 01:39:59 to 07-01-2023 01:40:03.

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 1537777 1537777 0 0.00 0 22784 6 0 0
doc_list_biud 1537776 1537776 0 0.00 0 29461 23 0 0
sp_add_invoice_to_stock 5179 5063 116 2.23 31 168683 17635 0 116
sp_cancel_adding_invoice 5723 5723 0 0.00 4 59552 797 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 21035 357 0 0
sp_cancel_customer_reserve 552680 552680 0 0.00 0 24993 49 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 6348 64 0 0
sp_cancel_pay_to_supplier 452 452 0 0.00 3 6516 108 0 0
sp_cancel_supplier_invoice 6310 6305 5 0.07 1 15220 645 0 5
sp_cancel_supplier_order 370 370 0 0.00 5 945137 91587 0 0
sp_cancel_write_off 10472 10472 0 0.00 1 9522 98 0 0
sp_client_order 20880 20880 0 0.00 3 22850 340 0 0
sp_customer_reserve 466260 466260 0 0.00 0 66552 176 0 0
sp_fill_shopping_cart 32090 32090 0 0.00 1 13580 39 0 0
sp_fill_shopping_cart_clo_res 466253 466253 0 0.00 0 14774 12 0 0
sp_get_clo_for_invoice 5056 5056 0 0.00 0 18770 286 0 0
sp_kill_cost_storno 17310 17310 0 0.00 0 9517 52 0 0
sp_kill_qstorno_ret_qs2qd 1122507 1122507 0 0.00 0 24916 26 0 0
sp_kill_qty_storno 592352 592352 0 0.00 0 29460 58 0 0
sp_lock_selected_doc 20845 20845 0 0.00 0 9656 6 0 0
sp_make_cost_storno 28199 28199 0 0.00 0 19270 58 0 0
sp_multiply_rows_for_pdistr 28199 28199 0 0.00 0 7856 28 0 0
sp_multiply_rows_for_qdistr 20880 20880 0 0.00 0 13703 131 0 0
sp_pay_from_customer 7201 7201 0 0.00 4 13861 114 0 0
sp_pay_to_supplier 5946 5946 0 0.00 4 15372 131 0 0
sp_payment_common 13147 13147 0 0.00 1 10497 26 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 21027 331 0 0
sp_qd_handle_on_invoice_upd_sts 10779 10779 0 0.00 0 29457 306 0 0
sp_qd_handle_on_reserve_upd_sts 9996 9996 0 0.00 0 11528 60 0 0
sp_reserve_write_off 9996 9996 0 0.00 6 19307 178 0 0
sp_supplier_invoice 5565 5565 0 0.00 5 86019 3531 0 0
sp_supplier_order 5670 5670 0 0.00 5 30955 1280 0 0
srv_aggregate_perf_data 96 96 0 0.00 3141 325753 21763 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1108 132418 41012 0 0
srv_find_qd_qs_mism 1057943 1057943 0 0.00 0 22784 5 0 0
srv_make_invnt_saldo 531 531 0 0.00 29 58156 1324 0 0
srv_make_money_saldo 734 734 0 0.00 1 752 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 141317 364350 237527 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5063 5063 0 0.00 0 16926 177 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 14924 178 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 14365 20 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 1249 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 6345 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 452 452 0 0.00 0 70 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 537 9 0 0
t$perf-norm:sp_cancel_supplier_order 370 370 0 0.00 0 16855 1569 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 762 7 0 0
t$perf-norm:sp_client_order 20880 20880 0 0.00 0 15297 23 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 3203 8 0 0
t$perf-norm:sp_pay_from_customer 7201 7201 0 0.00 0 15366 13 0 0
t$perf-norm:sp_pay_to_supplier 5946 5946 0 0.00 0 6052 13 0 0
t$perf-norm:sp_reserve_write_off 9996 9996 0 0.00 0 15082 15 0 0
t$perf-norm:sp_supplier_invoice 5565 5565 0 0.00 0 15704 22 0 0
t$perf-norm:sp_supplier_order 5670 5670 0 0.00 0 15507 20 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 189 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 2202 45 0 0
t$perf-norm:srv_make_invnt_saldo 531 531 0 0.00 0 4409 31 0 0
t$perf-norm:srv_make_money_saldo 734 734 0 0.00 0 1017 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10344 10112 232 2.24 0 6447 16 0 232
v_all_customers 38026 38026 0 0.00 0 32 1 0 0
v_all_suppliers 30880 30880 0 0.00 0 34 1 0 0
v_all_wares 41760 41760 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 2008 9 0 0
v_cancel_client_order 3500 3500 0 0.00 0 5908 5 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 560 3 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 1010 3 0 0
v_cancel_payment_to_supplier 904 904 0 0.00 0 564 4 0 0
v_cancel_supplier_invoice 848 838 10 1.17 0 1309 13 0 10
v_cancel_supplier_order 740 740 0 0.00 0 2861 8 0 0
v_cancel_write_off 1982 1982 0 0.00 0 2928 14 0 0
v_max_id_clo_ord 5670 5670 0 0.00 0 213 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 1719 10 0 0
v_max_id_ord_sup 5540 5540 0 0.00 0 6336 5 0 0
v_max_non_paid_invoice 1716 1716 0 0.00 0 312 2 0 0
v_max_non_paid_realizn 3827 3827 0 0.00 0 295 3 0 0
v_min_id_clo_ord 5670 5670 0 0.00 0 3490 7 0 0
v_min_id_clo_res 992 992 0 0.00 0 1700 8 0 0
v_min_id_ord_sup 5540 5540 0 0.00 0 4391 7 0 0
v_min_non_paid_invoice 5946 5946 0 0.00 0 7330 17 0 0
v_min_non_paid_realizn 7201 7201 0 0.00 0 9711 21 0 0
v_our_firm 10482 10482 0 0.00 0 26 1 0 0
v_reserve_write_off 19992 19992 0 0.00 0 5974 6 0 0
x_lock_depdocs_on_canc_invoice 11609 11609 0 0.00 0 13659 103 0 0
x_lock_depdocs_on_canc_sup_ord 370 370 0 0.00 0 12880 407 0 0
x_make_qstorno_1000_1200 5670 5670 0 0.00 1 30948 1205 0 0
x_make_qstorno_1200_2000 5565 5565 0 0.00 2 85489 3381 0 0
x_make_qstorno_2100_3300 466260 466260 0 0.00 0 66546 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-01-2023 01:40:04 to 07-01-2023 01:40:08.

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:38 2 8192000000 8799455648 8810889216 5852176 0.071 2 1 2 0 5951808 56848 42784
23:09:39 23179 8192000000 9911698912 10222063616 996724336 10.847 100 87 174 0 1025206912 6085136 22397440
23:11:06 7336 8192000000 9947386832 10269605888 1032472352 11.192 100 86 172 0 1062675024 8551600 21651072
23:12:22 16936 8192000000 9981164656 10310324224 1062977600 11.485 100 82 164 0 1093071808 9949488 20144720
23:13:52 13470 8192000000 10031316928 10376044544 1116893392 11.998 100 78 156 1 1145100224 10478816 17728016
23:15:10 7642 8192000000 10083871088 10438643712 1170864992 12.505 100 83 166 0 1202828496 12850304 19113200
23:16:21 3719 8192000000 10121420576 10490605568 1211448832 12.883 100 74 148 0 1240665968 12358256 16858880
23:17:27 2031 8192000000 10162142800 10536587264 1249909024 13.237 100 88 176 0 1283477120 13909376 19658720
23:18:32 4208 8192000000 10180064224 10562871296 1274870112 13.466 100 77 154 1 1304242000 12295216 17076672
23:19:38 2250 8192000000 10223050400 10606899200 1302532992 13.718 100 89 176 0 1335412064 12688528 20190544
23:20:41 18844 8192000000 10192649264 10605748224 1305114112 13.742 100 27 55 0 1317398688 5544880 6739696
23:22:02 10650 8192000000 10206191632 10621427712 1315873168 13.839 100 22 45 0 1326355952 5915072 4567712
23:23:14 8831 8192000000 10204433312 10624794624 1323498768 13.908 100 14 26 0 1332591088 5954432 3137888
23:24:24 49742 8192000000 10233751440 10641883136 1326991040 13.940 100 51 101 1 1344640576 5534016 12115520
23:26:14 5827 8192000000 10208551744 10632937472 1306752512 13.757 98 16 33 0 1316572080 5700368 4119200
23:27:24 8956 8192000000 10225180176 10644328448 1330732176 13.974 100 21 42 0 1341804480 6310976 4761328
23:28:34 7739 8192000000 10208065888 10622865408 1296487920 13.663 99 28 56 1 1318209712 15676592 6045200
23:30:16 7862 8192000000 10130778672 10535456768 1215698384 12.922 95 28 58 0 1228169216 6012304 6458528
23:31:26 6772 8192000000 10132968688 10530091008 1234771696 13.098 100 33 66 0 1247979760 5506736 7701328
23:32:34 5173 8192000000 10149099280 10551697408 1257739136 13.309 100 32 65 0 1271120400 6001264 7380000
23:33:40 38276 8192000000 10160670144 10569662464 1271865008 13.439 100 32 64 0 1285819856 6428032 7526816
23:35:20 7710 8192000000 10194147936 10595086336 1289106576 13.596 100 30 61 0 1301628816 5432240 7090000
23:36:28 8812 8192000000 10204180544 10616238080 1310113504 13.787 100 29 58 1 1322000976 5128624 6758848
23:37:39 22049 8192000000 10208913376 10625306624 1316870256 13.848 100 19 36 0 1326659840 5388608 4400976
23:39:06 57095 8192000000 10239162384 10656546816 1338694080 14.046 100 31 59 0 1351134336 5515552 6924704
23:41:07 10667 8192000000 10256421744 10678763520 1357337472 14.213 100 39 78 0 1375558256 9345056 8875728
23:42:24 8876 8192000000 10283131136 10704056320 1376842240 14.388 100 55 110 0 1400045248 10895504 12307504
23:43:42 8430 8192000000 10289036704 10707742720 1380657440 14.422 100 51 103 0 1403108096 10655696 11794960
23:44:53 5124 8192000000 10309458624 10727538688 1386495104 14.475 100 62 121 2 1413018272 13058944 13464224
23:46:02 32249 8192000000 10298275616 10720870400 1380644000 14.422 99 41 84 0 1403877984 13633600 9600384
23:47:41 1099 8192000000 10327179568 10743447552 1405853200 14.647 100 81 156 1 1441091104 16594672 18643232
23:48:49 2780 8192000000 10335828816 10748219392 1396941984 14.568 100 84 166 1 1440408000 23074768 20391248
23:50:02 3840 8192000000 10339776432 10758639616 1409016240 14.675 100 64 127 0 1444436400 20147824 15272336
23:51:16 6378 8192000000 10308268864 10725040128 1383196752 14.445 100 53 106 0 1416884368 19910624 13776992
23:52:28 1242 8192000000 10328779056 10743377920 1395750560 14.557 100 65 127 0 1435701936 23997808 15953568
23:53:34 3621 8192000000 10334162912 10745446400 1395980368 14.559 100 73 147 0 1439130032 24451120 18698544
23:54:47 6579 8192000000 10339844800 10756268032 1396045248 14.560 100 67 134 1 1447000912 33729504 17226160
23:56:03 10190 8192000000 10288775824 10711793664 1346299200 14.114 98 41 83 1 1387002848 30512528 10191120
23:57:19 3883 8192000000 10301355264 10705088512 1344488416 14.098 99 81 159 1 1394368096 30825312 19054368
23:58:26 12467 8192000000 10289479648 10714734592 1358148304 14.221 100 33 66 1 1401322224 34132784 9041136
23:59:44 2766 8192000000 10290468080 10703544320 1345117072 14.104 100 60 116 0 1395028800 34760352 15151376
00:00:55 16273 8192000000 10247705392 10671824896 1306726848 13.756 98 27 53 2 1347820864 34147248 6946768
00:02:14 15046 8192000000 10253902176 10686930944 1340133776 14.059 100 16 30 0 1366967152 22565712 4267664
00:03:38 24231 8192000000 10259858560 10690125824 1328669856 13.955 99 22 44 0 1358110848 22935888 6505104
00:05:06 16824 8192000000 10254788384 10680541184 1329365632 13.961 99 22 44 0 1352316704 17604656 5346416
00:06:24 46520 8192000000 10248961920 10673758208 1307516704 13.764 98 21 40 0 1334374976 21878576 4979696
00:08:11 29403 8192000000 10266877840 10685009920 1337271072 14.033 100 50 100 2 1368170624 19429552 11470000
00:09:51 54781 8192000000 10253361616 10688323584 1342084640 14.076 99 43 83 1 1365824848 15265424 8474784
00:11:51 31883 8192000000 10253631840 10685796352 1332854208 13.993 98 38 75 0 1351045472 9383392 8807872
00:13:52 24628 8192000000 10291098240 10716688384 1366586576 14.296 100 48 97 0 1389220624 11571408 11062640
00:15:25 17926 8192000000 10294064240 10729824256 1377023856 14.390 100 37 74 0 1399529744 13930784 8575104
00:16:48 76092 8192000000 10286890688 10747260928 1386869808 14.478 100 17 34 0 1411204848 19925920 4409120
00:19:10 38733 8192000000 10301418864 10752262144 1388289264 14.491 100 15 30 1 1409653536 17978000 3386272
00:20:55 43937 8192000000 10291773760 10749485056 1373134544 14.355 99 15 30 0 1398319040 20523792 4660704
00:22:41 37003 8192000000 10292619920 10740260864 1385798080 14.468 100 35 70 0 1401022976 7140848 8084048
00:24:22 24849 8192000000 10294821440 10745692160 1390473200 14.510 100 42 81 2 1412285632 10531248 11281184
00:25:50 52896 8192000000 10289345728 10760962048 1383893904 14.451 99 11 23 0 1402511808 15780800 2837104
00:28:44 41688 8192000000 10304879024 10764300288 1400017808 14.595 100 17 34 0 1415070688 10701296 4351584
00:30:33 71939 8192000000 10317844896 10791485440 1408825328 14.674 100 9 18 1 1434056432 22752256 2478848
00:32:48 46717 8192000000 10312274112 10768003072 1396838112 14.567 99 22 42 0 1412776496 11463856 4474528
00:34:36 63344 8192000000 10305112992 10777645056 1372130544 14.346 98 11 22 0 1399562336 24746368 2685424
00:36:40 44277 8192000000 10302147312 10740879360 1358894704 14.227 98 33 61 0 1374435248 7945728 7594816
00:38:26 18137 8192000000 10288422288 10727550976 1349935904 14.147 97 26 52 0 1364540208 9057568 5546736
00:39:51 132418 8192000000 10264661056 10724872192 1229234000 13.047 89 14 28 0 1256178768 23670096 3274672
00:43:04 103699 8192000000 10292174672 10731970560 1353548608 14.179 98 28 56 0 1371789072 11255888 6984576
00:46:01 57124 8192000000 10273925664 10721345536 1339821872 14.056 98 27 54 0 1364134336 18634496 5677968
00:48:00 63325 8192000000 10267055632 10720911360 1351069312 14.157 98 30 59 0 1367315056 9532864 6712880
00:50:14 35204 8192000000 10262470480 10711519232 1339110528 14.049 98 35 70 0 1361828256 13813552 8904176
00:51:57 63790 8192000000 10254081968 10707865600 1348528048 14.134 99 14 29 0 1360541072 8403792 3609232
00:54:16 72267 8192000000 10257332000 10707476480 1305939984 13.749 95 12 24 1 1319078144 10207968 2930192
00:56:44 45947 8192000000 10255127296 10699116544 1316323648 13.843 97 27 52 0 1334036944 11957888 5755408
00:58:38 65032 8192000000 10253130816 10697572352 1297013792 13.668 96 24 46 0 1317747232 15311024 5422416
01:00:44 72471 8192000000 10255816320 10694615040 1298472512 13.681 96 25 50 2 1315817568 12039072 5305984
01:02:57 71553 8192000000 10260638528 10720124928 1341837152 14.074 99 20 38 1 1365219328 19415200 3966976
01:05:13 15021 8192000000 10290253536 10720423936 1362725648 14.262 100 55 110 1 1385986624 10767936 12493040
01:06:28 62837 8192000000 10273550752 10712956928 1349089744 14.139 99 33 66 0 1366734000 10694784 6949472
01:08:49 55274 8192000000 10288823408 10738913280 1381148032 14.427 100 20 40 1 1396367568 11061248 4158288
01:10:49 83302 8192000000 10274003888 10735681536 1373933360 14.362 99 16 32 0 1386434384 8544688 3956336
01:13:13 60763 8192000000 10295431968 10748182528 1387675040 14.485 99 24 47 0 1404218304 11518784 5024480
01:15:22 58682 8192000000 10322633632 10777219072 1408633664 14.672 100 17 34 0 1426438880 13550576 4254640
01:17:28 91797 8192000000 10318510704 10778406912 1410506992 14.688 100 19 38 0 1435920528 20295680 5117856
01:20:03 59107 8192000000 10313351904 10767495168 1399158864 14.588 99 14 28 0 1412309168 9974240 3176064
01:22:04 66628 8192000000 10309725600 10768293888 1367807856 14.307 97 18 37 0 1382775632 11629856 3337920
01:24:11 46395 8192000000 10304977040 10781057024 1383405984 14.447 99 7 14 1 1413834144 28681472 1746688
01:25:58 28335 8192000000 10298895040 10737766400 1355032368 14.193 97 27 55 0 1368749616 7760928 5956320
01:27:31 90065 8192000000 10295897408 10750025728 1385712960 14.468 100 11 22 0 1399607568 11214048 2680560
01:30:02 34133 8192000000 10278480432 10734845952 1353197120 14.176 98 22 44 0 1370796448 13070544 4528784
01:31:53 41430 8192000000 10269036432 10721734656 1354609744 14.189 98 24 49 1 1366600144 6659040 5331360
01:33:46 53237 8192000000 10278527952 10719096832 1347549968 14.125 98 8 15 0 1354792624 5552976 1689680
01:35:42 43196 8192000000 10271401648 10730168320 1380985280 14.425 100 15 30 0 1390558512 6253104 3320128
01:37:36 60234 8192000000 9807785120 10188967936 905890320 9.957 65 10 20 0 913567840 5471728 2205792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-01-2023 01:40:10 to 07-01-2023 01:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-01-2023 01:40:26 to 07-01-2023 01:40:28.

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          240891
MON$OLDEST_ACTIVE               242120
MON$OLDEST_SNAPSHOT             242120
MON$NEXT_TRANSACTION            242120
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                       2017984
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.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Sat Jan  7 01:38:46 2023
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Sat Jan  7 01:38:46 2023
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
--- end of diff output ---

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

07.01.23 01:40:29. 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
2023-01-07 01:40:32 No severe PSQL-related problems occured
07.01.23 01:40:33. End of report.