Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.06.2022 01:40
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16139200 kB
MemAvailable 31227492 kB
Buffers 0 kB
Cached 15602876 kB
SwapCached 2496 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-29 01:38:18
DTS_END                         2022-06-29 01:38:18.3250
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/47430
TRN_ID                          250880
ATT_ID                          663
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: 9482.33 18881 1137880
customer order: creation 185.59 348 22271
customer order: refuse 15.59 333 1871
order to supplier: creation 50.93 1200 6111
order to supplier: removal 3.48 83736 418
invoice (draft): creation 49.22 3064 5906
invoice (draft): removal 53.21 726 6385
invoice accept: apply 44.68 16991 5361
invoice accept: cancel 47.53 796 5704
customer reserve: creation 4056.86 172 486823
customer reserve: removal 4659.63 48 559155
realization accept: apply 91.40 163 10968
realization accept: cancel 83.39 98 10007
payment from customer: creation 64.54 96 7745
payment from customer: removal 6.24 80 749
payment to supplier: creation 53.30 124 6396
payment to supplier: removal 3.73 79 447
service: total inventory turnovers 5.03 994 604
service: total monetary turnovers 7.15 26 858
service: aggregate perf. data 0.81 21089 97
service: refresh index statistics 0.03 247456 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-06-2022 01:40:43 to 29-06-2022 01:40:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3776
TEST_TIME 2 3974
TEST_TIME 3 4473
TEST_TIME 4 5266
TEST_TIME 5 5371
TEST_TIME 6 5624
TEST_TIME 7 5495
TEST_TIME 8 5973
TEST_TIME 9 5899
TEST_TIME 10 6262
TEST_TIME 11 6458
TEST_TIME 12 6681
TEST_TIME 13 7072
TEST_TIME 14 7247
TEST_TIME 15 7708
TEST_TIME 16 7854
TEST_TIME 17 8340
TEST_TIME 18 8574
TEST_TIME 19 8677
TEST_TIME 20 9310
TEST_TIME 21 9279
TEST_TIME 22 9276
TEST_TIME 23 9127
TEST_TIME 24 9355
TEST_TIME 25 9318
TEST_TIME 26 9438
TEST_TIME 27 9709
TEST_TIME 28 10049
TEST_TIME 29 10463
TEST_TIME 30 10282
TEST_TIME 31 10279
TEST_TIME 32 10229
TEST_TIME 33 10301
TEST_TIME 34 10212
TEST_TIME 35 10110
TEST_TIME 36 10308
TEST_TIME 37 10215
TEST_TIME 38 10150
TEST_TIME 39 10186
TEST_TIME 40 10050
TEST_TIME 41 10013
TEST_TIME 42 9860
TEST_TIME 43 9798
TEST_TIME 44 9808
TEST_TIME 45 9774
TEST_TIME 46 9718
TEST_TIME 47 9664
TEST_TIME 48 9741
TEST_TIME 49 9711
TEST_TIME 50 9599
TEST_TIME 51 9539
TEST_TIME 52 9700
TEST_TIME 53 9680
TEST_TIME 54 9606
TEST_TIME 55 9528
TEST_TIME 56 9485
TEST_TIME 57 9454
TEST_TIME 58 9403
TEST_TIME 59 9401
TEST_TIME 60 9389
TEST_TIME 61 9400
TEST_TIME 62 9407
TEST_TIME 63 9415
TEST_TIME 64 9509
TEST_TIME 65 9638
TEST_TIME 66 9690
TEST_TIME 67 9683
TEST_TIME 68 9670
TEST_TIME 69 9713
TEST_TIME 70 9707
TEST_TIME 71 9669
TEST_TIME 72 9749
TEST_TIME 73 9765
TEST_TIME 74 9713
TEST_TIME 75 9656
TEST_TIME 76 9614
TEST_TIME 77 9564
TEST_TIME 78 9580
TEST_TIME 79 9568
TEST_TIME 80 9503
TEST_TIME 81 9472
TEST_TIME 82 9422
TEST_TIME 83 9419
TEST_TIME 84 9410
TEST_TIME 85 9411
TEST_TIME 86 9421
TEST_TIME 87 9468
TEST_TIME 88 9454
TEST_TIME 89 9480
TEST_TIME 90 9554
TEST_TIME 91 9570
TEST_TIME 92 9603
TEST_TIME 93 9645
TEST_TIME 94 9673
TEST_TIME 95 9699
TEST_TIME 96 9756
TEST_TIME 97 9691
TEST_TIME 98 9631
TEST_TIME 99 9603
TEST_TIME 100 9615
TEST_TIME 101 9648
TEST_TIME 102 9598
TEST_TIME 103 9549
TEST_TIME 104 9524
TEST_TIME 105 9466
TEST_TIME 106 9429
TEST_TIME 107 9461
TEST_TIME 108 9444
TEST_TIME 109 9410
TEST_TIME 110 9454
TEST_TIME 111 9530
TEST_TIME 112 9546
TEST_TIME 113 9566
TEST_TIME 114 9536
TEST_TIME 115 9545
TEST_TIME 116 9572
TEST_TIME 117 9597
TEST_TIME 118 9580
TEST_TIME 119 9565
TEST_TIME 120 9490

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-06-2022 01:40:46 to 29-06-2022 01:40:50.

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 1588356 1588356 0 0.00 0 15733 6 0 0
doc_list_biud 1588356 1588356 0 0.00 0 91015 23 0 0
sp_add_invoice_to_stock 5552 5361 191 3.44 40 169006 16991 0 191
sp_cancel_adding_invoice 5704 5704 0 0.00 4 70513 797 0 0
sp_cancel_client_order 1871 1871 0 0.00 4 16800 334 0 0
sp_cancel_customer_reserve 559155 559155 0 0.00 0 66140 49 0 0
sp_cancel_pay_from_customer 749 749 0 0.00 3 6898 80 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 2 3231 80 0 0
sp_cancel_supplier_invoice 6395 6385 10 0.15 1 91034 726 0 10
sp_cancel_supplier_order 418 418 0 0.00 8 801007 83736 0 0
sp_cancel_write_off 10007 10007 0 0.00 1 15753 98 0 0
sp_client_order 22271 22271 0 0.00 3 21307 349 0 0
sp_customer_reserve 486823 486823 0 0.00 0 52621 172 0 0
sp_fill_shopping_cart 34283 34283 0 0.00 1 12753 39 0 0
sp_fill_shopping_cart_clo_res 486812 486812 0 0.00 0 16773 14 0 0
sp_get_clo_for_invoice 5349 5349 0 0.00 0 20411 256 0 0
sp_kill_cost_storno 16907 16907 0 0.00 0 15732 49 0 0
sp_kill_qstorno_ret_qs2qd 1135120 1135120 0 0.00 0 91007 27 0 0
sp_kill_qty_storno 599857 599857 0 0.00 0 91011 59 0 0
sp_lock_selected_doc 22527 22527 0 0.00 0 10586 6 0 0
sp_make_cost_storno 30458 30458 0 0.00 0 12578 48 0 0
sp_multiply_rows_for_pdistr 30458 30458 0 0.00 0 7132 25 0 0
sp_multiply_rows_for_qdistr 22271 22271 0 0.00 0 18732 168 0 0
sp_pay_from_customer 7745 7745 0 0.00 4 13675 97 0 0
sp_pay_to_supplier 6396 6396 0 0.00 4 12629 125 0 0
sp_payment_common 14141 14141 0 0.00 1 7402 23 0 0
sp_qd_handle_on_cancel_clo 1871 1871 0 0.00 0 16795 310 0 0
sp_qd_handle_on_invoice_upd_sts 11053 11053 0 0.00 0 21739 304 0 0
sp_qd_handle_on_reserve_upd_sts 10968 10968 0 0.00 0 20192 56 0 0
sp_reserve_write_off 10968 10968 0 0.00 5 20207 163 0 0
sp_supplier_invoice 5906 5906 0 0.00 5 94039 3064 0 0
sp_supplier_order 6111 6111 0 0.00 4 89446 1200 0 0
srv_aggregate_perf_data 97 97 0 0.00 2516 324586 21089 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1926 131104 42918 0 0
srv_find_qd_qs_mism 1086442 1086442 0 0.00 0 15073 5 0 0
srv_make_invnt_saldo 604 604 0 0.00 33 39328 994 0 0
srv_make_money_saldo 858 858 0 0.00 1 878 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 200829 306651 247456 0 0
t$perf-abend:sp_add_invoice_to_stock 191 191 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5361 5361 0 0.00 0 7679 143 0 0
t$perf-norm:sp_cancel_adding_invoice 468 468 0 0.00 0 2372 70 0 0
t$perf-norm:sp_cancel_client_order 1871 1871 0 0.00 0 5493 19 0 0
t$perf-norm:sp_cancel_customer_reserve 752 752 0 0.00 0 2038 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 749 749 0 0.00 0 2744 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 5089 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 2104 23 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 26700 1451 0 0
t$perf-norm:sp_cancel_write_off 1076 1076 0 0.00 0 5499 15 0 0
t$perf-norm:sp_client_order 22271 22271 0 0.00 0 8628 16 0 0
t$perf-norm:sp_customer_reserve 1106 1106 0 0.00 0 5493 16 0 0
t$perf-norm:sp_pay_from_customer 7745 7745 0 0.00 0 5208 10 0 0
t$perf-norm:sp_pay_to_supplier 6396 6396 0 0.00 0 4999 11 0 0
t$perf-norm:sp_reserve_write_off 10968 10968 0 0.00 0 8854 14 0 0
t$perf-norm:sp_supplier_invoice 5906 5906 0 0.00 0 5431 23 0 0
t$perf-norm:sp_supplier_order 6111 6111 0 0.00 0 4702 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 3772 54 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 21 1 0 0
t$perf-norm:srv_make_invnt_saldo 604 604 0 0.00 0 1041 24 0 0
t$perf-norm:srv_make_money_saldo 858 858 0 0.00 0 1141 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 11080 10698 382 3.44 0 4450 18 0 382
v_all_customers 40444 40444 0 0.00 0 20 0 0 0
v_all_suppliers 33270 33270 0 0.00 0 19 1 0 0
v_all_wares 44538 44538 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 936 936 0 0.00 0 2102 9 0 0
v_cancel_client_order 3742 3742 0 0.00 0 1534 4 0 0
v_cancel_customer_prepayment 1498 1498 0 0.00 0 3551 10 0 0
v_cancel_customer_reserve 1504 1504 0 0.00 0 829 3 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 1781 6 0 0
v_cancel_supplier_invoice 880 860 20 2.27 0 3667 18 0 20
v_cancel_supplier_order 836 836 0 0.00 0 4396 15 0 0
v_cancel_write_off 2152 2152 0 0.00 0 3446 16 0 0
v_max_id_clo_ord 6110 6110 0 0.00 0 3767 2 0 0
v_max_id_clo_res 1106 1106 0 0.00 0 2222 14 0 0
v_max_id_ord_sup 5904 5904 0 0.00 0 5609 4 0 0
v_max_non_paid_invoice 1775 1775 0 0.00 0 268 2 0 0
v_max_non_paid_realizn 4155 4155 0 0.00 0 5845 5 0 0
v_min_id_clo_ord 6110 6110 0 0.00 0 4458 8 0 0
v_min_id_clo_res 1106 1106 0 0.00 0 7582 19 0 0
v_min_id_ord_sup 5904 5904 0 0.00 0 7165 9 0 0
v_min_non_paid_invoice 6396 6396 0 0.00 0 6474 14 0 0
v_min_non_paid_realizn 7745 7745 0 0.00 0 7396 18 0 0
v_our_firm 11274 11274 0 0.00 0 26 1 0 0
v_reserve_write_off 21934 21934 0 0.00 0 5231 6 0 0
x_lock_depdocs_on_canc_invoice 11659 11659 0 0.00 0 18218 102 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 22641 327 0 0
x_make_qstorno_1000_1200 6111 6111 0 0.00 1 89431 1124 0 0
x_make_qstorno_1200_2000 5906 5906 0 0.00 2 94017 2914 0 0
x_make_qstorno_2100_3300 486823 486823 0 0.00 0 45135 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-06-2022 01:40:51 to 29-06-2022 01:40:55.

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:22 12 8192000000 8806532368 8820334592 11745056 0.143 3 3 4 0 11928976 76800 107120
23:09:23 11589 8192000000 9889554528 10195173376 976677696 10.652 100 88 176 0 1005801840 5938496 23185648
23:10:41 20635 8192000000 9934058384 10250711040 1013435536 11.009 100 92 184 0 1044976512 8038800 23502176
23:12:06 16984 8192000000 9974364144 10303569920 1052637728 11.386 100 82 164 1 1081978384 8808048 20532608
23:13:29 10185 8192000000 10021139536 10361540608 1105462816 11.889 100 84 168 0 1135879472 10889824 19526832
23:14:43 5908 8192000000 10069791184 10425487360 1158299680 12.387 100 73 146 0 1186803504 11914272 16589552
23:15:56 4348 8192000000 10106534720 10472382464 1198205824 12.760 100 69 139 1 1225497616 12323488 14968304
23:17:02 2485 8192000000 10140367104 10513166336 1231941872 13.072 100 72 144 0 1262137472 14224464 15971136
23:18:07 2461 8192000000 10184462384 10563375104 1269904016 13.421 100 88 173 1 1302593056 13799872 18889168
23:19:11 1802 8192000000 10202968320 10587848704 1294449872 13.645 100 88 175 1 1327511744 13144896 19916976
23:20:15 20610 8192000000 10207114816 10602373120 1301019760 13.705 100 66 130 0 1326508976 11509088 13980128
23:21:37 12804 8192000000 10209094912 10618359808 1317520096 13.854 100 58 116 0 1341730512 11956208 12254208
23:22:52 6196 8192000000 10211871296 10626367488 1326498544 13.936 100 36 72 0 1341103680 6767408 7837728
23:24:05 4530 8192000000 10215939536 10634608640 1334221696 14.005 100 28 53 0 1346240704 6300704 5718304
23:25:10 17769 8192000000 10225248832 10644611072 1336229648 14.023 100 24 46 1 1348242768 6252768 5760352
23:26:28 15501 8192000000 10246116176 10656432128 1338424192 14.043 100 51 99 2 1355943136 6524640 10994304
23:27:44 6216 8192000000 10220671296 10645446656 1327086528 13.941 99 30 61 1 1340174864 5655936 7432400
23:28:51 6968 8192000000 10199683472 10616537088 1291895600 13.621 98 17 34 0 1300846240 5064992 3885648
23:30:00 19372 8192000000 10161008432 10565373952 1148060896 12.291 88 26 51 2 1159229824 5059072 6109856
23:31:23 20787 8192000000 10132689600 10531557376 1169574544 12.493 92 21 41 0 1179405296 5326288 4504464
23:32:45 5798 8192000000 10115174592 10517123072 1187933968 12.664 95 25 48 0 1199044464 6067280 5043216
23:33:51 4642 8192000000 10153692784 10558021632 1259742624 13.328 99 32 64 0 1273869584 6041056 8085904
23:34:58 12517 8192000000 10169239472 10581241856 1281099088 13.523 100 16 32 0 1290749280 6120096 3530096
23:36:11 31077 8192000000 10208200144 10606899200 1297806576 13.675 100 55 112 2 1316834464 5382688 13645200
23:37:43 9537 8192000000 10202509616 10616696832 1309288368 13.780 100 28 56 0 1321757152 6350544 6118240
23:39:05 56010 8192000000 10245071696 10658828288 1338202720 14.041 100 49 97 1 1356649936 7804656 10642560
23:41:17 12961 8192000000 10264266928 10683785216 1357560928 14.215 100 44 90 2 1378536432 10866432 10109072
23:42:34 12728 8192000000 10268527936 10695348224 1364501408 14.278 100 26 52 0 1382657712 12103392 6052912
23:43:52 20139 8192000000 10285744928 10715590656 1380554976 14.422 100 40 76 2 1403048896 13144256 9349664
23:45:17 10506 8192000000 10300467584 10719956992 1379507888 14.412 100 55 108 0 1410352608 18856048 11988672
23:46:43 5123 8192000000 10312074448 10734620672 1394594624 14.547 100 64 128 0 1426825152 17211088 15019440
23:47:51 20200 8192000000 10311488080 10742067200 1395842800 14.558 100 52 103 0 1429647728 21499792 12305136
23:49:20 4358 8192000000 10330858192 10758127616 1408595328 14.671 100 57 114 2 1444268656 21537200 14136128
23:50:30 2426 8192000000 10342281200 10754424832 1394089872 14.542 99 84 167 0 1439310384 25336336 19884176
23:51:36 3620 8192000000 10334939744 10754506752 1405009008 14.640 100 65 127 0 1442661216 21753744 15898464
23:52:43 2040 8192000000 10354982080 10774712320 1412374000 14.705 100 80 159 1 1461439968 29051392 20014576
23:53:51 2971 8192000000 10320055808 10730254336 1364051760 14.274 98 78 156 1 1407510496 24079872 19378864
23:55:00 4521 8192000000 10314667904 10739138560 1383743648 14.450 100 45 87 1 1418063808 23089296 11230864
23:56:14 12477 8192000000 10277196160 10706046976 1359252016 14.231 100 38 76 0 1392799328 24287680 9259632
23:57:48 7530 8192000000 10294623696 10709880832 1349482912 14.143 100 56 112 0 1395949872 31886944 14580016
23:58:58 7811 8192000000 10263491968 10685018112 1340461392 14.062 99 32 64 0 1365062016 17361680 7238944
00:00:09 6987 8192000000 10290829824 10711879680 1365008480 14.282 100 38 73 1 1396881424 20989040 10883904
00:01:19 14122 8192000000 10304327984 10723196928 1361248896 14.249 99 41 78 0 1395383824 24211168 9923760
00:02:41 20443 8192000000 10304316352 10733498368 1378329360 14.402 100 29 58 0 1417709808 31095632 8284816
00:04:06 22894 8192000000 10299543248 10731880448 1361125808 14.247 99 29 58 2 1400419920 31348448 7945664
00:06:20 13583 8192000000 10287222176 10719969280 1359557024 14.233 100 26 52 0 1393714512 27243552 6913936
00:07:34 74679 8192000000 10291071056 10715238400 1331994576 13.985 97 27 48 0 1344454544 7395584 5064384
00:10:00 67279 8192000000 10295098928 10733797376 1366039952 14.292 99 41 82 0 1384984624 9548592 9396080
00:12:14 53219 8192000000 10285887968 10727518208 1377813696 14.397 100 21 42 0 1393724528 11557504 4353328
00:14:08 49279 8192000000 10281684704 10737733632 1360118096 14.238 99 8 16 0 1377343568 15462896 1762576
00:16:16 37189 8192000000 10286146112 10729623552 1357559408 14.215 98 22 45 1 1372553840 10468080 4526352
00:17:53 26184 8192000000 10311768192 10750824448 1393529952 14.537 100 23 46 0 1409721200 11235312 4955936
00:19:23 120788 8192000000 10316262704 10764464128 1397317424 14.571 100 29 58 0 1416251936 12562816 6371696
00:22:29 48088 8192000000 10299515168 10763472896 1376168608 14.382 98 11 22 0 1394874560 15924224 2781728
00:24:20 67192 8192000000 10315901504 10779795456 1407729472 14.664 100 30 60 1 1426835840 11424976 7681392
00:26:33 53056 8192000000 10308024992 10762985472 1383618848 14.449 99 17 34 0 1410336912 21972288 4745776
00:28:50 86224 8192000000 10303652112 10762575872 1384349312 14.455 99 21 42 0 1406892256 17452544 5090400
00:31:39 46242 8192000000 10281909664 10733940736 1346896800 14.120 97 37 74 0 1363004016 7149136 8958080
00:33:26 65503 8192000000 10284338368 10749517824 1373184608 14.356 99 10 20 0 1388536944 12712384 2639952
00:35:42 39921 8192000000 10286482064 10744664064 1380239904 14.419 100 20 40 1 1396405920 11453792 4712224
00:37:23 45717 8192000000 10282174096 10760839168 1383724096 14.450 100 7 14 0 1398210192 13152704 1333392
00:39:09 48754 8192000000 10263236096 10723893248 1300284800 13.698 94 24 48 0 1317686896 12416864 4985232
00:41:36 63184 8192000000 10299371200 10757484544 1363049632 14.265 98 13 26 0 1389633616 23238880 3345104
00:43:44 25556 8192000000 10273630528 10732613632 1349720704 14.145 98 19 38 0 1367566000 13351152 4494144
00:45:22 73302 8192000000 10254338000 10707202048 1266326608 13.388 92 18 36 0 1282989184 12455376 4207200
00:47:42 75379 8192000000 10275233760 10700025856 1294852016 13.648 96 26 52 0 1314945136 14408896 5684224
00:50:00 34205 8192000000 10273019056 10710765568 1334354880 14.006 99 34 68 0 1358417456 16678976 7383600
00:51:40 82638 8192000000 10262473360 10708127744 1338934064 14.048 99 27 54 1 1359491232 14445088 6112080
00:54:16 35058 8192000000 10260053536 10688638976 1302967184 13.722 96 44 87 1 1320701456 8093056 9641216
00:55:54 1926 8192000000 10256716160 10698174464 1340167840 14.059 100 48 97 0 1363241696 11947184 11126672
00:56:58 63855 8192000000 10239458592 10703630336 1330956208 13.976 99 11 22 0 1343432560 9800816 2675536
00:59:03 52650 8192000000 10235963856 10686779392 1321708288 13.892 98 11 23 0 1332327488 8011008 2608192
01:01:00 84113 8192000000 10256653856 10719637504 1325915680 13.930 98 22 44 0 1347205520 16091824 5198016
01:03:24 21602 8192000000 10266426000 10719277056 1364119120 14.274 100 24 48 0 1377549952 8423248 5007584
01:04:46 36502 8192000000 10281751472 10730573824 1369962496 14.327 100 36 73 0 1391400704 13706592 7731616
01:06:23 44341 8192000000 10277029760 10723434496 1336378064 14.025 98 21 42 0 1354384960 13002320 5004576
01:08:08 62601 8192000000 10283444224 10750038016 1375405312 14.375 100 13 26 0 1396721184 18129728 3186144
01:10:16 82732 8192000000 10284710736 10744938496 1358593696 14.225 98 8 16 0 1374381632 14200976 1586960
01:12:39 50656 8192000000 10300079984 10742902784 1341325264 14.069 97 18 34 0 1353006512 8229728 3451520
01:14:32 68115 8192000000 10305694192 10747133952 1349305744 14.141 97 19 38 0 1363792992 10612944 3874304
01:16:42 131102 8192000000 10293142272 10766487552 1385634480 14.467 99 20 40 0 1400314928 9670640 5009808
01:19:56 65112 8192000000 10273955552 10747969536 1372949488 14.353 99 7 14 0 1381789088 7220640 1618960
01:22:02 73229 8192000000 10290740816 10754957312 1377532464 14.394 99 10 20 1 1391858080 12171440 2154176
01:24:16 54486 8192000000 10284979440 10749726720 1374319104 14.366 99 24 48 1 1389979392 10213808 5446480
01:26:23 47787 8192000000 10284531120 10738667520 1331275696 13.979 96 18 36 0 1351793328 16135696 4381936
01:28:39 70693 8192000000 10249234912 10698059776 1287999888 13.586 94 16 32 0 1305244368 13329952 3914528
01:30:50 44805 8192000000 10243312272 10702753792 1283349840 13.544 95 25 49 1 1298879296 10177184 5352272
01:32:36 62891 8192000000 10270619680 10716930048 1346803712 14.119 99 34 68 0 1367709328 13130688 7774928
01:34:40 50911 8192000000 10266929744 10708881408 1325354096 13.925 98 34 68 0 1346630832 14389728 6887008
01:36:33 14504 8192000000 10255180960 10685661184 1328168272 13.951 98 40 79 0 1350764336 14290032 8306032
01:37:48 65831 8192000000 9372219408 9682083840 519624464 5.964 39 7 14 0 531847024 11013680 1208880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-06-2022 01:40:56 to 29-06-2022 01:41:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 191 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-06-2022 01:41:12 to 29-06-2022 01:41:14.

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          250925
MON$OLDEST_ACTIVE               251255
MON$OLDEST_SNAPSHOT             251255
MON$NEXT_TRANSACTION            251255
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                       2039904
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33605 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33605 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33605 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33605 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Wed Jun 29 01:38:57 2022
: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'

29.06.22 01:41:15. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-06-29 01:41:18 No severe PSQL-related problems occured
29.06.22 01:41:19. End of report.