Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.03.2023 16:43
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 15314292 kB
MemAvailable 30423680 kB
Buffers 0 kB
Cached 16382268 kB
SwapCached 3060 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-03-11 16:42:19
DTS_END                         2023-03-11 16:42:19.3710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57142
TRN_ID                          233971
ATT_ID                          707
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: 9294.19 25305 1115303
customer order: creation 167.10 353 20052
customer order: refuse 14.11 310 1693
order to supplier: creation 45.51 1255 5461
order to supplier: removal 3.51 102231 421
invoice (draft): creation 45.44 3363 5453
invoice (draft): removal 54.10 887 6492
invoice accept: apply 40.67 16967 4880
invoice accept: cancel 48.46 805 5815
customer reserve: creation 3812.83 164 457540
customer reserve: removal 4764.13 58 571696
realization accept: apply 82.42 185 9890
realization accept: cancel 88.98 110 10677
payment from customer: creation 58.38 107 7006
payment from customer: removal 5.73 84 688
payment to supplier: creation 48.08 114 5770
payment to supplier: removal 3.30 70 396
service: total inventory turnovers 4.28 1521 514
service: total monetary turnovers 6.28 54 753
service: aggregate perf. data 0.85 21177 102
service: refresh index statistics 0.03 356292 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-03-2023 16:43:28 to 11-03-2023 16:43:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3579
TEST_TIME 2 4417
TEST_TIME 3 3806
TEST_TIME 4 3366
TEST_TIME 5 4034
TEST_TIME 6 4453
TEST_TIME 7 4443
TEST_TIME 8 4246
TEST_TIME 9 4375
TEST_TIME 10 4485
TEST_TIME 11 4390
TEST_TIME 12 4532
TEST_TIME 13 4950
TEST_TIME 14 5189
TEST_TIME 15 5378
TEST_TIME 16 5640
TEST_TIME 17 5953
TEST_TIME 18 6634
TEST_TIME 19 6830
TEST_TIME 20 7180
TEST_TIME 21 7150
TEST_TIME 22 7070
TEST_TIME 23 7321
TEST_TIME 24 7252
TEST_TIME 25 7611
TEST_TIME 26 7855
TEST_TIME 27 7887
TEST_TIME 28 8039
TEST_TIME 29 8258
TEST_TIME 30 8600
TEST_TIME 31 9589
TEST_TIME 32 9612
TEST_TIME 33 9540
TEST_TIME 34 9564
TEST_TIME 35 9728
TEST_TIME 36 9668
TEST_TIME 37 9497
TEST_TIME 38 9458
TEST_TIME 39 9317
TEST_TIME 40 9387
TEST_TIME 41 9294
TEST_TIME 42 9381
TEST_TIME 43 9377
TEST_TIME 44 9312
TEST_TIME 45 9279
TEST_TIME 46 9332
TEST_TIME 47 9448
TEST_TIME 48 9491
TEST_TIME 49 9625
TEST_TIME 50 9523
TEST_TIME 51 9346
TEST_TIME 52 9317
TEST_TIME 53 9255
TEST_TIME 54 9265
TEST_TIME 55 9183
TEST_TIME 56 9189
TEST_TIME 57 9228
TEST_TIME 58 9208
TEST_TIME 59 9366
TEST_TIME 60 9327
TEST_TIME 61 9305
TEST_TIME 62 9314
TEST_TIME 63 9237
TEST_TIME 64 9239
TEST_TIME 65 9146
TEST_TIME 66 9146
TEST_TIME 67 9223
TEST_TIME 68 9199
TEST_TIME 69 9331
TEST_TIME 70 9325
TEST_TIME 71 9348
TEST_TIME 72 9327
TEST_TIME 73 9374
TEST_TIME 74 9388
TEST_TIME 75 9393
TEST_TIME 76 9443
TEST_TIME 77 9541
TEST_TIME 78 9562
TEST_TIME 79 9555
TEST_TIME 80 9591
TEST_TIME 81 9507
TEST_TIME 82 9510
TEST_TIME 83 9487
TEST_TIME 84 9445
TEST_TIME 85 9444
TEST_TIME 86 9405
TEST_TIME 87 9408
TEST_TIME 88 9363
TEST_TIME 89 9318
TEST_TIME 90 9258
TEST_TIME 91 9287
TEST_TIME 92 9268
TEST_TIME 93 9236
TEST_TIME 94 9236
TEST_TIME 95 9243
TEST_TIME 96 9327
TEST_TIME 97 9323
TEST_TIME 98 9343
TEST_TIME 99 9313
TEST_TIME 100 9344
TEST_TIME 101 9432
TEST_TIME 102 9419
TEST_TIME 103 9524
TEST_TIME 104 9507
TEST_TIME 105 9489
TEST_TIME 106 9577
TEST_TIME 107 9504
TEST_TIME 108 9486
TEST_TIME 109 9436
TEST_TIME 110 9423
TEST_TIME 111 9428
TEST_TIME 112 9398
TEST_TIME 113 9351
TEST_TIME 114 9311
TEST_TIME 115 9329
TEST_TIME 116 9295
TEST_TIME 117 9345
TEST_TIME 118 9329
TEST_TIME 119 9346
TEST_TIME 120 9297

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-03-2023 16:43:31 to 11-03-2023 16:43:35.

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 1537381 1537381 0 0.00 0 14353 6 0 0
doc_list_biud 1537378 1537378 0 0.00 0 24984 28 0 0
sp_add_invoice_to_stock 5009 4880 129 2.57 66 177302 16967 0 129
sp_cancel_adding_invoice 5815 5815 0 0.00 3 78454 806 0 0
sp_cancel_client_order 1693 1693 0 0.00 4 6604 310 0 0
sp_cancel_customer_reserve 571696 571696 0 0.00 1 24986 59 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 3 4403 84 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 3681 71 0 0
sp_cancel_supplier_invoice 6499 6492 7 0.10 1 14382 888 0 7
sp_cancel_supplier_order 421 421 0 0.00 5 1188385 102231 0 0
sp_cancel_write_off 10677 10677 0 0.00 1 13065 110 0 0
sp_client_order 20052 20052 0 0.00 3 20774 354 0 0
sp_customer_reserve 457540 457540 0 0.00 0 111878 165 0 0
sp_fill_shopping_cart 30946 30946 0 0.00 1 22115 37 0 0
sp_fill_shopping_cart_clo_res 457536 457536 0 0.00 0 22287 14 0 0
sp_get_clo_for_invoice 4875 4875 0 0.00 0 38810 343 0 0
sp_kill_cost_storno 17576 17576 0 0.00 0 10996 59 0 0
sp_kill_qstorno_ret_qs2qd 1160982 1160982 0 0.00 0 24942 32 0 0
sp_kill_qty_storno 611558 611558 0 0.00 0 24984 70 0 0
sp_lock_selected_doc 20511 20511 0 0.00 0 9519 6 0 0
sp_make_cost_storno 27541 27541 0 0.00 0 14350 55 0 0
sp_multiply_rows_for_pdistr 27541 27541 0 0.00 0 8141 27 0 0
sp_multiply_rows_for_qdistr 20051 20051 0 0.00 0 11218 149 0 0
sp_pay_from_customer 7006 7006 0 0.00 4 16462 108 0 0
sp_pay_to_supplier 5770 5770 0 0.00 5 11270 114 0 0
sp_payment_common 12776 12776 0 0.00 1 16421 33 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 6563 284 0 0
sp_qd_handle_on_invoice_upd_sts 10690 10690 0 0.00 0 23727 327 0 0
sp_qd_handle_on_reserve_upd_sts 9889 9889 0 0.00 0 23157 61 0 0
sp_reserve_write_off 9890 9890 0 0.00 6 23379 185 0 0
sp_supplier_invoice 5453 5453 0 0.00 5 101581 3363 0 0
sp_supplier_order 5461 5461 0 0.00 5 54065 1256 0 0
srv_aggregate_perf_data 102 102 0 0.00 2239 337182 21177 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2787 141919 45321 0 0
srv_find_qd_qs_mism 1067550 1067550 0 0.00 0 14214 5 0 0
srv_make_invnt_saldo 514 514 0 0.00 26 156750 1521 0 0
srv_make_money_saldo 753 753 0 0.00 0 11724 54 0 0
srv_recalc_idx_stat 4 4 0 0.00 287022 435423 356293 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4881 4881 0 0.00 0 16071 177 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 10286 98 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 3109 18 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 1389 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 733 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 645 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 2889 26 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 22351 1731 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 5624 16 0 0
t$perf-norm:sp_client_order 20052 20052 0 0.00 0 11244 20 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 1160 7 0 0
t$perf-norm:sp_pay_from_customer 7006 7006 0 0.00 0 5350 10 0 0
t$perf-norm:sp_pay_to_supplier 5770 5770 0 0.00 0 3346 10 0 0
t$perf-norm:sp_reserve_write_off 9890 9890 0 0.00 0 10388 11 0 0
t$perf-norm:sp_supplier_invoice 5453 5453 0 0.00 0 6472 18 0 0
t$perf-norm:sp_supplier_order 5461 5461 0 0.00 0 8181 19 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 239 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 89 3 0 0
t$perf-norm:srv_make_invnt_saldo 514 514 0 0.00 0 3518 39 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 1469 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2 2 0 0
v_add_invoice_to_stock 10008 9750 258 2.57 0 6172 17 0 258
v_all_customers 36388 36388 0 0.00 0 30 1 0 0
v_all_suppliers 30614 30614 0 0.00 0 19 1 0 0
v_all_wares 40102 40102 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 3927 10 0 0
v_cancel_client_order 3386 3386 0 0.00 0 4017 7 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 4366 8 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 3514 10 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 853 4 0 0
v_cancel_supplier_invoice 832 818 14 1.68 0 1970 17 0 14
v_cancel_supplier_order 842 842 0 0.00 0 2236 9 0 0
v_cancel_write_off 2002 2002 0 0.00 0 3172 15 0 0
v_max_id_clo_ord 5457 5457 0 0.00 0 1070 1 0 0
v_max_id_clo_res 986 986 0 0.00 0 2586 15 0 0
v_max_id_ord_sup 5437 5437 0 0.00 0 2824 4 0 0
v_max_non_paid_invoice 1357 1357 0 0.00 0 1383 4 0 0
v_max_non_paid_realizn 3779 3779 0 0.00 0 2955 5 0 0
v_min_id_clo_ord 5457 5457 0 0.00 0 3540 6 0 0
v_min_id_clo_res 986 986 0 0.00 0 1105 6 0 0
v_min_id_ord_sup 5437 5437 0 0.00 0 5167 6 0 0
v_min_non_paid_invoice 5770 5770 0 0.00 0 10166 20 0 0
v_min_non_paid_realizn 7006 7006 0 0.00 0 12515 27 0 0
v_our_firm 10168 10168 0 0.00 0 15 1 0 0
v_reserve_write_off 19778 19778 0 0.00 0 4074 7 0 0
x_lock_depdocs_on_canc_invoice 11898 11898 0 0.00 0 14013 108 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 6199 345 0 0
x_make_qstorno_1000_1200 5461 5461 0 0.00 1 54052 1182 0 0
x_make_qstorno_1200_2000 5453 5453 0 0.00 2 101431 3226 0 0
x_make_qstorno_2100_3300 457540 457540 0 0.00 0 111877 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-03-2023 16:43:36 to 11-03-2023 16:43:40.

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
14:12:23 8 8192000000 8798879696 8810233856 5307904 0.064 2 1 2 0 5407504 56816 42784
14:13:24 11146 8192000000 9896025872 10205241344 976970576 10.655 100 81 162 0 1003752384 5963936 20817872
14:14:45 16211 8192000000 9953582032 10276745216 1034159088 11.208 100 89 178 0 1065021712 8406080 22456544
14:16:12 16285 8192000000 9985923776 10317737984 1066134928 11.515 100 85 170 0 1097392960 10051696 21206336
14:17:43 9633 8192000000 10027952304 10374590464 1112306528 11.954 100 83 166 0 1143493280 10931376 20255376
14:19:07 5546 8192000000 10059898000 10416799744 1152756096 12.335 100 81 160 0 1183114176 12062864 18295216
14:20:15 5371 8192000000 10114803200 10480152576 1200474848 12.781 100 72 144 0 1228397120 11926032 15996240
14:21:22 3482 8192000000 10146134768 10515066880 1229419024 13.049 100 84 169 1 1260920384 12861568 18639792
14:22:27 5720 8192000000 10162365072 10538115072 1251768976 13.254 100 82 161 2 1282451984 12885680 17797328
14:23:37 1340 8192000000 10199044656 10579709952 1283534144 13.545 100 83 164 0 1313857072 12643744 17679184
14:24:42 51202 8192000000 10204833984 10603761664 1302320064 13.716 100 61 120 0 1325267600 8379856 14567680
14:26:36 7662 8192000000 10203178080 10613133312 1312704752 13.811 100 37 73 0 1328257312 7404112 8148448
14:27:46 8845 8192000000 10209719248 10627252224 1324343472 13.916 100 16 31 0 1334115920 6338832 3433616
14:28:56 7474 8192000000 10217193296 10640076800 1333613648 14.000 100 27 54 2 1346565856 6482752 6469456
14:30:04 29863 8192000000 10230572608 10659385344 1339160784 14.050 100 23 46 0 1353996240 9723088 5112368
14:31:34 5295 8192000000 10229852976 10650619904 1331774000 13.983 99 18 36 0 1341198032 5638176 3785856
14:32:40 5150 8192000000 10231611760 10650390528 1338415392 14.043 100 20 38 0 1348653360 5617648 4620320
14:33:49 11350 8192000000 10208458496 10627747840 1310245216 13.788 99 17 32 0 1320379968 5921504 4213248
14:35:01 28672 8192000000 10170960032 10577141760 1189875792 12.682 91 23 41 1 1199447472 5372192 4199488
14:36:31 5888 8192000000 10131520000 10527776768 1204141152 12.815 97 21 41 2 1214368912 5156960 5070800
14:37:39 10693 8192000000 10139965504 10539155456 1222701904 12.987 97 15 28 0 1231838560 5882848 3253808
14:38:51 32553 8192000000 10151373008 10557435904 1261245552 13.341 100 35 70 0 1275647056 6239040 8162464
14:40:27 9550 8192000000 10159001984 10570706944 1273186448 13.451 100 20 40 0 1283389120 5795616 4407056
14:41:38 8769 8192000000 10195996944 10602110976 1292632592 13.628 100 26 52 1 1304250768 5289440 6328736
14:42:52 16816 8192000000 10226120640 10635182080 1327412016 13.944 100 38 72 0 1340871744 5521104 7938624
14:44:18 21544 8192000000 10250014880 10659749888 1345191904 14.104 100 39 78 0 1361852032 8118576 8541552
14:45:43 74223 8192000000 10265589632 10684907520 1358599072 14.225 100 42 82 1 1378856224 11396544 8860608
14:48:02 61504 8192000000 10275969440 10704429056 1371226832 14.338 100 21 42 0 1390820736 14766928 4826976
14:50:16 15445 8192000000 10296236352 10723291136 1390834496 14.513 100 43 86 1 1414201280 12073568 11293216
14:51:37 10440 8192000000 10323582512 10744352768 1402460352 14.617 100 52 102 0 1434164464 19752688 11951424
14:52:55 12453 8192000000 10327771680 10756808704 1413622400 14.716 100 43 86 0 1443890464 19200448 11067616
14:54:12 4567 8192000000 10362984144 10783444992 1428313024 14.846 100 70 139 1 1471754768 26600880 16840864
14:55:23 5393 8192000000 10360190736 10782937088 1408993360 14.675 99 67 132 1 1462082592 36938000 16151232
14:56:35 3367 8192000000 10360033920 10783055872 1421997344 14.790 100 74 148 1 1471837152 31389376 18450432
14:57:41 7849 8192000000 10377272960 10801991680 1431481664 14.874 100 71 141 0 1485898848 36856320 17560864
14:59:12 3503 8192000000 10372187312 10794299392 1418910448 14.763 100 74 142 1 1473190064 36099744 18179872
15:00:22 5625 8192000000 10357954784 10778759168 1403054448 14.622 99 67 134 1 1463167552 42837952 17275152
15:01:39 2785 8192000000 10375072272 10792488960 1416042096 14.738 100 70 136 2 1472387408 38248560 18096752
15:02:49 24500 8192000000 10328342864 10758385664 1383672896 14.449 99 46 92 1 1433802864 37559808 12570160
15:04:17 4063 8192000000 10328399616 10747588608 1372633456 14.351 98 46 92 1 1425604944 40571840 12399648
15:05:34 6775 8192000000 10346619792 10772197376 1392514960 14.528 100 56 112 0 1458415008 51659920 14240128
15:06:46 21944 8192000000 10336034352 10761551872 1374976304 14.372 99 33 66 0 1436191552 51830480 9384768
15:08:26 33793 8192000000 10312231536 10742222848 1370542272 14.332 100 32 62 1 1427394192 47053520 9798400
15:10:13 12161 8192000000 10304497840 10736062464 1357836352 14.218 100 29 56 0 1414289632 48084320 8368960
15:11:29 22873 8192000000 10312135712 10754441216 1359336144 14.231 100 26 52 0 1425392016 58095472 7960400
15:12:58 20071 8192000000 10310904224 10747305984 1346676304 14.118 99 24 48 0 1402737936 49394912 6666720
15:14:22 49869 8192000000 10272464432 10710380544 1347908640 14.129 98 45 87 1 1375424448 16692320 10823488
15:17:05 94161 8192000000 10262872880 10705272832 1319198480 13.869 97 8 13 0 1341486640 20982112 1306048
15:20:34 56107 8192000000 10274394048 10707296256 1337169184 14.032 98 26 52 1 1353677488 10429872 6078432
15:22:39 61495 8192000000 10287085616 10726957056 1374214016 14.365 100 36 68 2 1394515888 12850288 7451584
15:24:54 116278 8192000000 10282114016 10730893312 1364002304 14.273 99 3 6 0 1378747488 14311120 434064
15:28:08 66460 8192000000 10287933280 10737999872 1363266112 14.267 99 12 25 0 1380256272 13636016 3354144
15:30:24 121361 8192000000 10298472448 10759598080 1349097648 14.139 97 3 6 0 1365804736 16025520 681568
15:34:24 31778 8192000000 10320779568 10765651968 1398191232 14.579 100 30 56 1 1414149152 9580720 6377200
15:35:58 23062 8192000000 10328996224 10760888320 1385231344 14.463 98 55 110 0 1416319328 18026176 13061808
15:37:38 46554 8192000000 10314189296 10769461248 1402731248 14.619 100 17 34 0 1424771520 18338560 3701712
15:39:26 28319 8192000000 10330721952 10779979776 1411961296 14.701 100 32 63 1 1433125056 14224832 6938928
15:40:57 29573 8192000000 10332491056 10791370752 1411352256 14.696 100 50 97 2 1438354704 15550400 11452048
15:42:27 46739 8192000000 10306893264 10771881984 1408646080 14.672 100 17 34 0 1422620016 10219984 3753952
15:44:38 34992 8192000000 10307626656 10767429632 1405495792 14.644 100 16 32 1 1417957168 8594624 3866752
15:46:15 75614 8192000000 10300414320 10767228928 1394243168 14.544 99 14 28 0 1409298912 12070688 2985056
15:48:32 59908 8192000000 10317026816 10791927808 1378532240 14.403 97 10 20 0 1396680928 15699424 2449264
15:51:16 62078 8192000000 10300735840 10773323776 1358978816 14.228 97 12 24 0 1375826656 14353584 2494256
15:53:18 85060 8192000000 10280491504 10734243840 1308503408 13.772 95 13 26 0 1332493440 21142976 2847056
15:56:05 50245 8192000000 10284190528 10727092224 1322293056 13.897 96 31 62 0 1338305264 8826064 7186144
15:57:57 23862 8192000000 10281674480 10715512832 1338680128 14.046 99 39 78 1 1362054272 14471568 8902576
15:59:22 40116 8192000000 10257145600 10714017792 1337456688 14.034 99 23 47 0 1362017120 19379232 5181200
16:01:03 62243 8192000000 10255018896 10698539008 1271749056 13.438 94 8 16 0 1285254896 12138640 1367200
16:03:06 76177 8192000000 10269488096 10711310336 1339525872 14.053 100 20 40 1 1362186848 18006032 4654944
16:05:57 40325 8192000000 10281609904 10748010496 1361424064 14.250 100 8 16 0 1393763312 30530096 1809152
16:07:45 72868 8192000000 10250615680 10714517504 1317474112 13.854 97 10 20 0 1332139536 12283104 2382320
16:10:02 49077 8192000000 10266443200 10718175232 1360114256 14.238 100 25 50 0 1373032880 6405328 6513296
16:11:52 85997 8192000000 10283154256 10750554112 1376881904 14.389 100 20 40 0 1397937728 16613584 4442240
16:15:01 66863 8192000000 10281906080 10742272000 1358057712 14.220 98 14 28 0 1369118384 7716032 3344640
16:17:08 50471 8192000000 10276589184 10728873984 1342422016 14.079 97 24 49 0 1361080640 13065056 5593568
16:18:59 48415 8192000000 10296361392 10745151488 1370770080 14.334 99 18 36 0 1385789184 11295808 3723296
16:20:48 23964 8192000000 10323664144 10774040576 1404289808 14.633 100 38 74 0 1425083712 12471840 8322064
16:22:13 33768 8192000000 10320174672 10778075136 1390600784 14.511 99 35 69 0 1415578320 17152944 7824592
16:23:47 108759 8192000000 10317039664 10784219136 1396248656 14.562 99 17 34 0 1420161664 20587072 3325936
16:26:37 11733 8192000000 10312805488 10762485760 1391085648 14.516 99 34 68 1 1405408976 6321792 8001536
16:27:53 63916 8192000000 10300964720 10765398016 1349487744 14.143 96 17 34 0 1370576512 17208368 3880400
16:30:05 107014 8192000000 10306127936 10773942272 1385997008 14.470 99 19 38 0 1407277424 16734592 4545824
16:33:04 73653 8192000000 10271319200 10739941376 1347995600 14.129 97 13 26 1 1360285200 9364960 2924640
16:35:18 50908 8192000000 10286141360 10750095360 1385435568 14.465 100 12 26 0 1400570672 12256304 2878800
16:37:09 141919 8192000000 10288291232 10747084800 1332537232 13.990 96 9 18 0 1351149104 16499280 2112592
16:41:02 46411 8192000000 10243765600 10700607488 1287043216 13.577 95 21 42 0 1300960256 9151680 4765360

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-03-2023 16:43:42 to 11-03-2023 16:43:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-03-2023 16:43:58 to 11-03-2023 16:44:00.

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          233361
MON$OLDEST_ACTIVE               234351
MON$OLDEST_SNAPSHOT             234351
MON$NEXT_TRANSACTION            234351
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                       2025248
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.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Mar 11 16:42:21 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Mar 11 16:42:22 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Mar 11 16:42:24 2023
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sat Mar 11 16:42:25 2023
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Sat Mar 11 16:42:43 2023
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
--- end of diff output ---

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

11.03.23 16:44:01. 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-03-11 16:44:04 No severe PSQL-related problems occured
11.03.23 16:44:05. End of report.